{"level":"warn","ts":"2026-05-07T03:18:47.195849Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T03:18:47.195932Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.25: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.19.213.25:2380","--initial-cluster=instance=https://199.19.213.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.25:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-07T03:18:47.195994Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T03:18:47.196001Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.25:2380"]} {"level":"info","ts":"2026-05-07T03:18:47.196018Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-07T03:18:47.196682Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.25:2379"]} {"level":"info","ts":"2026-05-07T03:18:47.196787Z","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.19.213.25:2380"],"listen-peer-urls":["https://199.19.213.25:2380"],"advertise-client-urls":["https://199.19.213.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.25:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-07T03:18:47.200593Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.609793ms"} {"level":"info","ts":"2026-05-07T03:18:47.20799Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9bd4db28c48efaf1","cluster-id":"b621f59192c78404"} {"level":"info","ts":"2026-05-07T03:18:47.208137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T03:18:47.208213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 became follower at term 0"} {"level":"info","ts":"2026-05-07T03:18:47.208249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9bd4db28c48efaf1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-07T03:18:47.208275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 became follower at term 1"} {"level":"info","ts":"2026-05-07T03:18:47.208346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 switched to configuration voters=(11228840739084368625)"} {"level":"warn","ts":"2026-05-07T03:18:47.216209Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T03:18:47.22237Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T03:18:47.227775Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-07T03:18:47.237326Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9bd4db28c48efaf1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-07T03:18:47.237675Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9bd4db28c48efaf1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-07T03:18:47.237823Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T03:18:47.237866Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T03:18:47.237875Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T03:18:47.241706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 switched to configuration voters=(11228840739084368625)"} {"level":"info","ts":"2026-05-07T03:18:47.2421Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b621f59192c78404","local-member-id":"9bd4db28c48efaf1","added-peer-id":"9bd4db28c48efaf1","added-peer-peer-urls":["https://199.19.213.25:2380"]} {"level":"info","ts":"2026-05-07T03:18:47.242911Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-07T03:18:47.242992Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.25:2380"} {"level":"info","ts":"2026-05-07T03:18:47.243023Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.25:2380"} {"level":"info","ts":"2026-05-07T03:18:47.243183Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9bd4db28c48efaf1","initial-advertise-peer-urls":["https://199.19.213.25:2380"],"listen-peer-urls":["https://199.19.213.25:2380"],"advertise-client-urls":["https://199.19.213.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-07T03:18:47.243224Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T03:18:47.309139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T03:18:47.309195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T03:18:47.309229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 received MsgPreVoteResp from 9bd4db28c48efaf1 at term 1"} {"level":"info","ts":"2026-05-07T03:18:47.309249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 became candidate at term 2"} {"level":"info","ts":"2026-05-07T03:18:47.309266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 received MsgVoteResp from 9bd4db28c48efaf1 at term 2"} {"level":"info","ts":"2026-05-07T03:18:47.309287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd4db28c48efaf1 became leader at term 2"} {"level":"info","ts":"2026-05-07T03:18:47.3093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9bd4db28c48efaf1 elected leader 9bd4db28c48efaf1 at term 2"} {"level":"info","ts":"2026-05-07T03:18:47.315287Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:18:47.319455Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9bd4db28c48efaf1","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.25:2379]}","request-path":"/0/members/9bd4db28c48efaf1/attributes","cluster-id":"b621f59192c78404","publish-timeout":"7s"} {"level":"info","ts":"2026-05-07T03:18:47.319474Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T03:18:47.319927Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b621f59192c78404","local-member-id":"9bd4db28c48efaf1","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:18:47.320106Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:18:47.32018Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T03:18:47.320212Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:18:47.320389Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T03:18:47.320415Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T03:18:47.322387Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-07T03:18:47.32447Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.25:2379"} {"level":"info","ts":"2026-05-07T03:18:49.174511Z","caller":"traceutil/trace.go:171","msg":"trace[983702305] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"124.37247ms","start":"2026-05-07T03:18:49.050125Z","end":"2026-05-07T03:18:49.174498Z","steps":["trace[983702305] 'process raft request' (duration: 124.256407ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:18:49.620376Z","caller":"traceutil/trace.go:171","msg":"trace[1747871589] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"570.042877ms","start":"2026-05-07T03:18:49.050274Z","end":"2026-05-07T03:18:49.620317Z","steps":["trace[1747871589] 'process raft request' (duration: 569.731699ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:18:49.620473Z","caller":"traceutil/trace.go:171","msg":"trace[85819144] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"569.496242ms","start":"2026-05-07T03:18:49.050954Z","end":"2026-05-07T03:18:49.62045Z","steps":["trace[85819144] 'read index received' (duration: 123.480025ms)","trace[85819144] 'applied index is now lower than readState.Index' (duration: 446.015227ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:18:49.620716Z","caller":"traceutil/trace.go:171","msg":"trace[396580464] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"492.668531ms","start":"2026-05-07T03:18:49.12804Z","end":"2026-05-07T03:18:49.620709Z","steps":["trace[396580464] 'process raft request' (duration: 492.23824ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:18:49.620855Z","caller":"traceutil/trace.go:171","msg":"trace[693883845] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"492.717062ms","start":"2026-05-07T03:18:49.128124Z","end":"2026-05-07T03:18:49.620841Z","steps":["trace[693883845] 'process raft request' (duration: 492.209289ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:18:49.620961Z","caller":"traceutil/trace.go:171","msg":"trace[378267348] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"480.711422ms","start":"2026-05-07T03:18:49.140235Z","end":"2026-05-07T03:18:49.620946Z","steps":["trace[378267348] 'process raft request' (duration: 480.145788ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:18:49.620989Z","caller":"traceutil/trace.go:171","msg":"trace[574575952] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"476.185246ms","start":"2026-05-07T03:18:49.144752Z","end":"2026-05-07T03:18:49.620937Z","steps":["trace[574575952] 'process raft request' (duration: 475.669093ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:18:49.620619Z","caller":"traceutil/trace.go:171","msg":"trace[972249757] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"492.477357ms","start":"2026-05-07T03:18:49.128108Z","end":"2026-05-07T03:18:49.620586Z","steps":["trace[972249757] 'process raft request' (duration: 492.20366ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:18:49.620689Z","caller":"traceutil/trace.go:171","msg":"trace[1845993065] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"492.651731ms","start":"2026-05-07T03:18:49.128028Z","end":"2026-05-07T03:18:49.62068Z","steps":["trace[1845993065] 'process raft request' (duration: 492.22837ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:18:49.620691Z","caller":"traceutil/trace.go:171","msg":"trace[742498113] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"492.688762ms","start":"2026-05-07T03:18:49.127978Z","end":"2026-05-07T03:18:49.620667Z","steps":["trace[742498113] 'process raft request' (duration: 492.249111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:49.621309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.333745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"warn","ts":"2026-05-07T03:18:49.621374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.317485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-07T03:18:49.622133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:49.128119Z","time spent":"492.811465ms","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:18:49.622309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:49.144742Z","time spent":"476.402331ms","remote":"127.0.0.1:44184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:18:49.622414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:49.128098Z","time spent":"493.063392ms","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:18:49.620766Z","caller":"traceutil/trace.go:171","msg":"trace[8332606] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"492.766174ms","start":"2026-05-07T03:18:49.12797Z","end":"2026-05-07T03:18:49.620736Z","steps":["trace[8332606] 'process raft request' (duration: 492.21861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:49.622623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:49.127958Z","time spent":"494.635092ms","remote":"127.0.0.1:44340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:18:49.622243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:49.128021Z","time spent":"492.790045ms","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:18:49.622182Z","caller":"traceutil/trace.go:171","msg":"trace[1093350500] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"523.165378ms","start":"2026-05-07T03:18:49.099002Z","end":"2026-05-07T03:18:49.622167Z","steps":["trace[1093350500] 'agreement among raft nodes before linearized reading' (duration: 522.309655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:49.622893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:49.098991Z","time spent":"523.875436ms","remote":"127.0.0.1:44046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-07T03:18:49.622996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:49.128022Z","time spent":"493.152964ms","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:18:49.623279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:49.127948Z","time spent":"493.235746ms","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:18:49.621481Z","caller":"traceutil/trace.go:171","msg":"trace[1484174607] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"570.484438ms","start":"2026-05-07T03:18:49.050937Z","end":"2026-05-07T03:18:49.621422Z","steps":["trace[1484174607] 'agreement among raft nodes before linearized reading' (duration: 570.192281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:49.623404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:49.050931Z","time spent":"572.461059ms","remote":"127.0.0.1:44104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3194,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-07T03:18:49.62157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.753384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T03:18:49.623668Z","caller":"traceutil/trace.go:171","msg":"trace[1410703359] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"444.745285ms","start":"2026-05-07T03:18:49.178768Z","end":"2026-05-07T03:18:49.623513Z","steps":["trace[1410703359] 'agreement among raft nodes before linearized reading' (duration: 442.771113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:49.621524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.953169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T03:18:49.623741Z","caller":"traceutil/trace.go:171","msg":"trace[369733232] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"495.219008ms","start":"2026-05-07T03:18:49.1285Z","end":"2026-05-07T03:18:49.62372Z","steps":["trace[369733232] 'agreement among raft nodes before linearized reading' (duration: 492.857757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:49.623794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:49.12849Z","time spent":"495.290999ms","remote":"127.0.0.1:44012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-05-07T03:18:49.621696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.12242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T03:18:49.623985Z","caller":"traceutil/trace.go:171","msg":"trace[707603086] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"522.425628ms","start":"2026-05-07T03:18:49.101549Z","end":"2026-05-07T03:18:49.623974Z","steps":["trace[707603086] 'agreement among raft nodes before linearized reading' (duration: 520.12174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:49.624027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:49.101541Z","time spent":"522.475419ms","remote":"127.0.0.1:44046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-07T03:18:49.621736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.541616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.25\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-07T03:18:49.623982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:49.178748Z","time spent":"445.214677ms","remote":"127.0.0.1:44046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-07T03:18:49.624106Z","caller":"traceutil/trace.go:171","msg":"trace[96875786] range","detail":"{range_begin:/registry/masterleases/199.19.213.25; range_end:; response_count:0; response_revision:11; }","duration":"497.918356ms","start":"2026-05-07T03:18:49.126174Z","end":"2026-05-07T03:18:49.624092Z","steps":["trace[96875786] 'agreement among raft nodes before linearized reading' (duration: 495.537776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:49.624317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:49.126169Z","time spent":"498.135132ms","remote":"127.0.0.1:43954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.19.213.25\" "} {"level":"warn","ts":"2026-05-07T03:18:49.621752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.861695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T03:18:49.624646Z","caller":"traceutil/trace.go:171","msg":"trace[1776987031] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"493.74942ms","start":"2026-05-07T03:18:49.130882Z","end":"2026-05-07T03:18:49.624631Z","steps":["trace[1776987031] 'agreement among raft nodes before linearized reading' (duration: 490.822524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:49.624726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:49.130839Z","time spent":"493.836942ms","remote":"127.0.0.1:44046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-07T03:18:49.621767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.46232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T03:18:49.624922Z","caller":"traceutil/trace.go:171","msg":"trace[2005606676] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"525.626082ms","start":"2026-05-07T03:18:49.099283Z","end":"2026-05-07T03:18:49.624909Z","steps":["trace[2005606676] 'agreement among raft nodes before linearized reading' (duration: 522.417109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:49.624964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:49.099249Z","time spent":"525.702404ms","remote":"127.0.0.1:44046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-07T03:18:49.622283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:49.140225Z","time spent":"480.786144ms","remote":"127.0.0.1:44104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:18:49.624412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:49.050261Z","time spent":"570.228211ms","remote":"127.0.0.1:43998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:18:54.849333Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18082407703617907349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:18:55.350092Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18082407703617907349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:18:55.851028Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18082407703617907349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:18:56.352077Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18082407703617907349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:18:56.85291Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18082407703617907349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:18:57.368498Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18082407703617907349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:18:57.869313Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18082407703617907349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:18:58.314481Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.989154657s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-07T03:18:59.128448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.566132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:18:59.128544Z","caller":"traceutil/trace.go:171","msg":"trace[1949094514] linearizableReadLoop","detail":"{readStateIndex:294; appliedIndex:293; }","duration":"4.779797267s","start":"2026-05-07T03:18:54.348731Z","end":"2026-05-07T03:18:59.128528Z","steps":["trace[1949094514] 'read index received' (duration: 3.965917776s)","trace[1949094514] 'applied index is now lower than readState.Index' (duration: 813.878351ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:18:59.12866Z","caller":"traceutil/trace.go:171","msg":"trace[333675478] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"4.803467858s","start":"2026-05-07T03:18:54.325181Z","end":"2026-05-07T03:18:59.128649Z","steps":["trace[333675478] 'process raft request' (duration: 3.989491055s)","trace[333675478] 'compare' (duration: 812.869445ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:18:59.128723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:54.32515Z","time spent":"4.803541871s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:18:59.128856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.622846917s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T03:18:59.128948Z","caller":"traceutil/trace.go:171","msg":"trace[512017312] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:284; }","duration":"3.622994601s","start":"2026-05-07T03:18:55.505935Z","end":"2026-05-07T03:18:59.12893Z","steps":["trace[512017312] 'agreement among raft nodes before linearized reading' (duration: 3.622823687s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:59.128987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:55.505912Z","time spent":"3.623065163s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T03:18:59.129116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.780391032s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:18:59.129151Z","caller":"traceutil/trace.go:171","msg":"trace[822319497] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:0; response_revision:284; }","duration":"4.780425063s","start":"2026-05-07T03:18:54.348716Z","end":"2026-05-07T03:18:59.129141Z","steps":["trace[822319497] 'agreement among raft nodes before linearized reading' (duration: 4.780373842s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:59.129177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:54.348667Z","time spent":"4.780502235s","remote":"127.0.0.1:44142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-05-07T03:18:59.129317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139964253s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"warn","ts":"2026-05-07T03:18:59.129351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.313319535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T03:18:59.129366Z","caller":"traceutil/trace.go:171","msg":"trace[1360327351] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:284; }","duration":"1.140066466s","start":"2026-05-07T03:18:57.989288Z","end":"2026-05-07T03:18:59.129354Z","steps":["trace[1360327351] 'agreement among raft nodes before linearized reading' (duration: 1.139966283s)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:18:59.12939Z","caller":"traceutil/trace.go:171","msg":"trace[439180288] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:284; }","duration":"3.313388717s","start":"2026-05-07T03:18:55.815984Z","end":"2026-05-07T03:18:59.129373Z","steps":["trace[439180288] 'agreement among raft nodes before linearized reading' (duration: 3.313309115s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:59.129403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:57.989263Z","time spent":"1.140131718s","remote":"127.0.0.1:44120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6985,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-05-07T03:18:59.129419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:55.815963Z","time spent":"3.313448828s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T03:18:59.129393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.771544ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:18:59.129458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.553076176s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T03:18:59.129535Z","caller":"traceutil/trace.go:171","msg":"trace[549556115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:284; }","duration":"3.553181559s","start":"2026-05-07T03:18:55.576331Z","end":"2026-05-07T03:18:59.129513Z","steps":["trace[549556115] 'agreement among raft nodes before linearized reading' (duration: 3.553065846s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:18:59.12959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:55.576313Z","time spent":"3.553264051s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T03:18:59.129487Z","caller":"traceutil/trace.go:171","msg":"trace[1431168826] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:284; }","duration":"579.855286ms","start":"2026-05-07T03:18:58.549609Z","end":"2026-05-07T03:18:59.129464Z","steps":["trace[1431168826] 'agreement among raft nodes before linearized reading' (duration: 579.739773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:00.538437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144024075s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:19:00.538538Z","caller":"traceutil/trace.go:171","msg":"trace[1952349243] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"1.404675377s","start":"2026-05-07T03:18:59.133845Z","end":"2026-05-07T03:19:00.53852Z","steps":["trace[1952349243] 'process raft request' (duration: 260.486619ms)","trace[1952349243] 'compare' (duration: 1.143934492s)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:00.53883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:59.133822Z","time spent":"1.404745239s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:00.792039Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.397622646s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T03:19:00.815173Z","caller":"traceutil/trace.go:171","msg":"trace[112877757] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"1.680830631s","start":"2026-05-07T03:18:59.134307Z","end":"2026-05-07T03:19:00.815138Z","steps":["trace[112877757] 'process raft request' (duration: 1.680681617s)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:00.815242Z","caller":"traceutil/trace.go:171","msg":"trace[2088028339] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"1.680950035s","start":"2026-05-07T03:18:59.134263Z","end":"2026-05-07T03:19:00.815213Z","steps":["trace[2088028339] 'process raft request' (duration: 1.658034433s)","trace[2088028339] 'compare' (duration: 22.586474ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:00.815376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:59.134302Z","time spent":"1.680957164s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:00.815403Z","caller":"traceutil/trace.go:171","msg":"trace[166859684] linearizableReadLoop","detail":"{readStateIndex:299; appliedIndex:294; }","duration":"1.462230824s","start":"2026-05-07T03:18:59.353152Z","end":"2026-05-07T03:19:00.815383Z","steps":["trace[166859684] 'read index received' (duration: 41.253336ms)","trace[166859684] 'applied index is now lower than readState.Index' (duration: 1.420976128s)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:00.815446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:59.134254Z","time spent":"1.681041787s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:00.815441Z","caller":"traceutil/trace.go:171","msg":"trace[10735453] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"1.678937472s","start":"2026-05-07T03:18:59.13645Z","end":"2026-05-07T03:19:00.815387Z","steps":["trace[10735453] 'process raft request' (duration: 1.67883464s)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:00.815512Z","caller":"traceutil/trace.go:171","msg":"trace[19754797] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"1.679926237s","start":"2026-05-07T03:18:59.135571Z","end":"2026-05-07T03:19:00.815498Z","steps":["trace[19754797] 'process raft request' (duration: 1.679445805s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:00.815616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:59.135554Z","time spent":"1.68002956s","remote":"127.0.0.1:44142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:19:00.81574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:59.136438Z","time spent":"1.679131527s","remote":"127.0.0.1:44120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:00.815984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.462577494s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:00.816094Z","caller":"traceutil/trace.go:171","msg":"trace[1331540232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:289; }","duration":"1.462894093s","start":"2026-05-07T03:18:59.353136Z","end":"2026-05-07T03:19:00.81603Z","steps":["trace[1331540232] 'agreement among raft nodes before linearized reading' (duration: 1.462347318s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:00.816128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:18:59.353046Z","time spent":"1.463074387s","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T03:19:00.850292Z","caller":"traceutil/trace.go:171","msg":"trace[1441174148] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"791.312464ms","start":"2026-05-07T03:19:00.058955Z","end":"2026-05-07T03:19:00.850267Z","steps":["trace[1441174148] 'process raft request' (duration: 791.167611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:00.850421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:00.058936Z","time spent":"791.415607ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:01.031462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.930501ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:19:01.031636Z","caller":"traceutil/trace.go:171","msg":"trace[274705808] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"176.763897ms","start":"2026-05-07T03:19:00.854846Z","end":"2026-05-07T03:19:01.03161Z","steps":["trace[274705808] 'process raft request' (duration: 71.56156ms)","trace[274705808] 'compare' (duration: 104.753996ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:01.085415Z","caller":"traceutil/trace.go:171","msg":"trace[1454763930] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"228.856163ms","start":"2026-05-07T03:19:00.856513Z","end":"2026-05-07T03:19:01.085369Z","steps":["trace[1454763930] 'process raft request' (duration: 228.498074ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:01.08559Z","caller":"traceutil/trace.go:171","msg":"trace[94017478] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"227.537229ms","start":"2026-05-07T03:19:00.858013Z","end":"2026-05-07T03:19:01.08555Z","steps":["trace[94017478] 'process raft request' (duration: 227.19468ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:01.336699Z","caller":"traceutil/trace.go:171","msg":"trace[2146638016] linearizableReadLoop","detail":"{readStateIndex:305; appliedIndex:304; }","duration":"231.532723ms","start":"2026-05-07T03:19:01.105121Z","end":"2026-05-07T03:19:01.336654Z","steps":["trace[2146638016] 'read index received' (duration: 187.329641ms)","trace[2146638016] 'applied index is now lower than readState.Index' (duration: 44.201462ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:01.336884Z","caller":"traceutil/trace.go:171","msg":"trace[1630576825] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"241.130491ms","start":"2026-05-07T03:19:01.095736Z","end":"2026-05-07T03:19:01.336866Z","steps":["trace[1630576825] 'process raft request' (duration: 196.751345ms)","trace[1630576825] 'compare' (duration: 44.023107ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:01.337139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.004045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:01.337241Z","caller":"traceutil/trace.go:171","msg":"trace[2036947302] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:0; response_revision:294; }","duration":"232.146769ms","start":"2026-05-07T03:19:01.105062Z","end":"2026-05-07T03:19:01.337208Z","steps":["trace[2036947302] 'agreement among raft nodes before linearized reading' (duration: 231.993075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:01.337415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.995411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:19:01.337645Z","caller":"traceutil/trace.go:171","msg":"trace[1001557575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:294; }","duration":"204.32205ms","start":"2026-05-07T03:19:01.133155Z","end":"2026-05-07T03:19:01.337477Z","steps":["trace[1001557575] 'agreement among raft nodes before linearized reading' (duration: 203.820776ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:07.183957Z","caller":"traceutil/trace.go:171","msg":"trace[1802071886] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"330.806182ms","start":"2026-05-07T03:19:06.853127Z","end":"2026-05-07T03:19:07.183933Z","steps":["trace[1802071886] 'process raft request' (duration: 330.672789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:07.184274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:06.853095Z","time spent":"331.028858ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:11.362343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.037111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-07T03:19:11.362452Z","caller":"traceutil/trace.go:171","msg":"trace[1738180775] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:375; }","duration":"164.161695ms","start":"2026-05-07T03:19:11.198258Z","end":"2026-05-07T03:19:11.36242Z","steps":["trace[1738180775] 'range keys from in-memory index tree' (duration: 163.906808ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:15.119327Z","caller":"traceutil/trace.go:171","msg":"trace[872218165] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"138.77153ms","start":"2026-05-07T03:19:14.980534Z","end":"2026-05-07T03:19:15.119305Z","steps":["trace[872218165] 'process raft request' (duration: 138.632506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:15.799357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.235421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:19:15.799441Z","caller":"traceutil/trace.go:171","msg":"trace[767560718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:385; }","duration":"224.382755ms","start":"2026-05-07T03:19:15.575038Z","end":"2026-05-07T03:19:15.799421Z","steps":["trace[767560718] 'range keys from in-memory index tree' (duration: 224.128298ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:16.896342Z","caller":"traceutil/trace.go:171","msg":"trace[596362845] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"148.826311ms","start":"2026-05-07T03:19:16.747488Z","end":"2026-05-07T03:19:16.896314Z","steps":["trace[596362845] 'process raft request' (duration: 148.584854ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:17.42072Z","caller":"traceutil/trace.go:171","msg":"trace[1064911371] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"219.543862ms","start":"2026-05-07T03:19:17.201152Z","end":"2026-05-07T03:19:17.420696Z","steps":["trace[1064911371] 'process raft request' (duration: 218.788043ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:19.456249Z","caller":"traceutil/trace.go:171","msg":"trace[384547502] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"105.982662ms","start":"2026-05-07T03:19:19.350225Z","end":"2026-05-07T03:19:19.456208Z","steps":["trace[384547502] 'process raft request' (duration: 16.727603ms)","trace[384547502] 'compare' (duration: 88.971192ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:19.68707Z","caller":"traceutil/trace.go:171","msg":"trace[2113775348] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"222.52582ms","start":"2026-05-07T03:19:19.464509Z","end":"2026-05-07T03:19:19.687035Z","steps":["trace[2113775348] 'process raft request' (duration: 127.066729ms)","trace[2113775348] 'compare' (duration: 94.915117ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:20.500141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.117372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:19:20.500274Z","caller":"traceutil/trace.go:171","msg":"trace[1075870502] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:439; }","duration":"679.028214ms","start":"2026-05-07T03:19:19.821227Z","end":"2026-05-07T03:19:20.500255Z","steps":["trace[1075870502] 'read index received' (duration: 374.681447ms)","trace[1075870502] 'applied index is now lower than readState.Index' (duration: 304.344857ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:20.500301Z","caller":"traceutil/trace.go:171","msg":"trace[1459324517] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"783.55068ms","start":"2026-05-07T03:19:19.716719Z","end":"2026-05-07T03:19:20.50027Z","steps":["trace[1459324517] 'process raft request' (duration: 479.168641ms)","trace[1459324517] 'compare' (duration: 303.906626ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:20.500428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.208758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:19:20.500459Z","caller":"traceutil/trace.go:171","msg":"trace[293985867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:424; }","duration":"679.288651ms","start":"2026-05-07T03:19:19.821158Z","end":"2026-05-07T03:19:20.500447Z","steps":["trace[293985867] 'agreement among raft nodes before linearized reading' (duration: 679.197099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:20.50048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:19.716701Z","time spent":"783.671422ms","remote":"127.0.0.1:44352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:20.500495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:19.821134Z","time spent":"679.352842ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T03:19:20.500713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.978584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:20.500742Z","caller":"traceutil/trace.go:171","msg":"trace[1902481719] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:424; }","duration":"577.045115ms","start":"2026-05-07T03:19:19.923691Z","end":"2026-05-07T03:19:20.500736Z","steps":["trace[1902481719] 'agreement among raft nodes before linearized reading' (duration: 576.997534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:20.500761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:19.923673Z","time spent":"577.084356ms","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:19:20.899248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.692478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:19:20.899568Z","caller":"traceutil/trace.go:171","msg":"trace[45488019] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"394.91068ms","start":"2026-05-07T03:19:20.504629Z","end":"2026-05-07T03:19:20.89954Z","steps":["trace[45488019] 'process raft request' (duration: 114.840602ms)","trace[45488019] 'compare' (duration: 279.561124ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:20.899739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:20.504607Z","time spent":"395.029724ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:23.189608Z","caller":"traceutil/trace.go:171","msg":"trace[1894982921] linearizableReadLoop","detail":"{readStateIndex:448; appliedIndex:447; }","duration":"211.567896ms","start":"2026-05-07T03:19:22.978015Z","end":"2026-05-07T03:19:23.189583Z","steps":["trace[1894982921] 'read index received' (duration: 211.33763ms)","trace[1894982921] 'applied index is now lower than readState.Index' (duration: 229.036µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:23.189684Z","caller":"traceutil/trace.go:171","msg":"trace[2008630727] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"281.847366ms","start":"2026-05-07T03:19:22.907805Z","end":"2026-05-07T03:19:23.189653Z","steps":["trace[2008630727] 'process raft request' (duration: 281.6319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:23.189816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.74906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T03:19:23.18993Z","caller":"traceutil/trace.go:171","msg":"trace[1321605829] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:431; }","duration":"211.892624ms","start":"2026-05-07T03:19:22.978009Z","end":"2026-05-07T03:19:23.189902Z","steps":["trace[1321605829] 'agreement among raft nodes before linearized reading' (duration: 211.668978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:23.352896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.055494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:19:23.353023Z","caller":"traceutil/trace.go:171","msg":"trace[1586550574] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"159.553749ms","start":"2026-05-07T03:19:23.193448Z","end":"2026-05-07T03:19:23.353002Z","steps":["trace[1586550574] 'process raft request' (duration: 38.260159ms)","trace[1586550574] 'compare' (duration: 120.92396ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:25.617158Z","caller":"traceutil/trace.go:171","msg":"trace[1515567473] linearizableReadLoop","detail":"{readStateIndex:454; appliedIndex:453; }","duration":"170.967146ms","start":"2026-05-07T03:19:25.446162Z","end":"2026-05-07T03:19:25.617129Z","steps":["trace[1515567473] 'read index received' (duration: 170.72905ms)","trace[1515567473] 'applied index is now lower than readState.Index' (duration: 236.386µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:25.617197Z","caller":"traceutil/trace.go:171","msg":"trace[826843927] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"256.374938ms","start":"2026-05-07T03:19:25.360797Z","end":"2026-05-07T03:19:25.617172Z","steps":["trace[826843927] 'process raft request' (duration: 256.189093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:25.617328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.134951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T03:19:25.617387Z","caller":"traceutil/trace.go:171","msg":"trace[392049569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:437; }","duration":"171.276184ms","start":"2026-05-07T03:19:25.446094Z","end":"2026-05-07T03:19:25.61737Z","steps":["trace[392049569] 'agreement among raft nodes before linearized reading' (duration: 171.146791ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:25.77392Z","caller":"traceutil/trace.go:171","msg":"trace[1942127933] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"152.804277ms","start":"2026-05-07T03:19:25.62108Z","end":"2026-05-07T03:19:25.773884Z","steps":["trace[1942127933] 'process raft request' (duration: 54.343927ms)","trace[1942127933] 'compare' (duration: 98.317886ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:25.991414Z","caller":"traceutil/trace.go:171","msg":"trace[1743419633] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"148.352941ms","start":"2026-05-07T03:19:25.843032Z","end":"2026-05-07T03:19:25.991385Z","steps":["trace[1743419633] 'process raft request' (duration: 52.650714ms)","trace[1743419633] 'compare' (duration: 95.507662ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:33.765814Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18082407703617907787,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:19:33.897881Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.576995261s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T03:19:33.898281Z","caller":"traceutil/trace.go:171","msg":"trace[2069498393] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"1.577553876s","start":"2026-05-07T03:19:32.320703Z","end":"2026-05-07T03:19:33.898257Z","steps":["trace[2069498393] 'process raft request' (duration: 1.577430932s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:33.898399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:32.320674Z","time spent":"1.577662338s","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:19:34.35598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.139726ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:34.356326Z","caller":"traceutil/trace.go:171","msg":"trace[269781471] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:488; }","duration":"124.496235ms","start":"2026-05-07T03:19:34.231815Z","end":"2026-05-07T03:19:34.356311Z","steps":["trace[269781471] 'range keys from in-memory index tree' (duration: 124.126605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:35.493291Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.594622752s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T03:19:35.493968Z","caller":"traceutil/trace.go:171","msg":"trace[1087013841] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"3.154446908s","start":"2026-05-07T03:19:32.339495Z","end":"2026-05-07T03:19:35.493942Z","steps":["trace[1087013841] 'process raft request' (duration: 3.154227202s)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:35.494119Z","caller":"traceutil/trace.go:171","msg":"trace[1015115515] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"3.169365604s","start":"2026-05-07T03:19:32.324732Z","end":"2026-05-07T03:19:35.494097Z","steps":["trace[1015115515] 'process raft request' (duration: 3.168850211s)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:35.494171Z","caller":"traceutil/trace.go:171","msg":"trace[969679410] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"3.150231549s","start":"2026-05-07T03:19:32.343905Z","end":"2026-05-07T03:19:35.494136Z","steps":["trace[969679410] 'process raft request' (duration: 3.149968192s)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:35.494175Z","caller":"traceutil/trace.go:171","msg":"trace[272386011] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"3.153914974s","start":"2026-05-07T03:19:32.340243Z","end":"2026-05-07T03:19:35.494158Z","steps":["trace[272386011] 'process raft request' (duration: 3.153524244s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:35.4942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:32.324705Z","time spent":"3.169458437s","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:19:35.494289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:32.343892Z","time spent":"3.150339821s","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:19:35.494288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:32.340225Z","time spent":"3.154008977s","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:19:35.494323Z","caller":"traceutil/trace.go:171","msg":"trace[1426071648] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"3.150665341s","start":"2026-05-07T03:19:32.343647Z","end":"2026-05-07T03:19:35.494313Z","steps":["trace[1426071648] 'process raft request' (duration: 3.150152966s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:35.494363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:32.343637Z","time spent":"3.150706272s","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:19:35.494415Z","caller":"traceutil/trace.go:171","msg":"trace[529446594] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"3.144340587s","start":"2026-05-07T03:19:32.350062Z","end":"2026-05-07T03:19:35.494403Z","steps":["trace[529446594] 'process raft request' (duration: 3.143843753s)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:35.494101Z","caller":"traceutil/trace.go:171","msg":"trace[1648251980] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"3.150379753s","start":"2026-05-07T03:19:32.343697Z","end":"2026-05-07T03:19:35.494077Z","steps":["trace[1648251980] 'process raft request' (duration: 3.150138056s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:35.494456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:32.350053Z","time spent":"3.144382769s","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:19:35.494493Z","caller":"traceutil/trace.go:171","msg":"trace[1576913347] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"3.135614741s","start":"2026-05-07T03:19:32.358868Z","end":"2026-05-07T03:19:35.494483Z","steps":["trace[1576913347] 'process raft request' (duration: 3.135087896s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:35.494531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:32.358854Z","time spent":"3.135660273s","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:19:35.494082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:32.339478Z","time spent":"3.154541281s","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:19:35.494944Z","caller":"traceutil/trace.go:171","msg":"trace[1746456123] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"3.122361358s","start":"2026-05-07T03:19:32.372563Z","end":"2026-05-07T03:19:35.494924Z","steps":["trace[1746456123] 'process raft request' (duration: 3.121440874s)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:35.49499Z","caller":"traceutil/trace.go:171","msg":"trace[1985342252] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"3.111514097s","start":"2026-05-07T03:19:32.383464Z","end":"2026-05-07T03:19:35.494978Z","steps":["trace[1985342252] 'process raft request' (duration: 3.111354653s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:35.495013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:32.372544Z","time spent":"3.12243704s","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:19:35.495039Z","caller":"traceutil/trace.go:171","msg":"trace[638083052] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:506; }","duration":"2.230182587s","start":"2026-05-07T03:19:33.264843Z","end":"2026-05-07T03:19:35.495025Z","steps":["trace[638083052] 'read index received' (duration: 633.305896ms)","trace[638083052] 'applied index is now lower than readState.Index' (duration: 1.596875871s)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:35.495048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:32.383444Z","time spent":"3.111575579s","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:19:35.495097Z","caller":"traceutil/trace.go:171","msg":"trace[1483564029] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"1.636460666s","start":"2026-05-07T03:19:33.858623Z","end":"2026-05-07T03:19:35.495084Z","steps":["trace[1483564029] 'process raft request' (duration: 1.636351434s)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:35.495103Z","caller":"traceutil/trace.go:171","msg":"trace[144124116] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"2.966529041s","start":"2026-05-07T03:19:32.528565Z","end":"2026-05-07T03:19:35.495094Z","steps":["trace[144124116] 'process raft request' (duration: 2.966353656s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:35.495138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:32.343692Z","time spent":"3.150748723s","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:19:35.495142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.230299019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-07T03:19:35.495147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:32.528545Z","time spent":"2.966577572s","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:19:35.495178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:33.858588Z","time spent":"1.636533588s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:35.495177Z","caller":"traceutil/trace.go:171","msg":"trace[2137185095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:500; }","duration":"2.230382521s","start":"2026-05-07T03:19:33.264785Z","end":"2026-05-07T03:19:35.495168Z","steps":["trace[2137185095] 'agreement among raft nodes before linearized reading' (duration: 2.23031358s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:35.495223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:33.264759Z","time spent":"2.230455274s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T03:19:35.923242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000328274s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-07T03:19:35.923363Z","caller":"traceutil/trace.go:171","msg":"trace[1620443597] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000496998s","start":"2026-05-07T03:19:33.922843Z","end":"2026-05-07T03:19:35.92334Z","steps":["trace[1620443597] 'agreement among raft nodes before linearized reading' (duration: 2.000351964s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:35.923413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:33.922831Z","time spent":"2.00057154s","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:19:35.995797Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18082407703617907804,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:19:36.496484Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18082407703617907804,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:19:36.839856Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.232042561s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T03:19:36.843497Z","caller":"traceutil/trace.go:171","msg":"trace[1027188264] transaction","detail":"{read_only:false; number_of_response:1; response_revision:502; }","duration":"2.935870402s","start":"2026-05-07T03:19:33.907604Z","end":"2026-05-07T03:19:36.843474Z","steps":["trace[1027188264] 'process raft request' (duration: 2.93578698s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:36.84366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:33.907587Z","time spent":"2.935975205s","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7367,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:36.843858Z","caller":"traceutil/trace.go:171","msg":"trace[1054099756] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"2.937043683s","start":"2026-05-07T03:19:33.906803Z","end":"2026-05-07T03:19:36.843846Z","steps":["trace[1054099756] 'process raft request' (duration: 2.93653327s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:36.843928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:33.906795Z","time spent":"2.937099844s","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:36.843975Z","caller":"traceutil/trace.go:171","msg":"trace[1062668278] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"2.938429619s","start":"2026-05-07T03:19:33.905503Z","end":"2026-05-07T03:19:36.843933Z","steps":["trace[1062668278] 'process raft request' (duration: 2.934564009s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:36.844128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:33.905481Z","time spent":"2.938565423s","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:19:36.856086Z","caller":"traceutil/trace.go:171","msg":"trace[1724462089] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"1.358975221s","start":"2026-05-07T03:19:35.497079Z","end":"2026-05-07T03:19:36.856054Z","steps":["trace[1724462089] 'process raft request' (duration: 1.358752485s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:36.856244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:35.49705Z","time spent":"1.359128814s","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:19:36.856255Z","caller":"traceutil/trace.go:171","msg":"trace[1990492114] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"1.356384393s","start":"2026-05-07T03:19:35.499736Z","end":"2026-05-07T03:19:36.856121Z","steps":["trace[1990492114] 'process raft request' (duration: 1.35624123s)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:36.856441Z","caller":"traceutil/trace.go:171","msg":"trace[873414966] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"1.344503735s","start":"2026-05-07T03:19:35.511918Z","end":"2026-05-07T03:19:36.856421Z","steps":["trace[873414966] 'process raft request' (duration: 1.344116705s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:36.856528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:35.511901Z","time spent":"1.344588647s","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:19:36.856613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:35.499722Z","time spent":"1.356724432s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:36.857291Z","caller":"traceutil/trace.go:171","msg":"trace[1992148613] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"1.345314595s","start":"2026-05-07T03:19:35.511965Z","end":"2026-05-07T03:19:36.857279Z","steps":["trace[1992148613] 'process raft request' (duration: 1.344417582s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:36.857344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:35.51196Z","time spent":"1.345361787s","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:19:36.857449Z","caller":"traceutil/trace.go:171","msg":"trace[175206057] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"1.345311355s","start":"2026-05-07T03:19:35.51213Z","end":"2026-05-07T03:19:36.857441Z","steps":["trace[175206057] 'process raft request' (duration: 1.345069029s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:36.857488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:35.512126Z","time spent":"1.345344956s","remote":"127.0.0.1:44416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:19:36.857605Z","caller":"traceutil/trace.go:171","msg":"trace[439357163] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"1.345235123s","start":"2026-05-07T03:19:35.512364Z","end":"2026-05-07T03:19:36.857599Z","steps":["trace[439357163] 'process raft request' (duration: 1.344941766s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:36.85765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:35.51235Z","time spent":"1.345272015s","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:36.857791Z","caller":"traceutil/trace.go:171","msg":"trace[1648000890] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"1.345404767s","start":"2026-05-07T03:19:35.512365Z","end":"2026-05-07T03:19:36.85777Z","steps":["trace[1648000890] 'process raft request' (duration: 1.345061399s)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:36.857889Z","caller":"traceutil/trace.go:171","msg":"trace[178444926] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"1.341502597s","start":"2026-05-07T03:19:35.516376Z","end":"2026-05-07T03:19:36.857878Z","steps":["trace[178444926] 'process raft request' (duration: 1.341173299s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:36.857946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:35.516343Z","time spent":"1.341574409s","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:36.858013Z","caller":"traceutil/trace.go:171","msg":"trace[2007367960] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"1.340724656s","start":"2026-05-07T03:19:35.517281Z","end":"2026-05-07T03:19:36.858006Z","steps":["trace[2007367960] 'process raft request' (duration: 1.34048948s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:36.858053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:35.517271Z","time spent":"1.340762607s","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:36.858146Z","caller":"traceutil/trace.go:171","msg":"trace[1741004236] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"1.30341354s","start":"2026-05-07T03:19:35.554717Z","end":"2026-05-07T03:19:36.85813Z","steps":["trace[1741004236] 'process raft request' (duration: 1.303211484s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:36.858132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:35.51234Z","time spent":"1.345556972s","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:36.858259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:35.554685Z","time spent":"1.303488662s","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:19:36.892395Z","caller":"traceutil/trace.go:171","msg":"trace[824248141] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:519; }","duration":"1.397303373s","start":"2026-05-07T03:19:35.495076Z","end":"2026-05-07T03:19:36.892379Z","steps":["trace[824248141] 'read index received' (duration: 1.345035768s)","trace[824248141] 'applied index is now lower than readState.Index' (duration: 52.266985ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:36.892503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.871272199s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-07T03:19:36.892523Z","caller":"traceutil/trace.go:171","msg":"trace[2091748564] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:512; }","duration":"2.871345941s","start":"2026-05-07T03:19:34.021172Z","end":"2026-05-07T03:19:36.892518Z","steps":["trace[2091748564] 'agreement among raft nodes before linearized reading' (duration: 2.87128706s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:36.892543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:34.021147Z","time spent":"2.871391753s","remote":"127.0.0.1:44196","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-05-07T03:19:36.892698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.120577828s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-07T03:19:36.892678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.079475384s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T03:19:36.892717Z","caller":"traceutil/trace.go:171","msg":"trace[1475275620] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:514; }","duration":"3.120597298s","start":"2026-05-07T03:19:33.772116Z","end":"2026-05-07T03:19:36.892713Z","steps":["trace[1475275620] 'agreement among raft nodes before linearized reading' (duration: 3.120551187s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:36.892731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:33.772054Z","time spent":"3.12067382s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-07T03:19:36.892736Z","caller":"traceutil/trace.go:171","msg":"trace[1925816174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:513; }","duration":"3.079574377s","start":"2026-05-07T03:19:33.813148Z","end":"2026-05-07T03:19:36.892723Z","steps":["trace[1925816174] 'agreement among raft nodes before linearized reading' (duration: 3.079428543s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:36.892806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:33.813127Z","time spent":"3.079655988s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T03:19:36.908622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.487507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:36.908723Z","caller":"traceutil/trace.go:171","msg":"trace[397189740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"984.62107ms","start":"2026-05-07T03:19:35.924086Z","end":"2026-05-07T03:19:36.908708Z","steps":["trace[397189740] 'agreement among raft nodes before linearized reading' (duration: 984.468346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:36.90876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:35.924069Z","time spent":"984.685292ms","remote":"127.0.0.1:43922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T03:19:37.285823Z","caller":"traceutil/trace.go:171","msg":"trace[146604540] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"118.531621ms","start":"2026-05-07T03:19:37.167261Z","end":"2026-05-07T03:19:37.285793Z","steps":["trace[146604540] 'process raft request' (duration: 105.099923ms)","trace[146604540] 'compare' (duration: 13.283984ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:37.286367Z","caller":"traceutil/trace.go:171","msg":"trace[1791643841] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"112.111465ms","start":"2026-05-07T03:19:37.174243Z","end":"2026-05-07T03:19:37.286354Z","steps":["trace[1791643841] 'process raft request' (duration: 112.074764ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:37.286806Z","caller":"traceutil/trace.go:171","msg":"trace[1770913031] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"112.983547ms","start":"2026-05-07T03:19:37.173789Z","end":"2026-05-07T03:19:37.286773Z","steps":["trace[1770913031] 'process raft request' (duration: 112.365051ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:39.653323Z","caller":"traceutil/trace.go:171","msg":"trace[1678871113] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"135.228885ms","start":"2026-05-07T03:19:39.518067Z","end":"2026-05-07T03:19:39.653296Z","steps":["trace[1678871113] 'process raft request' (duration: 135.095611ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:39.855371Z","caller":"traceutil/trace.go:171","msg":"trace[1269681719] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"197.889997ms","start":"2026-05-07T03:19:39.65745Z","end":"2026-05-07T03:19:39.85534Z","steps":["trace[1269681719] 'process raft request' (duration: 188.72123ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:42.903135Z","caller":"traceutil/trace.go:171","msg":"trace[339923708] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"356.471039ms","start":"2026-05-07T03:19:42.54664Z","end":"2026-05-07T03:19:42.903111Z","steps":["trace[339923708] 'process raft request' (duration: 355.930625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:42.903248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:42.546614Z","time spent":"356.577492ms","remote":"127.0.0.1:44028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:43.297288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.69437ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:19:43.297395Z","caller":"traceutil/trace.go:171","msg":"trace[351640154] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"339.356646ms","start":"2026-05-07T03:19:42.958019Z","end":"2026-05-07T03:19:43.297375Z","steps":["trace[351640154] 'read index received' (duration: 86.453791ms)","trace[351640154] 'applied index is now lower than readState.Index' (duration: 252.901415ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:43.297479Z","caller":"traceutil/trace.go:171","msg":"trace[704628437] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"340.967748ms","start":"2026-05-07T03:19:42.956496Z","end":"2026-05-07T03:19:43.297463Z","steps":["trace[704628437] 'process raft request' (duration: 88.010632ms)","trace[704628437] 'compare' (duration: 252.538136ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:43.297557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:42.95648Z","time spent":"341.03204ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:43.297858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.824858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T03:19:43.297915Z","caller":"traceutil/trace.go:171","msg":"trace[780498058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"339.89498ms","start":"2026-05-07T03:19:42.958005Z","end":"2026-05-07T03:19:43.2979Z","steps":["trace[780498058] 'agreement among raft nodes before linearized reading' (duration: 339.51712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:43.297958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:42.957999Z","time spent":"339.947971ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T03:19:43.299226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.990869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T03:19:43.299303Z","caller":"traceutil/trace.go:171","msg":"trace[1268031190] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:594; }","duration":"341.072341ms","start":"2026-05-07T03:19:42.958212Z","end":"2026-05-07T03:19:43.299284Z","steps":["trace[1268031190] 'agreement among raft nodes before linearized reading' (duration: 340.916117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:43.299346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:42.958205Z","time spent":"341.128472ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-07T03:19:43.299596Z","caller":"traceutil/trace.go:171","msg":"trace[1062893668] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"307.548582ms","start":"2026-05-07T03:19:42.992036Z","end":"2026-05-07T03:19:43.299584Z","steps":["trace[1062893668] 'process raft request' (duration: 307.009638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:43.299717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:42.99201Z","time spent":"307.666665ms","remote":"127.0.0.1:44260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:19:44.644201Z","caller":"traceutil/trace.go:171","msg":"trace[1640591301] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"892.334672ms","start":"2026-05-07T03:19:43.751815Z","end":"2026-05-07T03:19:44.64415Z","steps":["trace[1640591301] 'process raft request' (duration: 889.782455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:44.644354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:43.751796Z","time spent":"892.479816ms","remote":"127.0.0.1:44028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:45.802677Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18082407703617907982,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T03:19:45.836106Z","caller":"traceutil/trace.go:171","msg":"trace[110430947] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"706.449703ms","start":"2026-05-07T03:19:45.129631Z","end":"2026-05-07T03:19:45.836081Z","steps":["trace[110430947] 'process raft request' (duration: 705.745145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:45.836354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:45.129605Z","time spent":"706.610217ms","remote":"127.0.0.1:44028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:46.500511Z","caller":"traceutil/trace.go:171","msg":"trace[1562489794] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:627; }","duration":"1.198773657s","start":"2026-05-07T03:19:45.301714Z","end":"2026-05-07T03:19:46.500488Z","steps":["trace[1562489794] 'read index received' (duration: 533.420139ms)","trace[1562489794] 'applied index is now lower than readState.Index' (duration: 665.352687ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:46.500592Z","caller":"traceutil/trace.go:171","msg":"trace[1870911595] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"952.81359ms","start":"2026-05-07T03:19:45.547742Z","end":"2026-05-07T03:19:46.500556Z","steps":["trace[1870911595] 'process raft request' (duration: 952.608345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:46.500753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.199020983s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T03:19:46.500795Z","caller":"traceutil/trace.go:171","msg":"trace[118499978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:604; }","duration":"1.199079934s","start":"2026-05-07T03:19:45.301705Z","end":"2026-05-07T03:19:46.500785Z","steps":["trace[118499978] 'agreement among raft nodes before linearized reading' (duration: 1.19891946s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:46.500781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:45.547714Z","time spent":"952.971505ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:46.500828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:45.301662Z","time spent":"1.199159146s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T03:19:46.518968Z","caller":"traceutil/trace.go:171","msg":"trace[1677071100] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"611.843391ms","start":"2026-05-07T03:19:45.907093Z","end":"2026-05-07T03:19:46.518936Z","steps":["trace[1677071100] 'process raft request' (duration: 611.665887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:46.519129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:45.907068Z","time spent":"611.998465ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:19:47.921961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000314768s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-07T03:19:47.922042Z","caller":"traceutil/trace.go:171","msg":"trace[1102869198] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000442311s","start":"2026-05-07T03:19:45.921577Z","end":"2026-05-07T03:19:47.92202Z","steps":["trace[1102869198] 'agreement among raft nodes before linearized reading' (duration: 2.000348258s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:47.922083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:45.921559Z","time spent":"2.000516293s","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/07 03:19:47 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-07T03:19:49.922927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000647942s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-07T03:19:49.923092Z","caller":"traceutil/trace.go:171","msg":"trace[1750966155] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000866317s","start":"2026-05-07T03:19:47.922203Z","end":"2026-05-07T03:19:49.923069Z","steps":["trace[1750966155] 'agreement among raft nodes before linearized reading' (duration: 2.000679122s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:49.92317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:47.922182Z","time spent":"2.00097438s","remote":"127.0.0.1:43922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:19:50.368548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998990663s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-07T03:19:50.368619Z","caller":"traceutil/trace.go:171","msg":"trace[1388954646] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999110826s","start":"2026-05-07T03:19:45.369491Z","end":"2026-05-07T03:19:50.368602Z","steps":["trace[1388954646] 'agreement among raft nodes before linearized reading' (duration: 4.999022854s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:50.368649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:45.369474Z","time spent":"4.999169638s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/05/07 03:19:50 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-07T03:19:51.503932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:46.504653Z","time spent":"4.999240777s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/05/07 03:19:51 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-07T03:19:51.68485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.165986132s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-07T03:19:51.685568Z","caller":"traceutil/trace.go:171","msg":"trace[2039410161] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:629; }","duration":"5.184938554s","start":"2026-05-07T03:19:46.500597Z","end":"2026-05-07T03:19:51.685536Z","steps":["trace[2039410161] 'read index received' (duration: 18.223003ms)","trace[2039410161] 'applied index is now lower than readState.Index' (duration: 5.166714051s)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:51.68596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.91853239s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2pc7k\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-07T03:19:51.685994Z","caller":"traceutil/trace.go:171","msg":"trace[1377097329] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2pc7k; range_end:; response_count:1; response_revision:605; }","duration":"5.918609172s","start":"2026-05-07T03:19:45.767375Z","end":"2026-05-07T03:19:51.685984Z","steps":["trace[1377097329] 'agreement among raft nodes before linearized reading' (duration: 5.918191262s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:51.686023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:45.767354Z","time spent":"5.918662883s","remote":"127.0.0.1:44120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-2pc7k\" "} {"level":"warn","ts":"2026-05-07T03:19:51.686101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.344931033s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T03:19:51.686149Z","caller":"traceutil/trace.go:171","msg":"trace[1078809855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"6.345017036s","start":"2026-05-07T03:19:45.341123Z","end":"2026-05-07T03:19:51.68614Z","steps":["trace[1078809855] 'agreement among raft nodes before linearized reading' (duration: 6.344498003s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:51.686409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:45.341102Z","time spent":"6.345298945s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T03:19:51.686655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.164214606s","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-05-07T03:19:51.686686Z","caller":"traceutil/trace.go:171","msg":"trace[1519308522] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:606; }","duration":"5.164274228s","start":"2026-05-07T03:19:46.522404Z","end":"2026-05-07T03:19:51.686678Z","steps":["trace[1519308522] 'agreement among raft nodes before linearized reading' (duration: 5.164198246s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:51.68683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.743676784s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18ad2a560a4f88ee\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-05-07T03:19:51.686858Z","caller":"traceutil/trace.go:171","msg":"trace[133608629] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18ad2a560a4f88ee; range_end:; response_count:1; response_revision:606; }","duration":"1.743734605s","start":"2026-05-07T03:19:49.943115Z","end":"2026-05-07T03:19:51.686849Z","steps":["trace[133608629] 'agreement among raft nodes before linearized reading' (duration: 1.743659103s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:51.686878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:49.943098Z","time spent":"1.743776277s","remote":"127.0.0.1:44000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":798,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18ad2a560a4f88ee\" "} {"level":"warn","ts":"2026-05-07T03:19:51.686856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.445909957s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T03:19:51.68693Z","caller":"traceutil/trace.go:171","msg":"trace[1365339497] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:606; }","duration":"3.446038101s","start":"2026-05-07T03:19:48.240874Z","end":"2026-05-07T03:19:51.686912Z","steps":["trace[1365339497] 'agreement among raft nodes before linearized reading' (duration: 3.445902007s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:51.686967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:48.240855Z","time spent":"3.446104203s","remote":"127.0.0.1:44046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:19:51.687166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001416246s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:51.687198Z","caller":"traceutil/trace.go:171","msg":"trace[1768949610] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:606; }","duration":"2.001478878s","start":"2026-05-07T03:19:49.68571Z","end":"2026-05-07T03:19:51.687189Z","steps":["trace[1768949610] 'agreement among raft nodes before linearized reading' (duration: 2.001427127s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:51.687224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:49.685693Z","time spent":"2.00152383s","remote":"127.0.0.1:44300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:19:51.687296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.738392547s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:51.687389Z","caller":"traceutil/trace.go:171","msg":"trace[1581879283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"1.73851833s","start":"2026-05-07T03:19:49.948838Z","end":"2026-05-07T03:19:51.687357Z","steps":["trace[1581879283] 'agreement among raft nodes before linearized reading' (duration: 1.738256794s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:51.687434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:49.948819Z","time spent":"1.738605812s","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:19:51.686709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:46.522382Z","time spent":"5.16432115s","remote":"127.0.0.1:44142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-05-07T03:19:51.687564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.675229709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:19:51.687595Z","caller":"traceutil/trace.go:171","msg":"trace[1083042078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:606; }","duration":"4.675304921s","start":"2026-05-07T03:19:47.012283Z","end":"2026-05-07T03:19:51.687588Z","steps":["trace[1083042078] 'agreement among raft nodes before linearized reading' (duration: 4.675199318s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:51.687683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:47.012259Z","time spent":"4.675355963s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T03:19:51.687762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.291565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-07T03:19:51.688222Z","caller":"traceutil/trace.go:171","msg":"trace[1508235133] range","detail":"{range_begin:/registry/masterleases/199.19.213.25; range_end:; response_count:1; response_revision:606; }","duration":"836.775418ms","start":"2026-05-07T03:19:50.851433Z","end":"2026-05-07T03:19:51.688209Z","steps":["trace[1508235133] 'agreement among raft nodes before linearized reading' (duration: 836.095311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:51.688314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:50.851418Z","time spent":"836.884281ms","remote":"127.0.0.1:43954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.25\" "} {"level":"info","ts":"2026-05-07T03:19:51.840637Z","caller":"traceutil/trace.go:171","msg":"trace[1814781188] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"149.065506ms","start":"2026-05-07T03:19:51.691545Z","end":"2026-05-07T03:19:51.840611Z","steps":["trace[1814781188] 'read index received' (duration: 108.186826ms)","trace[1814781188] 'applied index is now lower than readState.Index' (duration: 40.87702ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:51.840806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.233941ms","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-05-07T03:19:51.840968Z","caller":"traceutil/trace.go:171","msg":"trace[16992302] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:606; }","duration":"149.326573ms","start":"2026-05-07T03:19:51.691505Z","end":"2026-05-07T03:19:51.840832Z","steps":["trace[16992302] 'agreement among raft nodes before linearized reading' (duration: 149.22718ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:52.045166Z","caller":"traceutil/trace.go:171","msg":"trace[1784345521] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"353.468657ms","start":"2026-05-07T03:19:51.691657Z","end":"2026-05-07T03:19:52.045125Z","steps":["trace[1784345521] 'process raft request' (duration: 337.40279ms)","trace[1784345521] 'compare' (duration: 15.861312ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:52.045331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:51.691606Z","time spent":"353.641801ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:52.045478Z","caller":"traceutil/trace.go:171","msg":"trace[1258343544] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"353.707863ms","start":"2026-05-07T03:19:51.691738Z","end":"2026-05-07T03:19:52.045446Z","steps":["trace[1258343544] 'process raft request' (duration: 353.335723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:52.045787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:51.691721Z","time spent":"353.968979ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:52.04584Z","caller":"traceutil/trace.go:171","msg":"trace[1259886102] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:633; }","duration":"205.122219ms","start":"2026-05-07T03:19:51.840704Z","end":"2026-05-07T03:19:52.045827Z","steps":["trace[1259886102] 'read index received' (duration: 188.370874ms)","trace[1259886102] 'applied index is now lower than readState.Index' (duration: 16.749855ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:52.045908Z","caller":"traceutil/trace.go:171","msg":"trace[252683266] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"349.873782ms","start":"2026-05-07T03:19:51.696025Z","end":"2026-05-07T03:19:52.045899Z","steps":["trace[252683266] 'process raft request' (duration: 349.279397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:52.04597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:51.696015Z","time spent":"349.912373ms","remote":"127.0.0.1:44000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:52.045993Z","caller":"traceutil/trace.go:171","msg":"trace[503817684] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"347.623234ms","start":"2026-05-07T03:19:51.698356Z","end":"2026-05-07T03:19:52.045979Z","steps":["trace[503817684] 'process raft request' (duration: 347.404578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:52.04615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:51.698345Z","time spent":"347.696846ms","remote":"127.0.0.1:44120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:52.046307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.036142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:52.047219Z","caller":"traceutil/trace.go:171","msg":"trace[200547496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"352.017567ms","start":"2026-05-07T03:19:51.69517Z","end":"2026-05-07T03:19:52.047188Z","steps":["trace[200547496] 'agreement among raft nodes before linearized reading' (duration: 350.835587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:52.047318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:51.695156Z","time spent":"352.12545ms","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T03:19:52.347263Z","caller":"traceutil/trace.go:171","msg":"trace[2019307586] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"505.611131ms","start":"2026-05-07T03:19:51.841623Z","end":"2026-05-07T03:19:52.347234Z","steps":["trace[2019307586] 'process raft request' (duration: 462.812712ms)","trace[2019307586] 'compare' (duration: 42.397469ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:52.347423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:51.841599Z","time spent":"505.744094ms","remote":"127.0.0.1:43954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:52.347436Z","caller":"traceutil/trace.go:171","msg":"trace[1329114539] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:637; }","duration":"301.424286ms","start":"2026-05-07T03:19:52.04587Z","end":"2026-05-07T03:19:52.347294Z","steps":["trace[1329114539] 'read index received' (duration: 258.633326ms)","trace[1329114539] 'applied index is now lower than readState.Index' (duration: 42.79ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:52.347661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.058546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:52.347781Z","caller":"traceutil/trace.go:171","msg":"trace[563214555] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:612; }","duration":"498.270731ms","start":"2026-05-07T03:19:51.849491Z","end":"2026-05-07T03:19:52.347762Z","steps":["trace[563214555] 'agreement among raft nodes before linearized reading' (duration: 498.102027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:52.347865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:51.849462Z","time spent":"498.390944ms","remote":"127.0.0.1:44068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" "} {"level":"info","ts":"2026-05-07T03:19:52.348197Z","caller":"traceutil/trace.go:171","msg":"trace[1706092603] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"472.678948ms","start":"2026-05-07T03:19:51.875014Z","end":"2026-05-07T03:19:52.347692Z","steps":["trace[1706092603] 'process raft request' (duration: 472.170094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:52.34844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:51.874989Z","time spent":"473.362665ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:52.505413Z","caller":"traceutil/trace.go:171","msg":"trace[1916318796] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"154.492166ms","start":"2026-05-07T03:19:52.350882Z","end":"2026-05-07T03:19:52.505374Z","steps":["trace[1916318796] 'read index received' (duration: 105.503645ms)","trace[1916318796] 'applied index is now lower than readState.Index' (duration: 48.987641ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:52.505624Z","caller":"traceutil/trace.go:171","msg":"trace[689535045] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"154.814504ms","start":"2026-05-07T03:19:52.350776Z","end":"2026-05-07T03:19:52.50559Z","steps":["trace[689535045] 'process raft request' (duration: 105.579477ms)","trace[689535045] 'compare' (duration: 48.852647ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:52.505703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.794073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-07T03:19:52.505752Z","caller":"traceutil/trace.go:171","msg":"trace[595351492] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:613; }","duration":"154.887536ms","start":"2026-05-07T03:19:52.350851Z","end":"2026-05-07T03:19:52.505739Z","steps":["trace[595351492] 'agreement among raft nodes before linearized reading' (duration: 154.685571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:52.505815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.479583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T03:19:52.506228Z","caller":"traceutil/trace.go:171","msg":"trace[593123928] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:613; }","duration":"135.929354ms","start":"2026-05-07T03:19:52.370245Z","end":"2026-05-07T03:19:52.506174Z","steps":["trace[593123928] 'agreement among raft nodes before linearized reading' (duration: 135.39091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:52.83835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:52.510263Z","time spent":"328.080058ms","remote":"127.0.0.1:44000","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-07T03:19:52.838595Z","caller":"traceutil/trace.go:171","msg":"trace[71201950] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"326.551899ms","start":"2026-05-07T03:19:52.512006Z","end":"2026-05-07T03:19:52.838558Z","steps":["trace[71201950] 'process raft request' (duration: 326.248641ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:52.838469Z","caller":"traceutil/trace.go:171","msg":"trace[1463176037] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:641; }","duration":"228.512136ms","start":"2026-05-07T03:19:52.609935Z","end":"2026-05-07T03:19:52.838447Z","steps":["trace[1463176037] 'read index received' (duration: 192.980174ms)","trace[1463176037] 'applied index is now lower than readState.Index' (duration: 35.530942ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:19:52.838731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:52.51199Z","time spent":"326.659561ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:52.838923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.974908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T03:19:52.839001Z","caller":"traceutil/trace.go:171","msg":"trace[504789583] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:616; }","duration":"229.091521ms","start":"2026-05-07T03:19:52.609882Z","end":"2026-05-07T03:19:52.838973Z","steps":["trace[504789583] 'agreement among raft nodes before linearized reading' (duration: 228.977788ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:52.839473Z","caller":"traceutil/trace.go:171","msg":"trace[1201070901] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"327.37924ms","start":"2026-05-07T03:19:52.512069Z","end":"2026-05-07T03:19:52.839448Z","steps":["trace[1201070901] 'process raft request' (duration: 326.326933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:52.839584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:52.512059Z","time spent":"327.468732ms","remote":"127.0.0.1:44120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:19:54.009546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.116038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2pc7k\" ","response":"range_response_count:1 size:17243"} {"level":"info","ts":"2026-05-07T03:19:54.009659Z","caller":"traceutil/trace.go:171","msg":"trace[1560796641] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2pc7k; range_end:; response_count:1; response_revision:624; }","duration":"203.279313ms","start":"2026-05-07T03:19:53.806355Z","end":"2026-05-07T03:19:54.009635Z","steps":["trace[1560796641] 'range keys from in-memory index tree' (duration: 202.908213ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:54.010226Z","caller":"traceutil/trace.go:171","msg":"trace[609213760] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"200.762037ms","start":"2026-05-07T03:19:53.809439Z","end":"2026-05-07T03:19:54.010201Z","steps":["trace[609213760] 'process raft request' (duration: 197.902083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:55.653597Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18082407703617908062,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:19:55.759161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.534302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:55.759254Z","caller":"traceutil/trace.go:171","msg":"trace[1555771607] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:639; }","duration":"800.659795ms","start":"2026-05-07T03:19:54.958568Z","end":"2026-05-07T03:19:55.759226Z","steps":["trace[1555771607] 'range keys from in-memory index tree' (duration: 800.48645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:55.759325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:54.958563Z","time spent":"800.739846ms","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-05-07T03:19:55.759378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.90608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:55.759557Z","caller":"traceutil/trace.go:171","msg":"trace[659783631] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:639; }","duration":"799.049754ms","start":"2026-05-07T03:19:54.960425Z","end":"2026-05-07T03:19:55.759475Z","steps":["trace[659783631] 'range keys from in-memory index tree' (duration: 798.848168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:55.759606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.456933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:55.759654Z","caller":"traceutil/trace.go:171","msg":"trace[1792839970] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:639; }","duration":"802.518654ms","start":"2026-05-07T03:19:54.957121Z","end":"2026-05-07T03:19:55.759639Z","steps":["trace[1792839970] 'range keys from in-memory index tree' (duration: 802.420102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:55.75971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:54.957114Z","time spent":"802.582976ms","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-05-07T03:19:55.759628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:54.960406Z","time spent":"799.209638ms","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-05-07T03:19:55.75985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.497794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:19:55.759901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.373224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:55.759921Z","caller":"traceutil/trace.go:171","msg":"trace[511520854] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:639; }","duration":"802.538905ms","start":"2026-05-07T03:19:54.957334Z","end":"2026-05-07T03:19:55.759873Z","steps":["trace[511520854] 'range keys from in-memory index tree' (duration: 802.474353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:55.759912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.573095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:55.759957Z","caller":"traceutil/trace.go:171","msg":"trace[686842180] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:639; }","duration":"801.439626ms","start":"2026-05-07T03:19:54.958501Z","end":"2026-05-07T03:19:55.759941Z","steps":["trace[686842180] 'range keys from in-memory index tree' (duration: 801.338273ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:55.759983Z","caller":"traceutil/trace.go:171","msg":"trace[666149850] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:639; }","duration":"802.663178ms","start":"2026-05-07T03:19:54.9573Z","end":"2026-05-07T03:19:55.759963Z","steps":["trace[666149850] 'range keys from in-memory index tree' (duration: 802.492254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:55.760008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:54.958496Z","time spent":"801.498957ms","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-05-07T03:19:55.760023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:54.957296Z","time spent":"802.71463ms","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-05-07T03:19:55.759775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.283478ms","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":"warn","ts":"2026-05-07T03:19:55.760105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:54.957332Z","time spent":"802.625217ms","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-05-07T03:19:55.760133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.688662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:55.760154Z","caller":"traceutil/trace.go:171","msg":"trace[1627687728] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:639; }","duration":"801.735293ms","start":"2026-05-07T03:19:54.958412Z","end":"2026-05-07T03:19:55.760147Z","steps":["trace[1627687728] 'range keys from in-memory index tree' (duration: 801.522947ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:55.760155Z","caller":"traceutil/trace.go:171","msg":"trace[1224347742] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:639; }","duration":"802.671338ms","start":"2026-05-07T03:19:54.957465Z","end":"2026-05-07T03:19:55.760136Z","steps":["trace[1224347742] 'range keys from in-memory index tree' (duration: 802.253288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:55.760329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:54.957458Z","time spent":"802.851983ms","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-07T03:19:55.760197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:54.958399Z","time spent":"801.789514ms","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-05-07T03:19:55.760248Z","caller":"traceutil/trace.go:171","msg":"trace[1897625955] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"797.540545ms","start":"2026-05-07T03:19:54.962699Z","end":"2026-05-07T03:19:55.76024Z","steps":["trace[1897625955] 'process raft request' (duration: 789.746113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:55.760527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:54.962683Z","time spent":"797.758561ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:55.765272Z","caller":"traceutil/trace.go:171","msg":"trace[705373642] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:667; }","duration":"611.857319ms","start":"2026-05-07T03:19:55.153374Z","end":"2026-05-07T03:19:55.765231Z","steps":["trace[705373642] 'read index received' (duration: 599.044886ms)","trace[705373642] 'applied index is now lower than readState.Index' (duration: 12.811653ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:19:55.765384Z","caller":"traceutil/trace.go:171","msg":"trace[1331980810] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"801.717564ms","start":"2026-05-07T03:19:54.963656Z","end":"2026-05-07T03:19:55.765373Z","steps":["trace[1331980810] 'process raft request' (duration: 801.403314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:55.765488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:54.963634Z","time spent":"801.806726ms","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:19:55.765771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.385033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:55.765814Z","caller":"traceutil/trace.go:171","msg":"trace[509235600] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:641; }","duration":"612.502976ms","start":"2026-05-07T03:19:55.153299Z","end":"2026-05-07T03:19:55.765802Z","steps":["trace[509235600] 'agreement among raft nodes before linearized reading' (duration: 612.334881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:55.765885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:55.153278Z","time spent":"612.593288ms","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-07T03:19:55.765955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.544761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-07T03:19:55.766014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.774247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:55.766039Z","caller":"traceutil/trace.go:171","msg":"trace[1976589245] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:641; }","duration":"365.90211ms","start":"2026-05-07T03:19:55.400131Z","end":"2026-05-07T03:19:55.766033Z","steps":["trace[1976589245] 'agreement among raft nodes before linearized reading' (duration: 365.839649ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:55.766028Z","caller":"traceutil/trace.go:171","msg":"trace[1899844231] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"227.654494ms","start":"2026-05-07T03:19:55.538363Z","end":"2026-05-07T03:19:55.766017Z","steps":["trace[1899844231] 'agreement among raft nodes before linearized reading' (duration: 227.432518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:55.766061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:55.40011Z","time spent":"365.943401ms","remote":"127.0.0.1:44080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:19:55.765799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.171867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:19:55.766434Z","caller":"traceutil/trace.go:171","msg":"trace[900058486] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:641; }","duration":"413.572496ms","start":"2026-05-07T03:19:55.352561Z","end":"2026-05-07T03:19:55.766134Z","steps":["trace[900058486] 'agreement among raft nodes before linearized reading' (duration: 413.103354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:55.766602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:55.352542Z","time spent":"414.042669ms","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-05-07T03:19:57.431928Z","caller":"traceutil/trace.go:171","msg":"trace[160576405] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"458.502792ms","start":"2026-05-07T03:19:56.973403Z","end":"2026-05-07T03:19:57.431906Z","steps":["trace[160576405] 'process raft request' (duration: 458.378498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:19:57.432071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:19:56.973364Z","time spent":"458.634006ms","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13205,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:19:57.445632Z","caller":"traceutil/trace.go:171","msg":"trace[706811593] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"257.739835ms","start":"2026-05-07T03:19:57.187874Z","end":"2026-05-07T03:19:57.445614Z","steps":["trace[706811593] 'process raft request' (duration: 257.415487ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:19:58.251889Z","caller":"traceutil/trace.go:171","msg":"trace[2005762954] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"160.438592ms","start":"2026-05-07T03:19:58.091417Z","end":"2026-05-07T03:19:58.251855Z","steps":["trace[2005762954] 'process raft request' (duration: 159.730843ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:03.986218Z","caller":"traceutil/trace.go:171","msg":"trace[746029920] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"104.583713ms","start":"2026-05-07T03:20:03.88162Z","end":"2026-05-07T03:20:03.986204Z","steps":["trace[746029920] 'process raft request' (duration: 104.43541ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:04.42294Z","caller":"traceutil/trace.go:171","msg":"trace[1936074324] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"140.71172ms","start":"2026-05-07T03:20:04.282202Z","end":"2026-05-07T03:20:04.422914Z","steps":["trace[1936074324] 'process raft request' (duration: 107.235711ms)","trace[1936074324] 'compare' (duration: 33.371706ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:20:05.332879Z","caller":"traceutil/trace.go:171","msg":"trace[656278085] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"177.31206ms","start":"2026-05-07T03:20:05.155538Z","end":"2026-05-07T03:20:05.33285Z","steps":["trace[656278085] 'process raft request' (duration: 177.098375ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:05.548044Z","caller":"traceutil/trace.go:171","msg":"trace[2023729095] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"197.055413ms","start":"2026-05-07T03:20:05.350963Z","end":"2026-05-07T03:20:05.548019Z","steps":["trace[2023729095] 'process raft request' (duration: 196.886189ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:08.222583Z","caller":"traceutil/trace.go:171","msg":"trace[416493634] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:797; }","duration":"170.35235ms","start":"2026-05-07T03:20:08.052202Z","end":"2026-05-07T03:20:08.222554Z","steps":["trace[416493634] 'read index received' (duration: 170.068783ms)","trace[416493634] 'applied index is now lower than readState.Index' (duration: 282.367µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:20:08.222798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.574886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-07T03:20:08.222838Z","caller":"traceutil/trace.go:171","msg":"trace[170412174] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:765; }","duration":"170.703379ms","start":"2026-05-07T03:20:08.052122Z","end":"2026-05-07T03:20:08.222826Z","steps":["trace[170412174] 'agreement among raft nodes before linearized reading' (duration: 170.533355ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:08.223198Z","caller":"traceutil/trace.go:171","msg":"trace[251271134] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"219.302411ms","start":"2026-05-07T03:20:08.003841Z","end":"2026-05-07T03:20:08.223144Z","steps":["trace[251271134] 'process raft request' (duration: 218.531071ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:10.374815Z","caller":"traceutil/trace.go:171","msg":"trace[208299474] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:806; }","duration":"146.935914ms","start":"2026-05-07T03:20:10.227855Z","end":"2026-05-07T03:20:10.374791Z","steps":["trace[208299474] 'read index received' (duration: 146.721388ms)","trace[208299474] 'applied index is now lower than readState.Index' (duration: 213.366µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:20:10.374951Z","caller":"traceutil/trace.go:171","msg":"trace[529367505] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"221.244251ms","start":"2026-05-07T03:20:10.153694Z","end":"2026-05-07T03:20:10.374938Z","steps":["trace[529367505] 'process raft request' (duration: 220.958154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:10.37521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.497387ms","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-05-07T03:20:10.37523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.364735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T03:20:10.375284Z","caller":"traceutil/trace.go:171","msg":"trace[582883309] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:774; }","duration":"147.457897ms","start":"2026-05-07T03:20:10.227813Z","end":"2026-05-07T03:20:10.375271Z","steps":["trace[582883309] 'agreement among raft nodes before linearized reading' (duration: 147.317913ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:10.375285Z","caller":"traceutil/trace.go:171","msg":"trace[180880776] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:774; }","duration":"135.62309ms","start":"2026-05-07T03:20:10.239641Z","end":"2026-05-07T03:20:10.375265Z","steps":["trace[180880776] 'agreement among raft nodes before linearized reading' (duration: 135.394024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:10.375696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.176567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-07T03:20:10.375866Z","caller":"traceutil/trace.go:171","msg":"trace[1694706057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:774; }","duration":"128.395792ms","start":"2026-05-07T03:20:10.247447Z","end":"2026-05-07T03:20:10.375843Z","steps":["trace[1694706057] 'agreement among raft nodes before linearized reading' (duration: 127.649402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:11.358154Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18082407703617908420,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:20:11.858787Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18082407703617908420,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:20:11.865234Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.143877846s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T03:20:11.865799Z","caller":"traceutil/trace.go:171","msg":"trace[891393589] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"1.144557173s","start":"2026-05-07T03:20:10.721211Z","end":"2026-05-07T03:20:11.865768Z","steps":["trace[891393589] 'process raft request' (duration: 1.144391729s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:11.866022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:10.721179Z","time spent":"1.144749799s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:20:12.876995Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.011530911s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T03:20:12.885735Z","caller":"traceutil/trace.go:171","msg":"trace[1987036682] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:815; }","duration":"2.028253907s","start":"2026-05-07T03:20:10.857459Z","end":"2026-05-07T03:20:12.885713Z","steps":["trace[1987036682] 'read index received' (duration: 1.008075031s)","trace[1987036682] 'applied index is now lower than readState.Index' (duration: 1.020177996s)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:20:12.88584Z","caller":"traceutil/trace.go:171","msg":"trace[1982483371] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"1.90547221s","start":"2026-05-07T03:20:10.980359Z","end":"2026-05-07T03:20:12.885831Z","steps":["trace[1982483371] 'process raft request' (duration: 1.896828756s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:12.885922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:10.980332Z","time spent":"1.905529672s","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:20:12.886544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.029071128s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-07T03:20:12.886603Z","caller":"traceutil/trace.go:171","msg":"trace[647315418] range","detail":"{range_begin:/registry/masterleases/199.19.213.25; range_end:; response_count:1; response_revision:784; }","duration":"2.02917885s","start":"2026-05-07T03:20:10.85741Z","end":"2026-05-07T03:20:12.886589Z","steps":["trace[647315418] 'agreement among raft nodes before linearized reading' (duration: 2.028998086s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:12.886634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:10.857391Z","time spent":"2.029237122s","remote":"127.0.0.1:43954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.25\" "} {"level":"warn","ts":"2026-05-07T03:20:12.950299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099506654s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T03:20:12.950372Z","caller":"traceutil/trace.go:171","msg":"trace[1571616388] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:785; }","duration":"1.099635127s","start":"2026-05-07T03:20:11.850714Z","end":"2026-05-07T03:20:12.950349Z","steps":["trace[1571616388] 'agreement among raft nodes before linearized reading' (duration: 1.099554075s)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:12.950404Z","caller":"traceutil/trace.go:171","msg":"trace[898153703] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"239.393862ms","start":"2026-05-07T03:20:12.710989Z","end":"2026-05-07T03:20:12.950383Z","steps":["trace[898153703] 'process raft request' (duration: 238.941471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:12.950662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.959239275s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-07T03:20:12.950205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.042099197s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:20:12.950726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.369169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-07T03:20:12.950913Z","caller":"traceutil/trace.go:171","msg":"trace[132996245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:785; }","duration":"487.557104ms","start":"2026-05-07T03:20:12.463308Z","end":"2026-05-07T03:20:12.950865Z","steps":["trace[132996245] 'agreement among raft nodes before linearized reading' (duration: 486.942438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:12.951024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:12.463286Z","time spent":"487.716967ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T03:20:12.950476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:11.850683Z","time spent":"1.099773271s","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:20:12.950564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.219144239s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T03:20:12.951141Z","caller":"traceutil/trace.go:171","msg":"trace[1006324822] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:785; }","duration":"1.219778825s","start":"2026-05-07T03:20:11.731343Z","end":"2026-05-07T03:20:12.951122Z","steps":["trace[1006324822] 'agreement among raft nodes before linearized reading' (duration: 1.219102068s)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:12.951182Z","caller":"traceutil/trace.go:171","msg":"trace[1795878341] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:785; }","duration":"2.042757944s","start":"2026-05-07T03:20:10.908038Z","end":"2026-05-07T03:20:12.950796Z","steps":["trace[1795878341] 'agreement among raft nodes before linearized reading' (duration: 2.042083287s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:12.951316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:10.90802Z","time spent":"2.043271567s","remote":"127.0.0.1:44354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:20:12.950608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.466674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:20:12.951447Z","caller":"traceutil/trace.go:171","msg":"trace[585348285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:785; }","duration":"443.316265ms","start":"2026-05-07T03:20:12.508113Z","end":"2026-05-07T03:20:12.951429Z","steps":["trace[585348285] 'agreement among raft nodes before linearized reading' (duration: 442.35232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:12.951516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:12.508108Z","time spent":"443.386327ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T03:20:12.950625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.745752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-07T03:20:12.951634Z","caller":"traceutil/trace.go:171","msg":"trace[960892553] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:785; }","duration":"487.75929ms","start":"2026-05-07T03:20:12.463861Z","end":"2026-05-07T03:20:12.951621Z","steps":["trace[960892553] 'agreement among raft nodes before linearized reading' (duration: 486.705252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:12.950608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026590442s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:20:12.95169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:12.463856Z","time spent":"487.819851ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T03:20:12.951698Z","caller":"traceutil/trace.go:171","msg":"trace[1983207363] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:785; }","duration":"1.027714502s","start":"2026-05-07T03:20:11.923965Z","end":"2026-05-07T03:20:12.95168Z","steps":["trace[1983207363] 'agreement among raft nodes before linearized reading' (duration: 1.026606262s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:12.951791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:11.923939Z","time spent":"1.027835505s","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T03:20:12.950724Z","caller":"traceutil/trace.go:171","msg":"trace[1954038453] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:785; }","duration":"1.959286126s","start":"2026-05-07T03:20:10.991415Z","end":"2026-05-07T03:20:12.950701Z","steps":["trace[1954038453] 'agreement among raft nodes before linearized reading' (duration: 1.959218595s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:12.951877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:10.991379Z","time spent":"1.960486278s","remote":"127.0.0.1:44028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":9,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:20:12.950506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.148193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-07T03:20:12.951953Z","caller":"traceutil/trace.go:171","msg":"trace[1169717733] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:785; }","duration":"488.638292ms","start":"2026-05-07T03:20:12.463294Z","end":"2026-05-07T03:20:12.951932Z","steps":["trace[1169717733] 'agreement among raft nodes before linearized reading' (duration: 487.009669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:12.952007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:12.463275Z","time spent":"488.714944ms","remote":"127.0.0.1:44196","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-05-07T03:20:12.951217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:11.731317Z","time spent":"1.219877998s","remote":"127.0.0.1:44240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:20:13.233037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.81618ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:20:13.23311Z","caller":"traceutil/trace.go:171","msg":"trace[770168598] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"252.590846ms","start":"2026-05-07T03:20:12.980508Z","end":"2026-05-07T03:20:13.233099Z","steps":["trace[770168598] 'process raft request' (duration: 137.658453ms)","trace[770168598] 'compare' (duration: 114.670146ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:20:25.546497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.213032ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:20:25.546639Z","caller":"traceutil/trace.go:171","msg":"trace[1227489271] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"453.971027ms","start":"2026-05-07T03:20:25.092626Z","end":"2026-05-07T03:20:25.546597Z","steps":["trace[1227489271] 'process raft request' (duration: 207.58269ms)","trace[1227489271] 'compare' (duration: 245.961975ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:20:25.546695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:25.09261Z","time spent":"454.06131ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:20:25.678888Z","caller":"traceutil/trace.go:171","msg":"trace[1115138700] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"545.963265ms","start":"2026-05-07T03:20:25.132903Z","end":"2026-05-07T03:20:25.678866Z","steps":["trace[1115138700] 'process raft request' (duration: 545.922264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:25.679126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:25.132882Z","time spent":"546.084109ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:20:25.679546Z","caller":"traceutil/trace.go:171","msg":"trace[1255528596] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"586.6366ms","start":"2026-05-07T03:20:25.092898Z","end":"2026-05-07T03:20:25.679534Z","steps":["trace[1255528596] 'process raft request' (duration: 577.479243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:25.679775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:25.092892Z","time spent":"586.691683ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:20:25.680031Z","caller":"traceutil/trace.go:171","msg":"trace[1798956175] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"586.310713ms","start":"2026-05-07T03:20:25.093708Z","end":"2026-05-07T03:20:25.680018Z","steps":["trace[1798956175] 'process raft request' (duration: 585.068511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:25.680139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:25.093702Z","time spent":"586.362914ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:20:26.315361Z","caller":"traceutil/trace.go:171","msg":"trace[1084028035] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"310.657086ms","start":"2026-05-07T03:20:26.004676Z","end":"2026-05-07T03:20:26.315333Z","steps":["trace[1084028035] 'process raft request' (duration: 310.115462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:26.315588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:26.004661Z","time spent":"310.77315ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:20:37.327713Z","caller":"traceutil/trace.go:171","msg":"trace[1869359786] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"127.718248ms","start":"2026-05-07T03:20:37.199973Z","end":"2026-05-07T03:20:37.327691Z","steps":["trace[1869359786] 'process raft request' (duration: 127.571994ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:37.86674Z","caller":"traceutil/trace.go:171","msg":"trace[1885288939] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"220.991859ms","start":"2026-05-07T03:20:37.645718Z","end":"2026-05-07T03:20:37.86671Z","steps":["trace[1885288939] 'process raft request' (duration: 220.853116ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:37.869875Z","caller":"traceutil/trace.go:171","msg":"trace[131890159] linearizableReadLoop","detail":"{readStateIndex:1033; appliedIndex:1031; }","duration":"102.374809ms","start":"2026-05-07T03:20:37.767481Z","end":"2026-05-07T03:20:37.869855Z","steps":["trace[131890159] 'read index received' (duration: 99.078992ms)","trace[131890159] 'applied index is now lower than readState.Index' (duration: 3.294717ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:20:37.869904Z","caller":"traceutil/trace.go:171","msg":"trace[486697399] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"109.592346ms","start":"2026-05-07T03:20:37.760292Z","end":"2026-05-07T03:20:37.869884Z","steps":["trace[486697399] 'process raft request' (duration: 109.423481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:37.870133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.873696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T03:20:37.870205Z","caller":"traceutil/trace.go:171","msg":"trace[1463795132] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:994; }","duration":"101.965728ms","start":"2026-05-07T03:20:37.768227Z","end":"2026-05-07T03:20:37.870193Z","steps":["trace[1463795132] 'agreement among raft nodes before linearized reading' (duration: 101.782164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:37.870405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.914652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:20:37.870478Z","caller":"traceutil/trace.go:171","msg":"trace[1105961518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:994; }","duration":"103.011675ms","start":"2026-05-07T03:20:37.767452Z","end":"2026-05-07T03:20:37.870464Z","steps":["trace[1105961518] 'agreement among raft nodes before linearized reading' (duration: 102.503772ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:38.551606Z","caller":"traceutil/trace.go:171","msg":"trace[1603034667] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"126.808693ms","start":"2026-05-07T03:20:38.424778Z","end":"2026-05-07T03:20:38.551587Z","steps":["trace[1603034667] 'process raft request' (duration: 126.68511ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:40.098694Z","caller":"traceutil/trace.go:171","msg":"trace[82233635] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"172.701956ms","start":"2026-05-07T03:20:39.925949Z","end":"2026-05-07T03:20:40.098651Z","steps":["trace[82233635] 'process raft request' (duration: 119.457553ms)","trace[82233635] 'compare' (duration: 52.109284ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:20:41.064035Z","caller":"traceutil/trace.go:171","msg":"trace[1703011182] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1044; }","duration":"139.585935ms","start":"2026-05-07T03:20:40.92443Z","end":"2026-05-07T03:20:41.064016Z","steps":["trace[1703011182] 'read index received' (duration: 139.101863ms)","trace[1703011182] 'applied index is now lower than readState.Index' (duration: 482.882µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:20:41.064151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.706209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:20:41.064234Z","caller":"traceutil/trace.go:171","msg":"trace[1333313722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1003; }","duration":"139.844752ms","start":"2026-05-07T03:20:40.924375Z","end":"2026-05-07T03:20:41.06422Z","steps":["trace[1333313722] 'agreement among raft nodes before linearized reading' (duration: 139.726979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:52.607712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.584628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:20:52.607905Z","caller":"traceutil/trace.go:171","msg":"trace[405088578] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"504.383033ms","start":"2026-05-07T03:20:52.103498Z","end":"2026-05-07T03:20:52.607881Z","steps":["trace[405088578] 'process raft request' (duration: 81.517449ms)","trace[405088578] 'compare' (duration: 422.405813ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:20:52.607997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:52.103479Z","time spent":"504.472226ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:20:52.608523Z","caller":"traceutil/trace.go:171","msg":"trace[918374603] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"503.015578ms","start":"2026-05-07T03:20:52.105484Z","end":"2026-05-07T03:20:52.608499Z","steps":["trace[918374603] 'process raft request' (duration: 502.34895ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:52.608525Z","caller":"traceutil/trace.go:171","msg":"trace[1639417348] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1087; }","duration":"459.129268ms","start":"2026-05-07T03:20:52.149358Z","end":"2026-05-07T03:20:52.608487Z","steps":["trace[1639417348] 'read index received' (duration: 35.669367ms)","trace[1639417348] 'applied index is now lower than readState.Index' (duration: 423.457941ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:20:52.608622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:52.105451Z","time spent":"503.124161ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:20:52.608738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.365624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-07T03:20:52.608775Z","caller":"traceutil/trace.go:171","msg":"trace[128092311] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1045; }","duration":"459.416145ms","start":"2026-05-07T03:20:52.149347Z","end":"2026-05-07T03:20:52.608763Z","steps":["trace[128092311] 'agreement among raft nodes before linearized reading' (duration: 459.261731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:52.60881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:52.149307Z","time spent":"459.494047ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T03:20:52.817883Z","caller":"traceutil/trace.go:171","msg":"trace[1469180694] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1089; }","duration":"148.460407ms","start":"2026-05-07T03:20:52.669393Z","end":"2026-05-07T03:20:52.817854Z","steps":["trace[1469180694] 'read index received' (duration: 145.927563ms)","trace[1469180694] 'applied index is now lower than readState.Index' (duration: 2.531374ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:20:52.817903Z","caller":"traceutil/trace.go:171","msg":"trace[1280524757] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"204.748229ms","start":"2026-05-07T03:20:52.61313Z","end":"2026-05-07T03:20:52.817878Z","steps":["trace[1280524757] 'process raft request' (duration: 202.254465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:52.818071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.661833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T03:20:52.818119Z","caller":"traceutil/trace.go:171","msg":"trace[548405697] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1046; }","duration":"148.759546ms","start":"2026-05-07T03:20:52.669348Z","end":"2026-05-07T03:20:52.818108Z","steps":["trace[548405697] 'agreement among raft nodes before linearized reading' (duration: 148.611211ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:20:56.527068Z","caller":"traceutil/trace.go:171","msg":"trace[309732826] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"414.750416ms","start":"2026-05-07T03:20:56.112295Z","end":"2026-05-07T03:20:56.527046Z","steps":["trace[309732826] 'process raft request' (duration: 414.577011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:20:56.52722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:20:56.112274Z","time spent":"414.872349ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:21:31.475766Z","caller":"traceutil/trace.go:171","msg":"trace[1647291362] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"219.087324ms","start":"2026-05-07T03:21:31.256654Z","end":"2026-05-07T03:21:31.475742Z","steps":["trace[1647291362] 'process raft request' (duration: 218.963561ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:31.515437Z","caller":"traceutil/trace.go:171","msg":"trace[1811769611] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"258.514559ms","start":"2026-05-07T03:21:31.256894Z","end":"2026-05-07T03:21:31.515409Z","steps":["trace[1811769611] 'process raft request' (duration: 258.387426ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:21:54.384034Z","caller":"traceutil/trace.go:171","msg":"trace[155835759] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"110.280218ms","start":"2026-05-07T03:21:54.273723Z","end":"2026-05-07T03:21:54.384003Z","steps":["trace[155835759] 'process raft request' (duration: 110.125083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:21:54.602085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.362562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-07T03:21:54.60218Z","caller":"traceutil/trace.go:171","msg":"trace[854419354] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:1; response_revision:1248; }","duration":"214.511916ms","start":"2026-05-07T03:21:54.387648Z","end":"2026-05-07T03:21:54.60216Z","steps":["trace[854419354] 'range keys from in-memory index tree' (duration: 214.249809ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:03.606611Z","caller":"traceutil/trace.go:171","msg":"trace[816090689] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1366; }","duration":"358.817449ms","start":"2026-05-07T03:22:03.247767Z","end":"2026-05-07T03:22:03.606584Z","steps":["trace[816090689] 'read index received' (duration: 358.729217ms)","trace[816090689] 'applied index is now lower than readState.Index' (duration: 86.762µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:22:03.606754Z","caller":"traceutil/trace.go:171","msg":"trace[1070273951] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"410.649317ms","start":"2026-05-07T03:22:03.196051Z","end":"2026-05-07T03:22:03.6067Z","steps":["trace[1070273951] 'process raft request' (duration: 410.34979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:03.606952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:22:03.196035Z","time spent":"410.811711ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:22:03.607043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.267371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-07T03:22:03.607096Z","caller":"traceutil/trace.go:171","msg":"trace[900555469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1307; }","duration":"359.358323ms","start":"2026-05-07T03:22:03.247724Z","end":"2026-05-07T03:22:03.607083Z","steps":["trace[900555469] 'agreement among raft nodes before linearized reading' (duration: 358.987694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:03.607133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:22:03.247702Z","time spent":"359.421494ms","remote":"127.0.0.1:44196","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":"info","ts":"2026-05-07T03:22:03.729541Z","caller":"traceutil/trace.go:171","msg":"trace[1872354076] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"118.939723ms","start":"2026-05-07T03:22:03.61055Z","end":"2026-05-07T03:22:03.72949Z","steps":["trace[1872354076] 'process raft request' (duration: 118.713726ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:12.274687Z","caller":"traceutil/trace.go:171","msg":"trace[735899652] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"179.20165ms","start":"2026-05-07T03:22:12.095444Z","end":"2026-05-07T03:22:12.274646Z","steps":["trace[735899652] 'process raft request' (duration: 178.981644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:12.912381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.376714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:22:12.912468Z","caller":"traceutil/trace.go:171","msg":"trace[211330232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1344; }","duration":"439.499617ms","start":"2026-05-07T03:22:12.472944Z","end":"2026-05-07T03:22:12.912443Z","steps":["trace[211330232] 'range keys from in-memory index tree' (duration: 439.286041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:12.912484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.21863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:22:12.912592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.805459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-07T03:22:12.912589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.210668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:22:12.912609Z","caller":"traceutil/trace.go:171","msg":"trace[7400633] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1344; }","duration":"159.426116ms","start":"2026-05-07T03:22:12.753155Z","end":"2026-05-07T03:22:12.912581Z","steps":["trace[7400633] 'count revisions from in-memory index tree' (duration: 159.080396ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:12.912646Z","caller":"traceutil/trace.go:171","msg":"trace[1407966526] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1344; }","duration":"311.895901ms","start":"2026-05-07T03:22:12.600737Z","end":"2026-05-07T03:22:12.912633Z","steps":["trace[1407966526] 'count revisions from in-memory index tree' (duration: 311.727656ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:12.912655Z","caller":"traceutil/trace.go:171","msg":"trace[425767193] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1344; }","duration":"557.316021ms","start":"2026-05-07T03:22:12.355324Z","end":"2026-05-07T03:22:12.91264Z","steps":["trace[425767193] 'count revisions from in-memory index tree' (duration: 557.069025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:12.912693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:22:12.355306Z","time spent":"557.376802ms","remote":"127.0.0.1:49350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:22:12.912694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:22:12.600716Z","time spent":"311.964102ms","remote":"127.0.0.1:44378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":5,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:22:12.912522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:22:12.472922Z","time spent":"439.58708ms","remote":"127.0.0.1:43924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:22:12.912359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.214206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:22:12.912921Z","caller":"traceutil/trace.go:171","msg":"trace[1308193346] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1344; }","duration":"440.853143ms","start":"2026-05-07T03:22:12.472051Z","end":"2026-05-07T03:22:12.912904Z","steps":["trace[1308193346] 'count revisions from in-memory index tree' (duration: 440.044651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:12.912977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:22:12.472026Z","time spent":"440.931005ms","remote":"127.0.0.1:49426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:22:12.913049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.461011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:22:12.913139Z","caller":"traceutil/trace.go:171","msg":"trace[2083573970] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1344; }","duration":"173.562953ms","start":"2026-05-07T03:22:12.73954Z","end":"2026-05-07T03:22:12.913102Z","steps":["trace[2083573970] 'count revisions from in-memory index tree' (duration: 172.679481ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:13.619386Z","caller":"traceutil/trace.go:171","msg":"trace[572528687] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1409; }","duration":"155.556535ms","start":"2026-05-07T03:22:13.463807Z","end":"2026-05-07T03:22:13.619363Z","steps":["trace[572528687] 'read index received' (duration: 155.321349ms)","trace[572528687] 'applied index is now lower than readState.Index' (duration: 233.846µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:22:13.619433Z","caller":"traceutil/trace.go:171","msg":"trace[826358853] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"198.954403ms","start":"2026-05-07T03:22:13.420444Z","end":"2026-05-07T03:22:13.619398Z","steps":["trace[826358853] 'process raft request' (duration: 198.768179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:13.619515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.679349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:22:13.619549Z","caller":"traceutil/trace.go:171","msg":"trace[178502145] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1348; }","duration":"155.771931ms","start":"2026-05-07T03:22:13.463768Z","end":"2026-05-07T03:22:13.61954Z","steps":["trace[178502145] 'agreement among raft nodes before linearized reading' (duration: 155.689759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:23.226374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.515902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:daemon-set-controller\" ","response":"range_response_count:1 size:958"} {"level":"info","ts":"2026-05-07T03:22:23.22648Z","caller":"traceutil/trace.go:171","msg":"trace[139605383] range","detail":"{range_begin:/registry/clusterroles/system:controller:daemon-set-controller; range_end:; response_count:1; response_revision:1382; }","duration":"118.687416ms","start":"2026-05-07T03:22:23.107765Z","end":"2026-05-07T03:22:23.226452Z","steps":["trace[139605383] 'range keys from in-memory index tree' (duration: 118.365598ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:25.138928Z","caller":"traceutil/trace.go:171","msg":"trace[1464792569] linearizableReadLoop","detail":"{readStateIndex:1452; appliedIndex:1451; }","duration":"107.676029ms","start":"2026-05-07T03:22:25.03123Z","end":"2026-05-07T03:22:25.138906Z","steps":["trace[1464792569] 'read index received' (duration: 107.531695ms)","trace[1464792569] 'applied index is now lower than readState.Index' (duration: 143.524µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:22:25.139068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.807352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:node-controller\" ","response":"range_response_count:1 size:835"} {"level":"info","ts":"2026-05-07T03:22:25.139139Z","caller":"traceutil/trace.go:171","msg":"trace[1831161897] range","detail":"{range_begin:/registry/clusterroles/system:controller:node-controller; range_end:; response_count:1; response_revision:1388; }","duration":"107.904545ms","start":"2026-05-07T03:22:25.031213Z","end":"2026-05-07T03:22:25.139118Z","steps":["trace[1831161897] 'agreement among raft nodes before linearized reading' (duration: 107.771262ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:25.139044Z","caller":"traceutil/trace.go:171","msg":"trace[491561404] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"116.15503ms","start":"2026-05-07T03:22:25.022812Z","end":"2026-05-07T03:22:25.138967Z","steps":["trace[491561404] 'process raft request' (duration: 115.922274ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:26.588491Z","caller":"traceutil/trace.go:171","msg":"trace[1768370891] linearizableReadLoop","detail":"{readStateIndex:1459; appliedIndex:1458; }","duration":"160.824972ms","start":"2026-05-07T03:22:26.427639Z","end":"2026-05-07T03:22:26.588464Z","steps":["trace[1768370891] 'read index received' (duration: 160.628947ms)","trace[1768370891] 'applied index is now lower than readState.Index' (duration: 194.855µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:22:26.588514Z","caller":"traceutil/trace.go:171","msg":"trace[1188553288] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"163.439729ms","start":"2026-05-07T03:22:26.425047Z","end":"2026-05-07T03:22:26.588487Z","steps":["trace[1188553288] 'process raft request' (duration: 163.221574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:26.588658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.998536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:persistent-volume-binder\" ","response":"range_response_count:1 size:771"} {"level":"warn","ts":"2026-05-07T03:22:26.588677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.333813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pod-garbage-collector\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-05-07T03:22:26.588699Z","caller":"traceutil/trace.go:171","msg":"trace[2053182607] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pod-garbage-collector; range_end:; response_count:1; response_revision:1394; }","duration":"119.399265ms","start":"2026-05-07T03:22:26.469293Z","end":"2026-05-07T03:22:26.588692Z","steps":["trace[2053182607] 'agreement among raft nodes before linearized reading' (duration: 119.336682ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:26.588696Z","caller":"traceutil/trace.go:171","msg":"trace[2094985281] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:persistent-volume-binder; range_end:; response_count:1; response_revision:1394; }","duration":"161.067738ms","start":"2026-05-07T03:22:26.427614Z","end":"2026-05-07T03:22:26.588682Z","steps":["trace[2094985281] 'agreement among raft nodes before linearized reading' (duration: 160.979725ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:26.822893Z","caller":"traceutil/trace.go:171","msg":"trace[164352421] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"105.898004ms","start":"2026-05-07T03:22:26.716961Z","end":"2026-05-07T03:22:26.822859Z","steps":["trace[164352421] 'process raft request' (duration: 105.606496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:27.324381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.059342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:route-controller\" ","response":"range_response_count:1 size:675"} {"level":"info","ts":"2026-05-07T03:22:27.324533Z","caller":"traceutil/trace.go:171","msg":"trace[1334203102] range","detail":"{range_begin:/registry/clusterroles/system:controller:route-controller; range_end:; response_count:1; response_revision:1396; }","duration":"100.258147ms","start":"2026-05-07T03:22:27.224248Z","end":"2026-05-07T03:22:27.324507Z","steps":["trace[1334203102] 'range keys from in-memory index tree' (duration: 99.823026ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:30.119672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.525408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-07T03:22:30.119742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.578906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-problem-detector\" ","response":"range_response_count:1 size:655"} {"level":"info","ts":"2026-05-07T03:22:30.11975Z","caller":"traceutil/trace.go:171","msg":"trace[1740324797] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1403; }","duration":"119.641482ms","start":"2026-05-07T03:22:30.000092Z","end":"2026-05-07T03:22:30.119734Z","steps":["trace[1740324797] 'range keys from in-memory index tree' (duration: 119.373294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:30.119793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.177525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:22:30.119794Z","caller":"traceutil/trace.go:171","msg":"trace[295941974] range","detail":"{range_begin:/registry/clusterroles/system:node-problem-detector; range_end:; response_count:1; response_revision:1403; }","duration":"140.649847ms","start":"2026-05-07T03:22:29.979126Z","end":"2026-05-07T03:22:30.119775Z","steps":["trace[295941974] 'range keys from in-memory index tree' (duration: 140.450342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:30.119672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.53845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-proxier\" ","response":"range_response_count:1 size:712"} {"level":"info","ts":"2026-05-07T03:22:30.119826Z","caller":"traceutil/trace.go:171","msg":"trace[78862867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1403; }","duration":"120.230707ms","start":"2026-05-07T03:22:29.999585Z","end":"2026-05-07T03:22:30.119816Z","steps":["trace[78862867] 'range keys from in-memory index tree' (duration: 120.085023ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:30.119873Z","caller":"traceutil/trace.go:171","msg":"trace[519173993] range","detail":"{range_begin:/registry/clusterroles/system:node-proxier; range_end:; response_count:1; response_revision:1403; }","duration":"121.756755ms","start":"2026-05-07T03:22:29.998089Z","end":"2026-05-07T03:22:30.119846Z","steps":["trace[519173993] 'range keys from in-memory index tree' (duration: 121.445978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:30.120088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.815517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T03:22:30.120224Z","caller":"traceutil/trace.go:171","msg":"trace[1163134758] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1403; }","duration":"121.983321ms","start":"2026-05-07T03:22:29.998226Z","end":"2026-05-07T03:22:30.120209Z","steps":["trace[1163134758] 'range keys from in-memory index tree' (duration: 121.367665ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:30.324091Z","caller":"traceutil/trace.go:171","msg":"trace[799394271] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"253.180343ms","start":"2026-05-07T03:22:30.070884Z","end":"2026-05-07T03:22:30.324065Z","steps":["trace[799394271] 'process raft request' (duration: 253.045189ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:30.38204Z","caller":"traceutil/trace.go:171","msg":"trace[102361865] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"256.820948ms","start":"2026-05-07T03:22:30.125193Z","end":"2026-05-07T03:22:30.382014Z","steps":["trace[102361865] 'process raft request' (duration: 256.745426ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:30.382191Z","caller":"traceutil/trace.go:171","msg":"trace[1032366511] linearizableReadLoop","detail":"{readStateIndex:1471; appliedIndex:1468; }","duration":"256.771097ms","start":"2026-05-07T03:22:30.1254Z","end":"2026-05-07T03:22:30.382171Z","steps":["trace[1032366511] 'read index received' (duration: 198.511242ms)","trace[1032366511] 'applied index is now lower than readState.Index' (duration: 58.257985ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:22:30.382275Z","caller":"traceutil/trace.go:171","msg":"trace[1116825760] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"255.001041ms","start":"2026-05-07T03:22:30.127256Z","end":"2026-05-07T03:22:30.382257Z","steps":["trace[1116825760] 'process raft request' (duration: 254.720004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:30.382312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.89732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-problem-detector\" ","response":"range_response_count:1 size:655"} {"level":"info","ts":"2026-05-07T03:22:30.38235Z","caller":"traceutil/trace.go:171","msg":"trace[993166968] range","detail":"{range_begin:/registry/clusterroles/system:node-problem-detector; range_end:; response_count:1; response_revision:1407; }","duration":"256.975672ms","start":"2026-05-07T03:22:30.125364Z","end":"2026-05-07T03:22:30.382339Z","steps":["trace[993166968] 'agreement among raft nodes before linearized reading' (duration: 256.89222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:30.382431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.14125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-proxier\" ","response":"range_response_count:1 size:712"} {"level":"info","ts":"2026-05-07T03:22:30.382502Z","caller":"traceutil/trace.go:171","msg":"trace[470506650] range","detail":"{range_begin:/registry/clusterroles/system:node-proxier; range_end:; response_count:1; response_revision:1407; }","duration":"171.251633ms","start":"2026-05-07T03:22:30.211235Z","end":"2026-05-07T03:22:30.382486Z","steps":["trace[470506650] 'agreement among raft nodes before linearized reading' (duration: 171.13113ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:30.382088Z","caller":"traceutil/trace.go:171","msg":"trace[759785222] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"256.961192ms","start":"2026-05-07T03:22:30.125087Z","end":"2026-05-07T03:22:30.382048Z","steps":["trace[759785222] 'process raft request' (duration: 256.718726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:30.784802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.964525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:monitoring\" ","response":"range_response_count:1 size:694"} {"level":"info","ts":"2026-05-07T03:22:30.784876Z","caller":"traceutil/trace.go:171","msg":"trace[2133346446] range","detail":"{range_begin:/registry/clusterrolebindings/system:monitoring; range_end:; response_count:1; response_revision:1408; }","duration":"121.255452ms","start":"2026-05-07T03:22:30.663603Z","end":"2026-05-07T03:22:30.784858Z","steps":["trace[2133346446] 'range keys from in-memory index tree' (duration: 120.520054ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:31.48678Z","caller":"traceutil/trace.go:171","msg":"trace[50806890] linearizableReadLoop","detail":"{readStateIndex:1476; appliedIndex:1475; }","duration":"193.593264ms","start":"2026-05-07T03:22:31.293168Z","end":"2026-05-07T03:22:31.486761Z","steps":["trace[50806890] 'read index received' (duration: 193.4251ms)","trace[50806890] 'applied index is now lower than readState.Index' (duration: 167.464µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:22:31.486933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.753248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-05-07T03:22:31.486916Z","caller":"traceutil/trace.go:171","msg":"trace[812337774] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"259.429276ms","start":"2026-05-07T03:22:31.227459Z","end":"2026-05-07T03:22:31.486889Z","steps":["trace[812337774] 'process raft request' (duration: 259.18424ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:31.48696Z","caller":"traceutil/trace.go:171","msg":"trace[1824054156] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:1410; }","duration":"193.83182ms","start":"2026-05-07T03:22:31.293121Z","end":"2026-05-07T03:22:31.486953Z","steps":["trace[1824054156] 'agreement among raft nodes before linearized reading' (duration: 193.744378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:31.487216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.945503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:public-info-viewer\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-05-07T03:22:31.4873Z","caller":"traceutil/trace.go:171","msg":"trace[1749827507] range","detail":"{range_begin:/registry/clusterrolebindings/system:public-info-viewer; range_end:; response_count:1; response_revision:1410; }","duration":"184.19726ms","start":"2026-05-07T03:22:31.303085Z","end":"2026-05-07T03:22:31.487282Z","steps":["trace[1749827507] 'agreement among raft nodes before linearized reading' (duration: 183.980614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:32.604465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.715559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/local-path-storage\" ","response":"range_response_count:1 size:404"} {"level":"info","ts":"2026-05-07T03:22:32.604639Z","caller":"traceutil/trace.go:171","msg":"trace[253001097] range","detail":"{range_begin:/registry/namespaces/local-path-storage; range_end:; response_count:1; response_revision:1414; }","duration":"155.960675ms","start":"2026-05-07T03:22:32.448652Z","end":"2026-05-07T03:22:32.604613Z","steps":["trace[253001097] 'range keys from in-memory index tree' (duration: 155.383971ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:38.817369Z","caller":"traceutil/trace.go:171","msg":"trace[660235026] linearizableReadLoop","detail":"{readStateIndex:1500; appliedIndex:1499; }","duration":"311.49131ms","start":"2026-05-07T03:22:38.50585Z","end":"2026-05-07T03:22:38.817341Z","steps":["trace[660235026] 'read index received' (duration: 263.559963ms)","trace[660235026] 'applied index is now lower than readState.Index' (duration: 47.930177ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:22:38.817768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.871999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/kube-system/\" range_end:\"/registry/statefulsets/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:22:38.817861Z","caller":"traceutil/trace.go:171","msg":"trace[337140396] range","detail":"{range_begin:/registry/statefulsets/kube-system/; range_end:/registry/statefulsets/kube-system0; response_count:0; response_revision:1433; }","duration":"312.018373ms","start":"2026-05-07T03:22:38.505821Z","end":"2026-05-07T03:22:38.81784Z","steps":["trace[337140396] 'agreement among raft nodes before linearized reading' (duration: 311.848758ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:38.817848Z","caller":"traceutil/trace.go:171","msg":"trace[1737649392] transaction","detail":"{read_only:false; response_revision:1433; number_of_response:1; }","duration":"342.714071ms","start":"2026-05-07T03:22:38.475095Z","end":"2026-05-07T03:22:38.817809Z","steps":["trace[1737649392] 'process raft request' (duration: 294.327963ms)","trace[1737649392] 'compare' (duration: 47.787872ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:22:38.817907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:22:38.505809Z","time spent":"312.087145ms","remote":"127.0.0.1:44354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":0,"response size":29,"request content":"key:\"/registry/statefulsets/kube-system/\" range_end:\"/registry/statefulsets/kube-system0\" limit:500 "} {"level":"warn","ts":"2026-05-07T03:22:38.817908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.311044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" limit:500 ","response":"range_response_count:1 size:12751"} {"level":"warn","ts":"2026-05-07T03:22:38.817933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.043309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2918"} {"level":"info","ts":"2026-05-07T03:22:38.817978Z","caller":"traceutil/trace.go:171","msg":"trace[1563027000] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:1433; }","duration":"311.414198ms","start":"2026-05-07T03:22:38.506547Z","end":"2026-05-07T03:22:38.817961Z","steps":["trace[1563027000] 'agreement among raft nodes before linearized reading' (duration: 311.170131ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:38.818006Z","caller":"traceutil/trace.go:171","msg":"trace[1009162339] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1433; }","duration":"258.139002ms","start":"2026-05-07T03:22:38.559848Z","end":"2026-05-07T03:22:38.817987Z","steps":["trace[1009162339] 'agreement among raft nodes before linearized reading' (duration: 257.988408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:38.818019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:22:38.475078Z","time spent":"342.854565ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:22:38.818028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:22:38.506543Z","time spent":"311.471719ms","remote":"127.0.0.1:44028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":12775,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" limit:500 "} {"level":"warn","ts":"2026-05-07T03:22:38.818049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.742931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T03:22:38.818111Z","caller":"traceutil/trace.go:171","msg":"trace[1876784819] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1433; }","duration":"107.841965ms","start":"2026-05-07T03:22:38.710252Z","end":"2026-05-07T03:22:38.818094Z","steps":["trace[1876784819] 'agreement among raft nodes before linearized reading' (duration: 107.66055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:38.818113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.452442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4709"} {"level":"info","ts":"2026-05-07T03:22:38.818201Z","caller":"traceutil/trace.go:171","msg":"trace[238583218] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:1433; }","duration":"260.573515ms","start":"2026-05-07T03:22:38.557596Z","end":"2026-05-07T03:22:38.818169Z","steps":["trace[238583218] 'agreement among raft nodes before linearized reading' (duration: 260.349529ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:39.712797Z","caller":"traceutil/trace.go:171","msg":"trace[1183621410] linearizableReadLoop","detail":"{readStateIndex:1502; appliedIndex:1501; }","duration":"150.513273ms","start":"2026-05-07T03:22:39.562259Z","end":"2026-05-07T03:22:39.712772Z","steps":["trace[1183621410] 'read index received' (duration: 150.310728ms)","trace[1183621410] 'applied index is now lower than readState.Index' (duration: 201.215µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:22:39.712847Z","caller":"traceutil/trace.go:171","msg":"trace[223646897] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"184.57259ms","start":"2026-05-07T03:22:39.528248Z","end":"2026-05-07T03:22:39.71282Z","steps":["trace[223646897] 'process raft request' (duration: 184.378675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:39.713039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.762399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-05-07T03:22:39.71308Z","caller":"traceutil/trace.go:171","msg":"trace[1450498983] range","detail":"{range_begin:/registry/deployments/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:1435; }","duration":"150.844441ms","start":"2026-05-07T03:22:39.562225Z","end":"2026-05-07T03:22:39.71307Z","steps":["trace[1450498983] 'agreement among raft nodes before linearized reading' (duration: 150.668907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:39.713153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.779196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2pc7k\" ","response":"range_response_count:1 size:18521"} {"level":"info","ts":"2026-05-07T03:22:39.713255Z","caller":"traceutil/trace.go:171","msg":"trace[58251082] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2pc7k; range_end:; response_count:1; response_revision:1435; }","duration":"146.90023ms","start":"2026-05-07T03:22:39.56633Z","end":"2026-05-07T03:22:39.713231Z","steps":["trace[58251082] 'agreement among raft nodes before linearized reading' (duration: 146.593972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:40.012197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.351309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2pc7k\" ","response":"range_response_count:1 size:18521"} {"level":"info","ts":"2026-05-07T03:22:40.012274Z","caller":"traceutil/trace.go:171","msg":"trace[658227375] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2pc7k; range_end:; response_count:1; response_revision:1435; }","duration":"175.495363ms","start":"2026-05-07T03:22:39.83676Z","end":"2026-05-07T03:22:40.012256Z","steps":["trace[658227375] 'range keys from in-memory index tree' (duration: 175.133994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:40.012236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.434367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-wkvjf\" ","response":"range_response_count:1 size:4690"} {"level":"info","ts":"2026-05-07T03:22:40.012351Z","caller":"traceutil/trace.go:171","msg":"trace[206677004] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-wkvjf; range_end:; response_count:1; response_revision:1435; }","duration":"166.592561ms","start":"2026-05-07T03:22:39.84573Z","end":"2026-05-07T03:22:40.012323Z","steps":["trace[206677004] 'range keys from in-memory index tree' (duration: 166.203052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:40.296498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.886859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-66vqs\" ","response":"range_response_count:1 size:4901"} {"level":"info","ts":"2026-05-07T03:22:40.29658Z","caller":"traceutil/trace.go:171","msg":"trace[7222705] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-66vqs; range_end:; response_count:1; response_revision:1436; }","duration":"175.026862ms","start":"2026-05-07T03:22:40.121537Z","end":"2026-05-07T03:22:40.296564Z","steps":["trace[7222705] 'range keys from in-memory index tree' (duration: 174.705764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:40.29657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.036485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-7lhdk\" ","response":"range_response_count:1 size:4907"} {"level":"info","ts":"2026-05-07T03:22:40.296665Z","caller":"traceutil/trace.go:171","msg":"trace[1109790121] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-7lhdk; range_end:; response_count:1; response_revision:1436; }","duration":"104.174139ms","start":"2026-05-07T03:22:40.192461Z","end":"2026-05-07T03:22:40.296635Z","steps":["trace[1109790121] 'range keys from in-memory index tree' (duration: 103.84516ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:40.585291Z","caller":"traceutil/trace.go:171","msg":"trace[1134296575] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"107.745672ms","start":"2026-05-07T03:22:40.477524Z","end":"2026-05-07T03:22:40.58527Z","steps":["trace[1134296575] 'process raft request' (duration: 107.373472ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:22:40.970823Z","caller":"traceutil/trace.go:171","msg":"trace[732862225] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"381.025737ms","start":"2026-05-07T03:22:40.589773Z","end":"2026-05-07T03:22:40.970799Z","steps":["trace[732862225] 'process raft request' (duration: 380.822032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:40.970964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:22:40.589756Z","time spent":"381.136449ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:22:40.983796Z","caller":"traceutil/trace.go:171","msg":"trace[960599464] linearizableReadLoop","detail":"{readStateIndex:1506; appliedIndex:1504; }","duration":"392.733207ms","start":"2026-05-07T03:22:40.591052Z","end":"2026-05-07T03:22:40.983785Z","steps":["trace[960599464] 'read index received' (duration: 379.559608ms)","trace[960599464] 'applied index is now lower than readState.Index' (duration: 13.172989ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:22:40.983903Z","caller":"traceutil/trace.go:171","msg":"trace[832204213] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"393.033094ms","start":"2026-05-07T03:22:40.590846Z","end":"2026-05-07T03:22:40.983879Z","steps":["trace[832204213] 'process raft request' (duration: 392.86847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:40.984032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.961483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5154"} {"level":"info","ts":"2026-05-07T03:22:40.984106Z","caller":"traceutil/trace.go:171","msg":"trace[1692194188] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:1439; }","duration":"393.052894ms","start":"2026-05-07T03:22:40.591036Z","end":"2026-05-07T03:22:40.984089Z","steps":["trace[1692194188] 'agreement among raft nodes before linearized reading' (duration: 392.930821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:40.984187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:22:40.59103Z","time spent":"393.116696ms","remote":"127.0.0.1:44120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5178,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-05-07T03:22:40.984251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:22:40.590834Z","time spent":"393.242029ms","remote":"127.0.0.1:44196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:22:40.984622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.230755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-07T03:22:40.984698Z","caller":"traceutil/trace.go:171","msg":"trace[1286702118] range","detail":"{range_begin:/registry/masterleases/199.19.213.25; range_end:; response_count:1; response_revision:1439; }","duration":"119.329357ms","start":"2026-05-07T03:22:40.865355Z","end":"2026-05-07T03:22:40.984685Z","steps":["trace[1286702118] 'agreement among raft nodes before linearized reading' (duration: 119.211154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:40.984736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.469486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T03:22:40.984801Z","caller":"traceutil/trace.go:171","msg":"trace[748303668] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1439; }","duration":"161.583778ms","start":"2026-05-07T03:22:40.823207Z","end":"2026-05-07T03:22:40.984791Z","steps":["trace[748303668] 'agreement among raft nodes before linearized reading' (duration: 161.381683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:40.985418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.231031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:70 size:51878"} {"level":"info","ts":"2026-05-07T03:22:40.985552Z","caller":"traceutil/trace.go:171","msg":"trace[1303265828] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:70; response_revision:1439; }","duration":"390.375335ms","start":"2026-05-07T03:22:40.595161Z","end":"2026-05-07T03:22:40.985537Z","steps":["trace[1303265828] 'agreement among raft nodes before linearized reading' (duration: 389.252486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:40.985643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:22:40.595155Z","time spent":"390.473067ms","remote":"127.0.0.1:44000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":70,"response size":51902,"request content":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 "} {"level":"info","ts":"2026-05-07T03:22:41.151947Z","caller":"traceutil/trace.go:171","msg":"trace[51228422] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"132.010093ms","start":"2026-05-07T03:22:41.019882Z","end":"2026-05-07T03:22:41.151892Z","steps":["trace[51228422] 'process raft request' (duration: 59.232125ms)","trace[51228422] 'compare' (duration: 72.640326ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:22:42.033864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.664925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:22:42.033974Z","caller":"traceutil/trace.go:171","msg":"trace[1433348526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1443; }","duration":"111.810648ms","start":"2026-05-07T03:22:41.922139Z","end":"2026-05-07T03:22:42.03395Z","steps":["trace[1433348526] 'range keys from in-memory index tree' (duration: 111.580813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:22:42.033988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.033397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/kubeadm:nodes-kubeadm-config\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-07T03:22:42.034069Z","caller":"traceutil/trace.go:171","msg":"trace[1544385329] range","detail":"{range_begin:/registry/rolebindings/kube-system/kubeadm:nodes-kubeadm-config; range_end:; response_count:1; response_revision:1443; }","duration":"125.158818ms","start":"2026-05-07T03:22:41.908893Z","end":"2026-05-07T03:22:42.034052Z","steps":["trace[1544385329] 'range keys from in-memory index tree' (duration: 124.923625ms)"],"step_count":1}