{"level":"warn","ts":"2026-04-09T00:48:28.640922Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T00:48:28.641006Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109: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.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-09T00:48:28.641113Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T00:48:28.641127Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-09T00:48:28.64115Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-09T00:48:28.641738Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-04-09T00:48:28.641823Z","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.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-09T00:48:28.647545Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.310957ms"} {"level":"info","ts":"2026-04-09T00:48:28.654677Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-04-09T00:48:28.654787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T00:48:28.654817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-04-09T00:48:28.65483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T00:48:28.654845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-04-09T00:48:28.654949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-04-09T00:48:28.669993Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T00:48:28.672349Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T00:48:28.673859Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-09T00:48:28.676038Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T00:48:28.676465Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T00:48:28.676645Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-09T00:48:28.676689Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-09T00:48:28.6767Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-09T00:48:28.679682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-04-09T00:48:28.680414Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-09T00:48:28.681269Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-09T00:48:28.681435Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T00:48:28.681458Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T00:48:28.681519Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-09T00:48:28.681533Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-09T00:48:29.056045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T00:48:29.056099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T00:48:29.056143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-04-09T00:48:29.056162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-04-09T00:48:29.056173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-09T00:48:29.056189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-04-09T00:48:29.056202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-09T00:48:29.057102Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T00:48:29.057145Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T00:48:29.057321Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T00:48:29.057557Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T00:48:29.057578Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T00:48:29.059675Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T00:48:29.05993Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T00:48:29.060875Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T00:48:29.060976Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T00:48:29.061019Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T00:48:29.062936Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-04-09T00:48:33.717006Z","caller":"traceutil/trace.go:171","msg":"trace[552807740] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"183.531538ms","start":"2026-04-09T00:48:33.53345Z","end":"2026-04-09T00:48:33.716981Z","steps":["trace[552807740] 'process raft request' (duration: 144.804434ms)","trace[552807740] 'compare' (duration: 38.626279ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T00:48:33.837486Z","caller":"traceutil/trace.go:171","msg":"trace[1076526698] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"302.322317ms","start":"2026-04-09T00:48:33.535141Z","end":"2026-04-09T00:48:33.837464Z","steps":["trace[1076526698] 'process raft request' (duration: 285.969202ms)","trace[1076526698] 'compare' (duration: 15.694435ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T00:48:33.83764Z","caller":"traceutil/trace.go:171","msg":"trace[436142346] transaction","detail":"{read_only:false; number_of_response:0; response_revision:273; }","duration":"196.241296ms","start":"2026-04-09T00:48:33.641367Z","end":"2026-04-09T00:48:33.837608Z","steps":["trace[436142346] 'process raft request' (duration: 195.694381ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:48:33.837833Z","caller":"traceutil/trace.go:171","msg":"trace[43137164] transaction","detail":"{read_only:false; number_of_response:0; response_revision:273; }","duration":"195.619597ms","start":"2026-04-09T00:48:33.642201Z","end":"2026-04-09T00:48:33.83782Z","steps":["trace[43137164] 'process raft request' (duration: 194.883054ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:48:33.837869Z","caller":"traceutil/trace.go:171","msg":"trace[567154010] transaction","detail":"{read_only:false; number_of_response:0; response_revision:274; }","duration":"193.301952ms","start":"2026-04-09T00:48:33.644504Z","end":"2026-04-09T00:48:33.837806Z","steps":["trace[567154010] 'process raft request' (duration: 192.630911ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:48:33.837937Z","caller":"traceutil/trace.go:171","msg":"trace[2054829647] transaction","detail":"{read_only:false; number_of_response:0; response_revision:272; }","duration":"220.103102ms","start":"2026-04-09T00:48:33.617826Z","end":"2026-04-09T00:48:33.837929Z","steps":["trace[2054829647] 'process raft request' (duration: 219.113568ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:48:33.838486Z","caller":"traceutil/trace.go:171","msg":"trace[385756391] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"220.340223ms","start":"2026-04-09T00:48:33.617589Z","end":"2026-04-09T00:48:33.837929Z","steps":["trace[385756391] 'process raft request' (duration: 219.311077ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:48:33.837957Z","caller":"traceutil/trace.go:171","msg":"trace[951080468] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"194.708846ms","start":"2026-04-09T00:48:33.643191Z","end":"2026-04-09T00:48:33.837899Z","steps":["trace[951080468] 'process raft request' (duration: 193.91035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:48:33.839179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:48:33.535134Z","time spent":"302.407821ms","remote":"127.0.0.1:41148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3200,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T00:48:33.839215Z","caller":"traceutil/trace.go:171","msg":"trace[1659490447] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"196.965348ms","start":"2026-04-09T00:48:33.641007Z","end":"2026-04-09T00:48:33.837972Z","steps":["trace[1659490447] 'process raft request' (duration: 195.999375ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:48:34.115409Z","caller":"traceutil/trace.go:171","msg":"trace[1531596177] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"265.692346ms","start":"2026-04-09T00:48:33.84969Z","end":"2026-04-09T00:48:34.115382Z","steps":["trace[1531596177] 'process raft request' (duration: 183.556521ms)","trace[1531596177] 'compare' (duration: 81.912665ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T00:48:34.115856Z","caller":"traceutil/trace.go:171","msg":"trace[918662241] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"264.383146ms","start":"2026-04-09T00:48:33.851447Z","end":"2026-04-09T00:48:34.11583Z","steps":["trace[918662241] 'process raft request' (duration: 263.838721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:48:34.116018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.886728ms","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-04-09T00:48:34.116185Z","caller":"traceutil/trace.go:171","msg":"trace[1959660108] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:278; }","duration":"265.080207ms","start":"2026-04-09T00:48:33.851089Z","end":"2026-04-09T00:48:34.11617Z","steps":["trace[1959660108] 'agreement among raft nodes before linearized reading' (duration: 264.798914ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:48:34.11601Z","caller":"traceutil/trace.go:171","msg":"trace[2060168261] linearizableReadLoop","detail":"{readStateIndex:288; appliedIndex:286; }","duration":"251.704269ms","start":"2026-04-09T00:48:33.863752Z","end":"2026-04-09T00:48:34.115456Z","steps":["trace[2060168261] 'read index received' (duration: 169.574485ms)","trace[2060168261] 'applied index is now lower than readState.Index' (duration: 82.128344ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T00:48:34.116004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.809175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T00:48:34.116389Z","caller":"traceutil/trace.go:171","msg":"trace[51966168] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:278; }","duration":"265.248546ms","start":"2026-04-09T00:48:33.851118Z","end":"2026-04-09T00:48:34.116367Z","steps":["trace[51966168] 'agreement among raft nodes before linearized reading' (duration: 264.660608ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:48:34.160621Z","caller":"traceutil/trace.go:171","msg":"trace[1539344312] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"293.382101ms","start":"2026-04-09T00:48:33.867223Z","end":"2026-04-09T00:48:34.160605Z","steps":["trace[1539344312] 'process raft request' (duration: 293.186472ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:48:34.160695Z","caller":"traceutil/trace.go:171","msg":"trace[1145617885] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"287.144167ms","start":"2026-04-09T00:48:33.873518Z","end":"2026-04-09T00:48:34.160663Z","steps":["trace[1145617885] 'process raft request' (duration: 287.017572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:48:34.160868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.978265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T00:48:34.16093Z","caller":"traceutil/trace.go:171","msg":"trace[1654499636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:280; }","duration":"203.043477ms","start":"2026-04-09T00:48:33.957868Z","end":"2026-04-09T00:48:34.160912Z","steps":["trace[1654499636] 'agreement among raft nodes before linearized reading' (duration: 202.959274ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:48:34.312799Z","caller":"traceutil/trace.go:171","msg":"trace[2098859440] linearizableReadLoop","detail":"{readStateIndex:293; appliedIndex:292; }","duration":"136.270039ms","start":"2026-04-09T00:48:34.176487Z","end":"2026-04-09T00:48:34.312757Z","steps":["trace[2098859440] 'read index received' (duration: 42.871178ms)","trace[2098859440] 'applied index is now lower than readState.Index' (duration: 93.396071ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T00:48:34.312779Z","caller":"traceutil/trace.go:171","msg":"trace[1245805418] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"132.04576ms","start":"2026-04-09T00:48:34.180705Z","end":"2026-04-09T00:48:34.312751Z","steps":["trace[1245805418] 'process raft request' (duration: 131.981827ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:48:34.312831Z","caller":"traceutil/trace.go:171","msg":"trace[87457141] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"147.628961ms","start":"2026-04-09T00:48:34.165175Z","end":"2026-04-09T00:48:34.312804Z","steps":["trace[87457141] 'process raft request' (duration: 54.152965ms)","trace[87457141] 'compare' (duration: 93.214154ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T00:48:34.313184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.674968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-09T00:48:34.313252Z","caller":"traceutil/trace.go:171","msg":"trace[2023913193] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:284; }","duration":"136.779813ms","start":"2026-04-09T00:48:34.176454Z","end":"2026-04-09T00:48:34.313234Z","steps":["trace[2023913193] 'agreement among raft nodes before linearized reading' (duration: 136.530012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:48:34.662287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.444127ms","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-04-09T00:48:34.662388Z","caller":"traceutil/trace.go:171","msg":"trace[789997225] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:293; }","duration":"114.593464ms","start":"2026-04-09T00:48:34.54776Z","end":"2026-04-09T00:48:34.662353Z","steps":["trace[789997225] 'agreement among raft nodes before linearized reading' (duration: 29.726537ms)","trace[789997225] 'range keys from in-memory index tree' (duration: 84.672188ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T00:48:34.663183Z","caller":"traceutil/trace.go:171","msg":"trace[25529293] transaction","detail":"{read_only:false; number_of_response:0; response_revision:293; }","duration":"108.698769ms","start":"2026-04-09T00:48:34.554466Z","end":"2026-04-09T00:48:34.663164Z","steps":["trace[25529293] 'process raft request' (duration: 108.565513ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:48:34.663199Z","caller":"traceutil/trace.go:171","msg":"trace[1489573123] transaction","detail":"{read_only:false; number_of_response:0; response_revision:293; }","duration":"108.504931ms","start":"2026-04-09T00:48:34.554676Z","end":"2026-04-09T00:48:34.663181Z","steps":["trace[1489573123] 'process raft request' (duration: 108.411596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:48:34.663458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.100052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-04-09T00:48:34.663491Z","caller":"traceutil/trace.go:171","msg":"trace[1590618116] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:293; }","duration":"110.183327ms","start":"2026-04-09T00:48:34.553298Z","end":"2026-04-09T00:48:34.663482Z","steps":["trace[1590618116] 'agreement among raft nodes before linearized reading' (duration: 110.113064ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:48:34.66356Z","caller":"traceutil/trace.go:171","msg":"trace[270532719] transaction","detail":"{read_only:false; number_of_response:0; response_revision:293; }","duration":"107.935985ms","start":"2026-04-09T00:48:34.555615Z","end":"2026-04-09T00:48:34.663551Z","steps":["trace[270532719] 'process raft request' (duration: 107.549078ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:48:34.663219Z","caller":"traceutil/trace.go:171","msg":"trace[966756452] transaction","detail":"{read_only:false; number_of_response:0; response_revision:293; }","duration":"108.388976ms","start":"2026-04-09T00:48:34.554811Z","end":"2026-04-09T00:48:34.6632Z","steps":["trace[966756452] 'process raft request' (duration: 108.291391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:48:34.908246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.573369ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T00:48:34.908378Z","caller":"traceutil/trace.go:171","msg":"trace[1697981060] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"238.351202ms","start":"2026-04-09T00:48:34.670003Z","end":"2026-04-09T00:48:34.908354Z","steps":["trace[1697981060] 'process raft request' (duration: 112.413207ms)","trace[1697981060] 'compare' (duration: 125.335648ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T00:48:34.990086Z","caller":"traceutil/trace.go:171","msg":"trace[2113243598] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"318.042465ms","start":"2026-04-09T00:48:34.672016Z","end":"2026-04-09T00:48:34.990058Z","steps":["trace[2113243598] 'process raft request' (duration: 317.806155ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:48:34.990186Z","caller":"traceutil/trace.go:171","msg":"trace[2033249409] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"308.36413ms","start":"2026-04-09T00:48:34.681779Z","end":"2026-04-09T00:48:34.990143Z","steps":["trace[2033249409] 'process raft request' (duration: 308.190752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:48:34.990239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.614466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T00:48:34.990074Z","caller":"traceutil/trace.go:171","msg":"trace[1728747153] linearizableReadLoop","detail":"{readStateIndex:310; appliedIndex:308; }","duration":"317.433429ms","start":"2026-04-09T00:48:34.672606Z","end":"2026-04-09T00:48:34.99004Z","steps":["trace[1728747153] 'read index received' (duration: 109.81469ms)","trace[1728747153] 'applied index is now lower than readState.Index' (duration: 207.617449ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T00:48:34.990312Z","caller":"traceutil/trace.go:171","msg":"trace[446054130] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:0; response_revision:297; }","duration":"317.710421ms","start":"2026-04-09T00:48:34.672581Z","end":"2026-04-09T00:48:34.990291Z","steps":["trace[446054130] 'agreement among raft nodes before linearized reading' (duration: 317.552494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:48:34.990352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:48:34.672573Z","time spent":"317.769834ms","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-04-09T00:48:34.990447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.616579ms","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-04-09T00:48:34.990499Z","caller":"traceutil/trace.go:171","msg":"trace[568863268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:297; }","duration":"233.737064ms","start":"2026-04-09T00:48:34.756744Z","end":"2026-04-09T00:48:34.990481Z","steps":["trace[568863268] 'agreement among raft nodes before linearized reading' (duration: 233.63606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:48:34.990518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:48:34.672002Z","time spent":"318.157571ms","remote":"127.0.0.1:40998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T00:48:34.990589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:48:34.681741Z","time spent":"308.551239ms","remote":"127.0.0.1:40922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T00:48:35.108804Z","caller":"traceutil/trace.go:171","msg":"trace[226052694] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"107.810992ms","start":"2026-04-09T00:48:35.000974Z","end":"2026-04-09T00:48:35.108785Z","steps":["trace[226052694] 'process raft request' (duration: 107.755359ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:48:35.108885Z","caller":"traceutil/trace.go:171","msg":"trace[1248879576] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"107.908067ms","start":"2026-04-09T00:48:35.00092Z","end":"2026-04-09T00:48:35.108828Z","steps":["trace[1248879576] 'process raft request' (duration: 107.714838ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:48:35.326509Z","caller":"traceutil/trace.go:171","msg":"trace[1179112202] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"207.696019ms","start":"2026-04-09T00:48:35.118778Z","end":"2026-04-09T00:48:35.326474Z","steps":["trace[1179112202] 'process raft request' (duration: 207.634296ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:48:35.326671Z","caller":"traceutil/trace.go:171","msg":"trace[1824599611] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"209.168715ms","start":"2026-04-09T00:48:35.117461Z","end":"2026-04-09T00:48:35.32663Z","steps":["trace[1824599611] 'process raft request' (duration: 109.983077ms)","trace[1824599611] 'compare' (duration: 98.759298ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T00:48:39.942818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.716064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T00:48:39.943071Z","caller":"traceutil/trace.go:171","msg":"trace[1664376663] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"189.21459ms","start":"2026-04-09T00:48:39.753836Z","end":"2026-04-09T00:48:39.94305Z","steps":["trace[1664376663] 'process raft request' (duration: 83.173742ms)","trace[1664376663] 'compare' (duration: 105.37802ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T00:48:39.943346Z","caller":"traceutil/trace.go:171","msg":"trace[75002088] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"187.07494ms","start":"2026-04-09T00:48:39.756255Z","end":"2026-04-09T00:48:39.94333Z","steps":["trace[75002088] 'process raft request' (duration: 186.819828ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:48:39.943426Z","caller":"traceutil/trace.go:171","msg":"trace[2048393288] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"187.231237ms","start":"2026-04-09T00:48:39.756188Z","end":"2026-04-09T00:48:39.943419Z","steps":["trace[2048393288] 'process raft request' (duration: 186.776646ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:48:45.404414Z","caller":"traceutil/trace.go:171","msg":"trace[1770118106] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"120.671897ms","start":"2026-04-09T00:48:45.28371Z","end":"2026-04-09T00:48:45.404382Z","steps":["trace[1770118106] 'process raft request' (duration: 110.673823ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:48:57.506569Z","caller":"traceutil/trace.go:171","msg":"trace[797028054] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"380.725292ms","start":"2026-04-09T00:48:57.125814Z","end":"2026-04-09T00:48:57.506539Z","steps":["trace[797028054] 'process raft request' (duration: 380.490444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:48:57.506784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:48:57.125784Z","time spent":"380.887817ms","remote":"127.0.0.1:40998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T00:48:57.90746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.182973ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-09T00:48:57.90759Z","caller":"traceutil/trace.go:171","msg":"trace[1184243315] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:487; }","duration":"610.244799ms","start":"2026-04-09T00:48:57.297327Z","end":"2026-04-09T00:48:57.907572Z","steps":["trace[1184243315] 'read index received' (duration: 208.992951ms)","trace[1184243315] 'applied index is now lower than readState.Index' (duration: 401.250448ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T00:48:57.907718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.386333ms","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-04-09T00:48:57.907795Z","caller":"traceutil/trace.go:171","msg":"trace[722195302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:470; }","duration":"610.519548ms","start":"2026-04-09T00:48:57.297263Z","end":"2026-04-09T00:48:57.907783Z","steps":["trace[722195302] 'agreement among raft nodes before linearized reading' (duration: 610.371653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:48:57.907847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:48:57.297238Z","time spent":"610.58096ms","remote":"127.0.0.1:40998","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-04-09T00:48:58.628115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.746095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-09T00:48:58.628167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.806983ms","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-04-09T00:48:58.628189Z","caller":"traceutil/trace.go:171","msg":"trace[1372795825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:471; }","duration":"176.860529ms","start":"2026-04-09T00:48:58.451312Z","end":"2026-04-09T00:48:58.628173Z","steps":["trace[1372795825] 'range keys from in-memory index tree' (duration: 176.631521ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:48:58.628223Z","caller":"traceutil/trace.go:171","msg":"trace[2133943734] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:471; }","duration":"308.866045ms","start":"2026-04-09T00:48:58.31934Z","end":"2026-04-09T00:48:58.628206Z","steps":["trace[2133943734] 'range keys from in-memory index tree' (duration: 308.685998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:48:58.628269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:48:58.319304Z","time spent":"308.953238ms","remote":"127.0.0.1:40998","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-04-09T00:48:58.62831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.170051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12677"} {"level":"info","ts":"2026-04-09T00:48:58.628414Z","caller":"traceutil/trace.go:171","msg":"trace[780111595] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:471; }","duration":"210.324716ms","start":"2026-04-09T00:48:58.418063Z","end":"2026-04-09T00:48:58.628388Z","steps":["trace[780111595] 'range keys from in-memory index tree' (duration: 210.012735ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:48:59.678552Z","caller":"traceutil/trace.go:171","msg":"trace[796228095] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"155.580258ms","start":"2026-04-09T00:48:59.522932Z","end":"2026-04-09T00:48:59.678513Z","steps":["trace[796228095] 'process raft request' (duration: 121.681629ms)","trace[796228095] 'compare' (duration: 33.462774ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T00:48:59.987538Z","caller":"traceutil/trace.go:171","msg":"trace[1627412103] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:495; }","duration":"160.512165ms","start":"2026-04-09T00:48:59.826985Z","end":"2026-04-09T00:48:59.987497Z","steps":["trace[1627412103] 'read index received' (duration: 80.585028ms)","trace[1627412103] 'applied index is now lower than readState.Index' (duration: 79.924977ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T00:48:59.98779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.788614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T00:48:59.987896Z","caller":"traceutil/trace.go:171","msg":"trace[691532372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:477; }","duration":"160.95048ms","start":"2026-04-09T00:48:59.826917Z","end":"2026-04-09T00:48:59.987867Z","steps":["trace[691532372] 'agreement among raft nodes before linearized reading' (duration: 160.737622ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:48:59.988102Z","caller":"traceutil/trace.go:171","msg":"trace[1688087293] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"283.044602ms","start":"2026-04-09T00:48:59.704514Z","end":"2026-04-09T00:48:59.987559Z","steps":["trace[1688087293] 'process raft request' (duration: 203.112455ms)","trace[1688087293] 'compare' (duration: 79.487171ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T00:49:00.241841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.955795ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T00:49:00.242003Z","caller":"traceutil/trace.go:171","msg":"trace[1932621056] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"196.810961ms","start":"2026-04-09T00:49:00.045169Z","end":"2026-04-09T00:49:00.241979Z","steps":["trace[1932621056] 'process raft request' (duration: 55.585268ms)","trace[1932621056] 'compare' (duration: 140.733727ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T00:49:00.29761Z","caller":"traceutil/trace.go:171","msg":"trace[502802207] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"252.332857ms","start":"2026-04-09T00:49:00.04524Z","end":"2026-04-09T00:49:00.297573Z","steps":["trace[502802207] 'process raft request' (duration: 252.145831ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:02.194319Z","caller":"traceutil/trace.go:171","msg":"trace[1454521842] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"109.025255ms","start":"2026-04-09T00:49:02.085271Z","end":"2026-04-09T00:49:02.194296Z","steps":["trace[1454521842] 'process raft request' (duration: 108.896011ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:02.194373Z","caller":"traceutil/trace.go:171","msg":"trace[1795045110] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"109.361026ms","start":"2026-04-09T00:49:02.084933Z","end":"2026-04-09T00:49:02.194294Z","steps":["trace[1795045110] 'process raft request' (duration: 109.124148ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:02.194447Z","caller":"traceutil/trace.go:171","msg":"trace[1557544982] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"106.72471ms","start":"2026-04-09T00:49:02.087614Z","end":"2026-04-09T00:49:02.194338Z","steps":["trace[1557544982] 'process raft request' (duration: 106.603126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:02.194533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.136229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-09T00:49:02.194588Z","caller":"traceutil/trace.go:171","msg":"trace[1520364123] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:525; }","duration":"109.224132ms","start":"2026-04-09T00:49:02.085353Z","end":"2026-04-09T00:49:02.194577Z","steps":["trace[1520364123] 'agreement among raft nodes before linearized reading' (duration: 109.085898ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:02.194392Z","caller":"traceutil/trace.go:171","msg":"trace[1264552100] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:534; }","duration":"108.995554ms","start":"2026-04-09T00:49:02.085371Z","end":"2026-04-09T00:49:02.194367Z","steps":["trace[1264552100] 'read index received' (duration: 22.315126ms)","trace[1264552100] 'applied index is now lower than readState.Index' (duration: 86.679618ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T00:49:02.194224Z","caller":"traceutil/trace.go:171","msg":"trace[191416400] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"111.448784ms","start":"2026-04-09T00:49:02.082752Z","end":"2026-04-09T00:49:02.1942Z","steps":["trace[191416400] 'process raft request' (duration: 111.160445ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:02.227416Z","caller":"traceutil/trace.go:171","msg":"trace[233016795] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"112.394255ms","start":"2026-04-09T00:49:02.11501Z","end":"2026-04-09T00:49:02.227405Z","steps":["trace[233016795] 'process raft request' (duration: 112.22911ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:02.227437Z","caller":"traceutil/trace.go:171","msg":"trace[380699688] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"112.375384ms","start":"2026-04-09T00:49:02.115053Z","end":"2026-04-09T00:49:02.227428Z","steps":["trace[380699688] 'process raft request' (duration: 112.283581ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:02.227685Z","caller":"traceutil/trace.go:171","msg":"trace[310151401] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"108.643483ms","start":"2026-04-09T00:49:02.119022Z","end":"2026-04-09T00:49:02.227666Z","steps":["trace[310151401] 'process raft request' (duration: 108.54772ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:02.227837Z","caller":"traceutil/trace.go:171","msg":"trace[464982864] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"111.692763ms","start":"2026-04-09T00:49:02.116134Z","end":"2026-04-09T00:49:02.227826Z","steps":["trace[464982864] 'process raft request' (duration: 111.261759ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:02.227898Z","caller":"traceutil/trace.go:171","msg":"trace[2135145788] transaction","detail":"{read_only:false; number_of_response:1; response_revision:529; }","duration":"111.62171ms","start":"2026-04-09T00:49:02.11627Z","end":"2026-04-09T00:49:02.227892Z","steps":["trace[2135145788] 'process raft request' (duration: 111.267858ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:02.227715Z","caller":"traceutil/trace.go:171","msg":"trace[1450652448] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"112.580231ms","start":"2026-04-09T00:49:02.115128Z","end":"2026-04-09T00:49:02.227708Z","steps":["trace[1450652448] 'process raft request' (duration: 112.23161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:02.420866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.450895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T00:49:02.421544Z","caller":"traceutil/trace.go:171","msg":"trace[903649730] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"211.546588ms","start":"2026-04-09T00:49:02.209961Z","end":"2026-04-09T00:49:02.421507Z","steps":["trace[903649730] 'process raft request' (duration: 211.044022ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:02.422135Z","caller":"traceutil/trace.go:171","msg":"trace[398539272] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"219.587369ms","start":"2026-04-09T00:49:02.20218Z","end":"2026-04-09T00:49:02.421767Z","steps":["trace[398539272] 'process raft request' (duration: 67.152953ms)","trace[398539272] 'compare' (duration: 151.30673ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T00:49:02.422434Z","caller":"traceutil/trace.go:171","msg":"trace[1491025181] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"199.118634ms","start":"2026-04-09T00:49:02.223292Z","end":"2026-04-09T00:49:02.422411Z","steps":["trace[1491025181] 'process raft request' (duration: 197.822351ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:02.422467Z","caller":"traceutil/trace.go:171","msg":"trace[632236520] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"211.499678ms","start":"2026-04-09T00:49:02.210838Z","end":"2026-04-09T00:49:02.422337Z","steps":["trace[632236520] 'process raft request' (duration: 210.244136ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:02.427502Z","caller":"traceutil/trace.go:171","msg":"trace[316169400] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"200.131158ms","start":"2026-04-09T00:49:02.227347Z","end":"2026-04-09T00:49:02.427478Z","steps":["trace[316169400] 'process raft request' (duration: 199.972112ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:02.427619Z","caller":"traceutil/trace.go:171","msg":"trace[1156946336] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"200.326865ms","start":"2026-04-09T00:49:02.227284Z","end":"2026-04-09T00:49:02.427611Z","steps":["trace[1156946336] 'process raft request' (duration: 199.868429ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:02.427687Z","caller":"traceutil/trace.go:171","msg":"trace[685337251] transaction","detail":"{read_only:false; number_of_response:0; response_revision:537; }","duration":"193.281295ms","start":"2026-04-09T00:49:02.234399Z","end":"2026-04-09T00:49:02.42768Z","steps":["trace[685337251] 'process raft request' (duration: 193.046757ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:02.427824Z","caller":"traceutil/trace.go:171","msg":"trace[384671093] transaction","detail":"{read_only:false; number_of_response:0; response_revision:537; }","duration":"193.508482ms","start":"2026-04-09T00:49:02.234309Z","end":"2026-04-09T00:49:02.427817Z","steps":["trace[384671093] 'process raft request' (duration: 193.094418ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:02.427853Z","caller":"traceutil/trace.go:171","msg":"trace[413426727] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:552; }","duration":"193.573074ms","start":"2026-04-09T00:49:02.234239Z","end":"2026-04-09T00:49:02.427812Z","steps":["trace[413426727] 'read index received' (duration: 35.117281ms)","trace[413426727] 'applied index is now lower than readState.Index' (duration: 158.454833ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T00:49:02.427972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.713389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-09T00:49:02.42801Z","caller":"traceutil/trace.go:171","msg":"trace[1244332557] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:541; }","duration":"193.763911ms","start":"2026-04-09T00:49:02.234236Z","end":"2026-04-09T00:49:02.427999Z","steps":["trace[1244332557] 'agreement among raft nodes before linearized reading' (duration: 193.668848ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:02.428195Z","caller":"traceutil/trace.go:171","msg":"trace[1727423898] transaction","detail":"{read_only:false; number_of_response:0; response_revision:537; }","duration":"193.787701ms","start":"2026-04-09T00:49:02.234399Z","end":"2026-04-09T00:49:02.428186Z","steps":["trace[1727423898] 'process raft request' (duration: 193.070388ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:02.428374Z","caller":"traceutil/trace.go:171","msg":"trace[247911175] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"179.357732ms","start":"2026-04-09T00:49:02.248985Z","end":"2026-04-09T00:49:02.428343Z","steps":["trace[247911175] 'process raft request' (duration: 178.636359ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:02.428488Z","caller":"traceutil/trace.go:171","msg":"trace[1947061419] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"189.312306ms","start":"2026-04-09T00:49:02.239163Z","end":"2026-04-09T00:49:02.428475Z","steps":["trace[1947061419] 'process raft request' (duration: 188.331565ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:02.428517Z","caller":"traceutil/trace.go:171","msg":"trace[1393365908] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"189.260295ms","start":"2026-04-09T00:49:02.239234Z","end":"2026-04-09T00:49:02.428494Z","steps":["trace[1393365908] 'process raft request' (duration: 188.337225ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:02.428525Z","caller":"traceutil/trace.go:171","msg":"trace[1168262133] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"174.375781ms","start":"2026-04-09T00:49:02.254136Z","end":"2026-04-09T00:49:02.428511Z","steps":["trace[1168262133] 'process raft request' (duration: 173.578485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:03.971686Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.165959762s","expected-duration":"1s"} {"level":"info","ts":"2026-04-09T00:49:03.980943Z","caller":"traceutil/trace.go:171","msg":"trace[1371837950] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"1.192030986s","start":"2026-04-09T00:49:02.788897Z","end":"2026-04-09T00:49:03.980928Z","steps":["trace[1371837950] 'process raft request' (duration: 1.191968644s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:03.981167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:49:02.788889Z","time spent":"1.19210753s","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T00:49:03.98127Z","caller":"traceutil/trace.go:171","msg":"trace[289109547] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.193810585s","start":"2026-04-09T00:49:02.787442Z","end":"2026-04-09T00:49:03.981252Z","steps":["trace[289109547] 'process raft request' (duration: 1.184670501s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:03.981344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:49:02.787422Z","time spent":"1.193888088s","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T00:49:04.006792Z","caller":"traceutil/trace.go:171","msg":"trace[1425231919] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:590; }","duration":"427.082585ms","start":"2026-04-09T00:49:03.579682Z","end":"2026-04-09T00:49:04.006764Z","steps":["trace[1425231919] 'read index received' (duration: 392.442092ms)","trace[1425231919] 'applied index is now lower than readState.Index' (duration: 34.638363ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T00:49:04.006857Z","caller":"traceutil/trace.go:171","msg":"trace[978475837] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"1.180783911s","start":"2026-04-09T00:49:02.826045Z","end":"2026-04-09T00:49:04.006829Z","steps":["trace[978475837] 'process raft request' (duration: 1.180555674s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:04.007013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.317263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-09T00:49:04.007056Z","caller":"traceutil/trace.go:171","msg":"trace[1828072181] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:568; }","duration":"427.407825ms","start":"2026-04-09T00:49:03.579638Z","end":"2026-04-09T00:49:04.007046Z","steps":["trace[1828072181] 'agreement among raft nodes before linearized reading' (duration: 427.285702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:04.00708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:49:03.579618Z","time spent":"427.455946ms","remote":"127.0.0.1:40998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-09T00:49:04.007099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:49:02.826024Z","time spent":"1.180969636s","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T00:49:04.023018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.266638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T00:49:04.023103Z","caller":"traceutil/trace.go:171","msg":"trace[1904709755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:570; }","duration":"197.402632ms","start":"2026-04-09T00:49:03.825678Z","end":"2026-04-09T00:49:04.02308Z","steps":["trace[1904709755] 'agreement among raft nodes before linearized reading' (duration: 197.193616ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:04.674141Z","caller":"traceutil/trace.go:171","msg":"trace[1326417701] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"115.66617ms","start":"2026-04-09T00:49:04.558442Z","end":"2026-04-09T00:49:04.674108Z","steps":["trace[1326417701] 'process raft request' (duration: 111.026343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:04.80429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.228098ms","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-04-09T00:49:04.804357Z","caller":"traceutil/trace.go:171","msg":"trace[1715683234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:592; }","duration":"121.333891ms","start":"2026-04-09T00:49:04.683007Z","end":"2026-04-09T00:49:04.804341Z","steps":["trace[1715683234] 'agreement among raft nodes before linearized reading' (duration: 121.176606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:10.613615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.515841ms","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-04-09T00:49:10.613712Z","caller":"traceutil/trace.go:171","msg":"trace[1907371345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"437.650527ms","start":"2026-04-09T00:49:10.176041Z","end":"2026-04-09T00:49:10.613691Z","steps":["trace[1907371345] 'range keys from in-memory index tree' (duration: 437.258724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:10.61375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:49:10.176024Z","time spent":"437.716799ms","remote":"127.0.0.1:40998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-09T00:49:10.693322Z","caller":"traceutil/trace.go:171","msg":"trace[1136453586] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"492.486614ms","start":"2026-04-09T00:49:10.200815Z","end":"2026-04-09T00:49:10.693302Z","steps":["trace[1136453586] 'process raft request' (duration: 491.978819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:10.693531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:49:10.200785Z","time spent":"492.670628ms","remote":"127.0.0.1:40818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T00:49:10.858763Z","caller":"traceutil/trace.go:171","msg":"trace[270436806] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:651; }","duration":"198.917989ms","start":"2026-04-09T00:49:10.659821Z","end":"2026-04-09T00:49:10.858739Z","steps":["trace[270436806] 'read index received' (duration: 32.764279ms)","trace[270436806] 'applied index is now lower than readState.Index' (duration: 166.15282ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T00:49:10.858809Z","caller":"traceutil/trace.go:171","msg":"trace[1530238020] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"240.747518ms","start":"2026-04-09T00:49:10.618029Z","end":"2026-04-09T00:49:10.858776Z","steps":["trace[1530238020] 'process raft request' (duration: 148.919666ms)","trace[1530238020] 'compare' (duration: 91.623696ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T00:49:10.859061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.229808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rwsh7\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-09T00:49:10.859143Z","caller":"traceutil/trace.go:171","msg":"trace[86575482] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rwsh7; range_end:; response_count:1; response_revision:627; }","duration":"199.357002ms","start":"2026-04-09T00:49:10.659769Z","end":"2026-04-09T00:49:10.859126Z","steps":["trace[86575482] 'agreement among raft nodes before linearized reading' (duration: 199.108145ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:10.881331Z","caller":"traceutil/trace.go:171","msg":"trace[675832118] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"134.961619ms","start":"2026-04-09T00:49:10.746332Z","end":"2026-04-09T00:49:10.881293Z","steps":["trace[675832118] 'process raft request' (duration: 134.676621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:11.383783Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366884367823972,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T00:49:11.563394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.447378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T00:49:11.56356Z","caller":"traceutil/trace.go:171","msg":"trace[1541334649] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"690.001076ms","start":"2026-04-09T00:49:10.873524Z","end":"2026-04-09T00:49:11.563525Z","steps":["trace[1541334649] 'process raft request' (duration: 545.28669ms)","trace[1541334649] 'compare' (duration: 143.84949ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T00:49:11.5637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:49:10.873454Z","time spent":"690.179902ms","remote":"127.0.0.1:40922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T00:49:11.600548Z","caller":"traceutil/trace.go:171","msg":"trace[1930125028] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"714.842186ms","start":"2026-04-09T00:49:10.885676Z","end":"2026-04-09T00:49:11.600518Z","steps":["trace[1930125028] 'process raft request' (duration: 714.659691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:11.600741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:49:10.885624Z","time spent":"715.005551ms","remote":"127.0.0.1:40998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T00:49:11.601026Z","caller":"traceutil/trace.go:171","msg":"trace[872604535] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:654; }","duration":"718.226817ms","start":"2026-04-09T00:49:10.882752Z","end":"2026-04-09T00:49:11.600979Z","steps":["trace[872604535] 'read index received' (duration: 536.073985ms)","trace[872604535] 'applied index is now lower than readState.Index' (duration: 182.150502ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T00:49:11.601183Z","caller":"traceutil/trace.go:171","msg":"trace[40075732] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"713.964509ms","start":"2026-04-09T00:49:10.887209Z","end":"2026-04-09T00:49:11.601174Z","steps":["trace[40075732] 'process raft request' (duration: 713.267749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:11.601233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.477434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T00:49:11.601285Z","caller":"traceutil/trace.go:171","msg":"trace[1313548500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"718.537345ms","start":"2026-04-09T00:49:10.882726Z","end":"2026-04-09T00:49:11.601263Z","steps":["trace[1313548500] 'agreement among raft nodes before linearized reading' (duration: 718.482083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:11.601316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:49:10.882709Z","time spent":"718.598147ms","remote":"127.0.0.1:40718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T00:49:11.601731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:49:10.887196Z","time spent":"714.019472ms","remote":"127.0.0.1:40998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T00:49:11.612539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.129179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-09T00:49:11.612549Z","caller":"traceutil/trace.go:171","msg":"trace[922588713] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"137.361088ms","start":"2026-04-09T00:49:11.475155Z","end":"2026-04-09T00:49:11.612516Z","steps":["trace[922588713] 'process raft request' (duration: 137.184043ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:11.612608Z","caller":"traceutil/trace.go:171","msg":"trace[765071289] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:632; }","duration":"728.220121ms","start":"2026-04-09T00:49:10.884373Z","end":"2026-04-09T00:49:11.612593Z","steps":["trace[765071289] 'agreement among raft nodes before linearized reading' (duration: 728.088888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:11.612639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:49:10.884365Z","time spent":"728.267993ms","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-09T00:49:13.012753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.452305ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T00:49:13.012877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.340304957s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T00:49:13.012901Z","caller":"traceutil/trace.go:171","msg":"trace[1668140951] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:632; }","duration":"795.611461ms","start":"2026-04-09T00:49:12.217267Z","end":"2026-04-09T00:49:13.012878Z","steps":["trace[1668140951] 'range keys from in-memory index tree' (duration: 795.433265ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:13.012947Z","caller":"traceutil/trace.go:171","msg":"trace[882857375] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"1.186942397s","start":"2026-04-09T00:49:11.825992Z","end":"2026-04-09T00:49:13.012934Z","steps":["trace[882857375] 'read index received' (duration: 53.882µs)","trace[882857375] 'applied index is now lower than readState.Index' (duration: 1.186887215s)"],"step_count":2} {"level":"info","ts":"2026-04-09T00:49:13.01297Z","caller":"traceutil/trace.go:171","msg":"trace[1792020397] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.385980828s","start":"2026-04-09T00:49:11.626967Z","end":"2026-04-09T00:49:13.012947Z","steps":["trace[1792020397] 'process raft request' (duration: 45.524356ms)","trace[1792020397] 'compare' (duration: 1.340109222s)"],"step_count":2} {"level":"warn","ts":"2026-04-09T00:49:13.013054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:49:11.626947Z","time spent":"1.38606854s","remote":"127.0.0.1:40922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T00:49:13.013226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021343223s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-09T00:49:13.013267Z","caller":"traceutil/trace.go:171","msg":"trace[1350528629] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:633; }","duration":"1.021385574s","start":"2026-04-09T00:49:11.991872Z","end":"2026-04-09T00:49:13.013258Z","steps":["trace[1350528629] 'agreement among raft nodes before linearized reading' (duration: 1.021152208s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:13.013224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187219835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T00:49:13.013282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.678183ms","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-04-09T00:49:13.013323Z","caller":"traceutil/trace.go:171","msg":"trace[464788040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"1.187362049s","start":"2026-04-09T00:49:11.825938Z","end":"2026-04-09T00:49:13.0133Z","steps":["trace[464788040] 'agreement among raft nodes before linearized reading' (duration: 1.18707351s)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:13.013338Z","caller":"traceutil/trace.go:171","msg":"trace[1101164879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"149.774435ms","start":"2026-04-09T00:49:12.863549Z","end":"2026-04-09T00:49:13.013323Z","steps":["trace[1101164879] 'agreement among raft nodes before linearized reading' (duration: 149.629941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:13.01338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.720835ms","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-04-09T00:49:13.013376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:49:11.825913Z","time spent":"1.187453742s","remote":"127.0.0.1:40714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-09T00:49:13.013406Z","caller":"traceutil/trace.go:171","msg":"trace[185227168] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"924.792547ms","start":"2026-04-09T00:49:12.088606Z","end":"2026-04-09T00:49:13.013399Z","steps":["trace[185227168] 'agreement among raft nodes before linearized reading' (duration: 924.712554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:13.01343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:49:12.088571Z","time spent":"924.854958ms","remote":"127.0.0.1:40998","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-04-09T00:49:13.0133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:49:11.991783Z","time spent":"1.021508938s","remote":"127.0.0.1:40760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"info","ts":"2026-04-09T00:49:13.482899Z","caller":"traceutil/trace.go:171","msg":"trace[800641950] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"467.985433ms","start":"2026-04-09T00:49:13.014866Z","end":"2026-04-09T00:49:13.482851Z","steps":["trace[800641950] 'read index received' (duration: 370.245965ms)","trace[800641950] 'applied index is now lower than readState.Index' (duration: 97.738188ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T00:49:13.482914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:49:13.014434Z","time spent":"468.472937ms","remote":"127.0.0.1:40760","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-09T00:49:13.482997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.114747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T00:49:13.483022Z","caller":"traceutil/trace.go:171","msg":"trace[866412445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"468.177369ms","start":"2026-04-09T00:49:13.014837Z","end":"2026-04-09T00:49:13.483014Z","steps":["trace[866412445] 'agreement among raft nodes before linearized reading' (duration: 468.114687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:13.483043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:49:13.014821Z","time spent":"468.21715ms","remote":"127.0.0.1:40718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-09T00:49:13.485355Z","caller":"traceutil/trace.go:171","msg":"trace[1960216351] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"467.687845ms","start":"2026-04-09T00:49:13.017627Z","end":"2026-04-09T00:49:13.485315Z","steps":["trace[1960216351] 'process raft request' (duration: 467.323855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:13.485495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:49:13.017598Z","time spent":"467.819819ms","remote":"127.0.0.1:40998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T00:49:13.485755Z","caller":"traceutil/trace.go:171","msg":"trace[652724010] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"467.693375ms","start":"2026-04-09T00:49:13.018047Z","end":"2026-04-09T00:49:13.485741Z","steps":["trace[652724010] 'process raft request' (duration: 467.540751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:13.485821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:49:13.01802Z","time spent":"467.764706ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T00:49:13.485941Z","caller":"traceutil/trace.go:171","msg":"trace[30914697] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"463.03535ms","start":"2026-04-09T00:49:13.022874Z","end":"2026-04-09T00:49:13.48591Z","steps":["trace[30914697] 'process raft request' (duration: 462.919627ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:13.486088Z","caller":"traceutil/trace.go:171","msg":"trace[1903066178] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"467.617293ms","start":"2026-04-09T00:49:13.018453Z","end":"2026-04-09T00:49:13.48607Z","steps":["trace[1903066178] 'process raft request' (duration: 467.205661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:13.486166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:49:13.022866Z","time spent":"463.166773ms","remote":"127.0.0.1:40922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T00:49:13.486181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:49:13.018443Z","time spent":"467.693424ms","remote":"127.0.0.1:40998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T00:49:13.48704Z","caller":"traceutil/trace.go:171","msg":"trace[1750463869] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"468.273122ms","start":"2026-04-09T00:49:13.018742Z","end":"2026-04-09T00:49:13.487015Z","steps":["trace[1750463869] 'process raft request' (duration: 466.975044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:13.48716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:49:13.018729Z","time spent":"468.372594ms","remote":"127.0.0.1:40976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T00:49:13.487297Z","caller":"traceutil/trace.go:171","msg":"trace[1336301843] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"464.576094ms","start":"2026-04-09T00:49:13.021411Z","end":"2026-04-09T00:49:13.485987Z","steps":["trace[1336301843] 'process raft request' (duration: 464.350458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:13.487489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:49:13.021401Z","time spent":"466.020047ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T00:49:15.134719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.293822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T00:49:15.134784Z","caller":"traceutil/trace.go:171","msg":"trace[1228158802] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:665; }","duration":"309.401065ms","start":"2026-04-09T00:49:14.825368Z","end":"2026-04-09T00:49:15.134769Z","steps":["trace[1228158802] 'range keys from in-memory index tree' (duration: 309.207729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:15.134813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:49:14.82535Z","time spent":"309.456886ms","remote":"127.0.0.1:40718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T00:49:15.135107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.77603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T00:49:15.135153Z","caller":"traceutil/trace.go:171","msg":"trace[2030626143] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:665; }","duration":"210.850862ms","start":"2026-04-09T00:49:14.924291Z","end":"2026-04-09T00:49:15.135142Z","steps":["trace[2030626143] 'range keys from in-memory index tree' (duration: 210.697457ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:26.963167Z","caller":"traceutil/trace.go:171","msg":"trace[98545102] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:811; }","duration":"138.377089ms","start":"2026-04-09T00:49:26.824768Z","end":"2026-04-09T00:49:26.963145Z","steps":["trace[98545102] 'read index received' (duration: 138.086862ms)","trace[98545102] 'applied index is now lower than readState.Index' (duration: 275.717µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T00:49:26.963252Z","caller":"traceutil/trace.go:171","msg":"trace[1620589490] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"173.145735ms","start":"2026-04-09T00:49:26.790062Z","end":"2026-04-09T00:49:26.963208Z","steps":["trace[1620589490] 'process raft request' (duration: 172.815696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:26.963282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.495693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T00:49:26.963443Z","caller":"traceutil/trace.go:171","msg":"trace[1728754861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:782; }","duration":"138.613936ms","start":"2026-04-09T00:49:26.824763Z","end":"2026-04-09T00:49:26.963377Z","steps":["trace[1728754861] 'agreement among raft nodes before linearized reading' (duration: 138.475693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:29.809256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.605668ms","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-04-09T00:49:29.809329Z","caller":"traceutil/trace.go:171","msg":"trace[924825905] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:795; }","duration":"841.725161ms","start":"2026-04-09T00:49:28.967591Z","end":"2026-04-09T00:49:29.809316Z","steps":["trace[924825905] 'range keys from in-memory index tree' (duration: 841.439894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:29.809357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T00:49:28.967571Z","time spent":"841.780173ms","remote":"127.0.0.1:40998","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-04-09T00:49:29.809272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.266093ms","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-04-09T00:49:29.809534Z","caller":"traceutil/trace.go:171","msg":"trace[315641545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:795; }","duration":"126.460987ms","start":"2026-04-09T00:49:29.682979Z","end":"2026-04-09T00:49:29.80944Z","steps":["trace[315641545] 'range keys from in-memory index tree' (duration: 126.139009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:29.809593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.036312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-09T00:49:29.809718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.864484ms","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-04-09T00:49:29.809755Z","caller":"traceutil/trace.go:171","msg":"trace[1972912205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:795; }","duration":"252.203926ms","start":"2026-04-09T00:49:29.557507Z","end":"2026-04-09T00:49:29.809711Z","steps":["trace[1972912205] 'range keys from in-memory index tree' (duration: 251.918449ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:29.809791Z","caller":"traceutil/trace.go:171","msg":"trace[155232714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:795; }","duration":"246.971746ms","start":"2026-04-09T00:49:29.562805Z","end":"2026-04-09T00:49:29.809777Z","steps":["trace[155232714] 'range keys from in-memory index tree' (duration: 246.723741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T00:49:29.80989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.516297ms","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-04-09T00:49:29.809939Z","caller":"traceutil/trace.go:171","msg":"trace[1725681206] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:795; }","duration":"130.586668ms","start":"2026-04-09T00:49:29.679339Z","end":"2026-04-09T00:49:29.809926Z","steps":["trace[1725681206] 'range keys from in-memory index tree' (duration: 130.440465ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:44.035564Z","caller":"traceutil/trace.go:171","msg":"trace[1681121176] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"117.724522ms","start":"2026-04-09T00:49:43.917807Z","end":"2026-04-09T00:49:44.035532Z","steps":["trace[1681121176] 'process raft request' (duration: 117.437006ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:44.093156Z","caller":"traceutil/trace.go:171","msg":"trace[1927132105] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"172.386935ms","start":"2026-04-09T00:49:43.920753Z","end":"2026-04-09T00:49:44.09314Z","steps":["trace[1927132105] 'process raft request' (duration: 172.350324ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:44.093192Z","caller":"traceutil/trace.go:171","msg":"trace[1522299452] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"175.202217ms","start":"2026-04-09T00:49:43.917951Z","end":"2026-04-09T00:49:44.093154Z","steps":["trace[1522299452] 'process raft request' (duration: 175.056773ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:44.093289Z","caller":"traceutil/trace.go:171","msg":"trace[1666922084] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:995; }","duration":"174.00782ms","start":"2026-04-09T00:49:43.919271Z","end":"2026-04-09T00:49:44.093278Z","steps":["trace[1666922084] 'read index received' (duration: 116.118907ms)","trace[1666922084] 'applied index is now lower than readState.Index' (duration: 57.888233ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T00:49:44.09341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.128363ms","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-04-09T00:49:44.093432Z","caller":"traceutil/trace.go:171","msg":"trace[149796973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:964; }","duration":"174.180843ms","start":"2026-04-09T00:49:43.919244Z","end":"2026-04-09T00:49:44.093425Z","steps":["trace[149796973] 'agreement among raft nodes before linearized reading' (duration: 174.064621ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:49:44.093479Z","caller":"traceutil/trace.go:171","msg":"trace[1840128360] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"172.568888ms","start":"2026-04-09T00:49:43.920906Z","end":"2026-04-09T00:49:44.093475Z","steps":["trace[1840128360] 'process raft request' (duration: 172.214731ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:51:20.824219Z","caller":"traceutil/trace.go:171","msg":"trace[1902425580] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"224.933671ms","start":"2026-04-09T00:51:20.599255Z","end":"2026-04-09T00:51:20.824189Z","steps":["trace[1902425580] 'process raft request' (duration: 224.766569ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T00:51:20.857402Z","caller":"traceutil/trace.go:171","msg":"trace[58116058] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"257.642682ms","start":"2026-04-09T00:51:20.599723Z","end":"2026-04-09T00:51:20.857366Z","steps":["trace[58116058] 'process raft request' (duration: 246.780646ms)","trace[58116058] 'compare' (duration: 10.591073ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T00:51:20.857667Z","caller":"traceutil/trace.go:171","msg":"trace[1460073044] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"257.812585ms","start":"2026-04-09T00:51:20.599833Z","end":"2026-04-09T00:51:20.857645Z","steps":["trace[1460073044] 'process raft request' (duration: 257.43903ms)"],"step_count":1}