{"level":"warn","ts":"2026-08-13T01:07:27.758239Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-13T01:07:27.758342Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-08-13T01:07:27.75848Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-13T01:07:27.758504Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-08-13T01:07:27.758546Z","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-08-13T01:07:27.759383Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-08-13T01:07:27.759498Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-08-13T01:07:27.76325Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.496137ms"} {"level":"info","ts":"2026-08-13T01:07:27.771441Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-08-13T01:07:27.771525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-08-13T01:07:27.771549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-08-13T01:07:27.771567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-13T01:07:27.771576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-08-13T01:07:27.771614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-08-13T01:07:27.781484Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-13T01:07:27.783683Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-13T01:07:27.785107Z","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-08-13T01:07:27.788174Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-13T01:07:27.788566Z","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-08-13T01:07:27.788708Z","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-08-13T01:07:27.788733Z","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-08-13T01:07:27.788834Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-13T01:07:27.78995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-08-13T01:07:27.79059Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-08-13T01:07:27.792141Z","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-08-13T01:07:27.792215Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-08-13T01:07:27.792269Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-08-13T01:07:27.792595Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-13T01:07:27.792741Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-13T01:07:28.672807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-08-13T01:07:28.672895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-08-13T01:07:28.672919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-08-13T01:07:28.672965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-08-13T01:07:28.672986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-08-13T01:07:28.67301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-08-13T01:07:28.673023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-08-13T01:07:28.674342Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T01:07:28.675207Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-08-13T01:07:28.675269Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T01:07:28.675384Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T01:07:28.675668Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-13T01:07:28.675712Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-13T01:07:28.675999Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T01:07:28.676084Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T01:07:28.676107Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T01:07:28.677945Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-13T01:07:28.679263Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-08-13T01:07:30.102694Z","caller":"traceutil/trace.go:171","msg":"trace[2043260212] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"110.025506ms","start":"2026-08-13T01:07:29.99262Z","end":"2026-08-13T01:07:30.102645Z","steps":["trace[2043260212] 'process raft request' (duration: 109.938734ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:30.102891Z","caller":"traceutil/trace.go:171","msg":"trace[140468149] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"110.210672ms","start":"2026-08-13T01:07:29.992655Z","end":"2026-08-13T01:07:30.102865Z","steps":["trace[140468149] 'process raft request' (duration: 109.935774ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:30.102968Z","caller":"traceutil/trace.go:171","msg":"trace[470853293] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"110.08163ms","start":"2026-08-13T01:07:29.992873Z","end":"2026-08-13T01:07:30.102955Z","steps":["trace[470853293] 'process raft request' (duration: 109.7813ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:30.102769Z","caller":"traceutil/trace.go:171","msg":"trace[890680241] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"110.17022ms","start":"2026-08-13T01:07:29.992566Z","end":"2026-08-13T01:07:30.102737Z","steps":["trace[890680241] 'process raft request' (duration: 109.867761ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:30.103233Z","caller":"traceutil/trace.go:171","msg":"trace[1586943995] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"110.382358ms","start":"2026-08-13T01:07:29.992794Z","end":"2026-08-13T01:07:30.103176Z","steps":["trace[1586943995] 'process raft request' (duration: 109.832912ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:30.24993Z","caller":"traceutil/trace.go:171","msg":"trace[69524132] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"192.041481ms","start":"2026-08-13T01:07:30.057859Z","end":"2026-08-13T01:07:30.2499Z","steps":["trace[69524132] 'process raft request' (duration: 123.638622ms)","trace[69524132] 'compare' (duration: 68.139132ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:30.250097Z","caller":"traceutil/trace.go:171","msg":"trace[1066895002] transaction","detail":"{read_only:false; number_of_response:0; response_revision:12; }","duration":"191.189776ms","start":"2026-08-13T01:07:30.058867Z","end":"2026-08-13T01:07:30.250056Z","steps":["trace[1066895002] 'process raft request' (duration: 190.902847ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:30.25068Z","caller":"traceutil/trace.go:171","msg":"trace[791122178] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"190.754704ms","start":"2026-08-13T01:07:30.059901Z","end":"2026-08-13T01:07:30.250655Z","steps":["trace[791122178] 'process raft request' (duration: 189.93164ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:30.250849Z","caller":"traceutil/trace.go:171","msg":"trace[1167387111] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"136.721386ms","start":"2026-08-13T01:07:30.11411Z","end":"2026-08-13T01:07:30.250831Z","steps":["trace[1167387111] 'process raft request' (duration: 136.654944ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:30.25118Z","caller":"traceutil/trace.go:171","msg":"trace[1863380167] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"143.815009ms","start":"2026-08-13T01:07:30.107345Z","end":"2026-08-13T01:07:30.25116Z","steps":["trace[1863380167] 'process raft request' (duration: 143.337595ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:30.251271Z","caller":"traceutil/trace.go:171","msg":"trace[2027406754] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"144.292173ms","start":"2026-08-13T01:07:30.106966Z","end":"2026-08-13T01:07:30.251259Z","steps":["trace[2027406754] 'process raft request' (duration: 143.602843ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:30.251314Z","caller":"traceutil/trace.go:171","msg":"trace[1049207338] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"144.224231ms","start":"2026-08-13T01:07:30.107075Z","end":"2026-08-13T01:07:30.251299Z","steps":["trace[1049207338] 'process raft request' (duration: 143.541592ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:30.251423Z","caller":"traceutil/trace.go:171","msg":"trace[1590109587] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"144.212351ms","start":"2026-08-13T01:07:30.107194Z","end":"2026-08-13T01:07:30.251406Z","steps":["trace[1590109587] 'process raft request' (duration: 143.453138ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:30.251443Z","caller":"traceutil/trace.go:171","msg":"trace[254125194] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"143.328886ms","start":"2026-08-13T01:07:30.108015Z","end":"2026-08-13T01:07:30.251344Z","steps":["trace[254125194] 'process raft request' (duration: 142.696557ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:30.251512Z","caller":"traceutil/trace.go:171","msg":"trace[155159051] linearizableReadLoop","detail":"{readStateIndex:17; appliedIndex:14; }","duration":"148.464252ms","start":"2026-08-13T01:07:30.102833Z","end":"2026-08-13T01:07:30.251298Z","steps":["trace[155159051] 'read index received' (duration: 78.666123ms)","trace[155159051] 'applied index is now lower than readState.Index' (duration: 69.795449ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:30.251516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.614336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T01:07:30.252443Z","caller":"traceutil/trace.go:171","msg":"trace[413418504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19; }","duration":"194.565284ms","start":"2026-08-13T01:07:30.057851Z","end":"2026-08-13T01:07:30.252417Z","steps":["trace[413418504] 'agreement among raft nodes before linearized reading' (duration: 193.579356ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:30.252474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.415436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-qf8fj\" ","response":"range_response_count:1 size:857"} {"level":"info","ts":"2026-08-13T01:07:30.25258Z","caller":"traceutil/trace.go:171","msg":"trace[1992825227] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-qf8fj; range_end:; response_count:1; response_revision:19; }","duration":"145.56086ms","start":"2026-08-13T01:07:30.107002Z","end":"2026-08-13T01:07:30.252563Z","steps":["trace[1992825227] 'agreement among raft nodes before linearized reading' (duration: 145.411175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:30.251625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.175952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-08-13T01:07:30.252894Z","caller":"traceutil/trace.go:171","msg":"trace[1366916768] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"225.453338ms","start":"2026-08-13T01:07:30.027407Z","end":"2026-08-13T01:07:30.252861Z","steps":["trace[1366916768] 'agreement among raft nodes before linearized reading' (duration: 224.143991ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:30.251665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.204224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-08-13T01:07:30.25303Z","caller":"traceutil/trace.go:171","msg":"trace[1395241836] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"209.593623ms","start":"2026-08-13T01:07:30.043415Z","end":"2026-08-13T01:07:30.253008Z","steps":["trace[1395241836] 'agreement among raft nodes before linearized reading' (duration: 208.231395ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:30.251671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.146493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3490"} {"level":"info","ts":"2026-08-13T01:07:30.253193Z","caller":"traceutil/trace.go:171","msg":"trace[1023934702] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:19; }","duration":"194.715416ms","start":"2026-08-13T01:07:30.058469Z","end":"2026-08-13T01:07:30.253185Z","steps":["trace[1023934702] 'agreement among raft nodes before linearized reading' (duration: 192.983607ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:30.567158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.444513ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-13T01:07:30.567545Z","caller":"traceutil/trace.go:171","msg":"trace[1653775933] linearizableReadLoop","detail":"{readStateIndex:27; appliedIndex:23; }","duration":"311.191034ms","start":"2026-08-13T01:07:30.256337Z","end":"2026-08-13T01:07:30.567528Z","steps":["trace[1653775933] 'read index received' (duration: 34.134218ms)","trace[1653775933] 'applied index is now lower than readState.Index' (duration: 277.056046ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:30.567626Z","caller":"traceutil/trace.go:171","msg":"trace[1397438306] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"310.650617ms","start":"2026-08-13T01:07:30.256966Z","end":"2026-08-13T01:07:30.567617Z","steps":["trace[1397438306] 'process raft request' (duration: 310.453652ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:30.567629Z","caller":"traceutil/trace.go:171","msg":"trace[1964047890] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"311.543253ms","start":"2026-08-13T01:07:30.256064Z","end":"2026-08-13T01:07:30.567607Z","steps":["trace[1964047890] 'process raft request' (duration: 311.195603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:30.56768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:30.256958Z","time spent":"310.693478ms","remote":"127.0.0.1:52044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T01:07:30.567745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:30.256053Z","time spent":"311.656997ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T01:07:30.567802Z","caller":"traceutil/trace.go:171","msg":"trace[852024405] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"310.409251ms","start":"2026-08-13T01:07:30.257365Z","end":"2026-08-13T01:07:30.567775Z","steps":["trace[852024405] 'process raft request' (duration: 310.107082ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:30.567813Z","caller":"traceutil/trace.go:171","msg":"trace[1158412425] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"311.866742ms","start":"2026-08-13T01:07:30.255824Z","end":"2026-08-13T01:07:30.56769Z","steps":["trace[1158412425] 'process raft request' (duration: 141.417091ms)","trace[1158412425] 'compare' (duration: 169.269928ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:30.568171Z","caller":"traceutil/trace.go:171","msg":"trace[249828269] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"311.038909ms","start":"2026-08-13T01:07:30.257125Z","end":"2026-08-13T01:07:30.568164Z","steps":["trace[249828269] 'process raft request' (duration: 310.322467ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:30.568201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:30.25578Z","time spent":"312.391938ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T01:07:30.56822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:30.257115Z","time spent":"311.086981ms","remote":"127.0.0.1:51936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T01:07:30.567934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:30.257354Z","time spent":"310.506453ms","remote":"127.0.0.1:52044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T01:07:30.567904Z","caller":"traceutil/trace.go:171","msg":"trace[1763852924] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"310.436871ms","start":"2026-08-13T01:07:30.257393Z","end":"2026-08-13T01:07:30.56783Z","steps":["trace[1763852924] 'process raft request' (duration: 310.105422ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:30.567972Z","caller":"traceutil/trace.go:171","msg":"trace[1648872995] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"310.845504ms","start":"2026-08-13T01:07:30.256782Z","end":"2026-08-13T01:07:30.567628Z","steps":["trace[1648872995] 'process raft request' (duration: 310.607767ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:30.568022Z","caller":"traceutil/trace.go:171","msg":"trace[1286642643] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"311.715479ms","start":"2026-08-13T01:07:30.256294Z","end":"2026-08-13T01:07:30.56801Z","steps":["trace[1286642643] 'process raft request' (duration: 311.06208ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:30.568543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:30.25677Z","time spent":"311.703688ms","remote":"127.0.0.1:51984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T01:07:30.568583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:30.256285Z","time spent":"312.249614ms","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":357,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T01:07:30.568014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.653408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T01:07:30.56869Z","caller":"traceutil/trace.go:171","msg":"trace[716112317] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:29; }","duration":"312.300326ms","start":"2026-08-13T01:07:30.256333Z","end":"2026-08-13T01:07:30.568633Z","steps":["trace[716112317] 'agreement among raft nodes before linearized reading' (duration: 311.551975ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:30.568784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:30.256296Z","time spent":"312.471071ms","remote":"127.0.0.1:51620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" "} {"level":"info","ts":"2026-08-13T01:07:30.568061Z","caller":"traceutil/trace.go:171","msg":"trace[1533608581] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"311.76785ms","start":"2026-08-13T01:07:30.256282Z","end":"2026-08-13T01:07:30.56805Z","steps":["trace[1533608581] 'process raft request' (duration: 311.031659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:30.568854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:30.256267Z","time spent":"312.562753ms","remote":"127.0.0.1:52044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":956,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T01:07:30.570159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:30.25738Z","time spent":"310.978267ms","remote":"127.0.0.1:52044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T01:07:30.590457Z","caller":"traceutil/trace.go:171","msg":"trace[370476222] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"297.263116ms","start":"2026-08-13T01:07:30.293176Z","end":"2026-08-13T01:07:30.590439Z","steps":["trace[370476222] 'process raft request' (duration: 297.146232ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:30.590506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.048949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T01:07:30.590574Z","caller":"traceutil/trace.go:171","msg":"trace[935704417] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:30; }","duration":"334.153751ms","start":"2026-08-13T01:07:30.256384Z","end":"2026-08-13T01:07:30.590537Z","steps":["trace[935704417] 'agreement among raft nodes before linearized reading' (duration: 334.062899ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:30.590621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:30.256375Z","time spent":"334.237565ms","remote":"127.0.0.1:51646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":26,"request content":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" "} {"level":"warn","ts":"2026-08-13T01:07:30.793649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.963003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-13T01:07:30.793869Z","caller":"traceutil/trace.go:171","msg":"trace[2097266846] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"199.215326ms","start":"2026-08-13T01:07:30.594619Z","end":"2026-08-13T01:07:30.793834Z","steps":["trace[2097266846] 'process raft request' (duration: 199.120533ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:30.794003Z","caller":"traceutil/trace.go:171","msg":"trace[539007025] linearizableReadLoop","detail":"{readStateIndex:42; appliedIndex:40; }","duration":"144.143328ms","start":"2026-08-13T01:07:30.649851Z","end":"2026-08-13T01:07:30.793994Z","steps":["trace[539007025] 'read index received' (duration: 21.770394ms)","trace[539007025] 'applied index is now lower than readState.Index' (duration: 122.364964ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:30.794076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.20913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T01:07:30.794103Z","caller":"traceutil/trace.go:171","msg":"trace[764084848] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:40; }","duration":"144.280292ms","start":"2026-08-13T01:07:30.649816Z","end":"2026-08-13T01:07:30.794096Z","steps":["trace[764084848] 'agreement among raft nodes before linearized reading' (duration: 144.21473ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:30.793881Z","caller":"traceutil/trace.go:171","msg":"trace[392479316] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"199.551735ms","start":"2026-08-13T01:07:30.594305Z","end":"2026-08-13T01:07:30.793856Z","steps":["trace[392479316] 'process raft request' (duration: 77.313505ms)","trace[392479316] 'compare' (duration: 121.839209ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:30.794158Z","caller":"traceutil/trace.go:171","msg":"trace[1272264486] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"144.156158ms","start":"2026-08-13T01:07:30.649983Z","end":"2026-08-13T01:07:30.794139Z","steps":["trace[1272264486] 'process raft request' (duration: 143.803048ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:30.793915Z","caller":"traceutil/trace.go:171","msg":"trace[1083658141] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"142.718268ms","start":"2026-08-13T01:07:30.65118Z","end":"2026-08-13T01:07:30.793898Z","steps":["trace[1083658141] 'process raft request' (duration: 142.643176ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:31.003303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.17969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-13T01:07:31.003444Z","caller":"traceutil/trace.go:171","msg":"trace[1539233909] linearizableReadLoop","detail":"{readStateIndex:46; appliedIndex:44; }","duration":"113.641455ms","start":"2026-08-13T01:07:30.889778Z","end":"2026-08-13T01:07:31.00342Z","steps":["trace[1539233909] 'read index received' (duration: 6.303241ms)","trace[1539233909] 'applied index is now lower than readState.Index' (duration: 107.337534ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:31.003584Z","caller":"traceutil/trace.go:171","msg":"trace[821352078] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"207.333549ms","start":"2026-08-13T01:07:30.796226Z","end":"2026-08-13T01:07:31.003559Z","steps":["trace[821352078] 'process raft request' (duration: 99.848811ms)","trace[821352078] 'compare' (duration: 107.032025ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:31.003584Z","caller":"traceutil/trace.go:171","msg":"trace[1179001832] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"205.891417ms","start":"2026-08-13T01:07:30.797655Z","end":"2026-08-13T01:07:31.003546Z","steps":["trace[1179001832] 'process raft request' (duration: 205.732532ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:31.003634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.846821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T01:07:31.003658Z","caller":"traceutil/trace.go:171","msg":"trace[1725085638] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:42; }","duration":"113.906593ms","start":"2026-08-13T01:07:30.889744Z","end":"2026-08-13T01:07:31.003651Z","steps":["trace[1725085638] 'agreement among raft nodes before linearized reading' (duration: 113.80386ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:31.003687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.785779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T01:07:31.003741Z","caller":"traceutil/trace.go:171","msg":"trace[761448556] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:42; }","duration":"113.854262ms","start":"2026-08-13T01:07:30.889868Z","end":"2026-08-13T01:07:31.003722Z","steps":["trace[761448556] 'agreement among raft nodes before linearized reading' (duration: 113.763299ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:31.292666Z","caller":"traceutil/trace.go:171","msg":"trace[305547558] linearizableReadLoop","detail":"{readStateIndex:49; appliedIndex:48; }","duration":"202.666114ms","start":"2026-08-13T01:07:31.089976Z","end":"2026-08-13T01:07:31.292642Z","steps":["trace[305547558] 'read index received' (duration: 107.738505ms)","trace[305547558] 'applied index is now lower than readState.Index' (duration: 94.926669ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:31.292801Z","caller":"traceutil/trace.go:171","msg":"trace[734184142] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"203.675393ms","start":"2026-08-13T01:07:31.089098Z","end":"2026-08-13T01:07:31.292774Z","steps":["trace[734184142] 'process raft request' (duration: 108.596769ms)","trace[734184142] 'compare' (duration: 94.477417ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:31.29291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.915202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T01:07:31.293023Z","caller":"traceutil/trace.go:171","msg":"trace[531756529] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:45; }","duration":"203.010684ms","start":"2026-08-13T01:07:31.089953Z","end":"2026-08-13T01:07:31.292964Z","steps":["trace[531756529] 'agreement among raft nodes before linearized reading' (duration: 202.831799ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:31.297031Z","caller":"traceutil/trace.go:171","msg":"trace[1399679863] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"206.605938ms","start":"2026-08-13T01:07:31.090406Z","end":"2026-08-13T01:07:31.297012Z","steps":["trace[1399679863] 'process raft request' (duration: 206.423652ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:31.297195Z","caller":"traceutil/trace.go:171","msg":"trace[1561373672] transaction","detail":"{read_only:false; response_revision:47; number_of_response:1; }","duration":"206.69035ms","start":"2026-08-13T01:07:31.090476Z","end":"2026-08-13T01:07:31.297167Z","steps":["trace[1561373672] 'process raft request' (duration: 206.465154ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:31.297368Z","caller":"traceutil/trace.go:171","msg":"trace[1928641029] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"204.701783ms","start":"2026-08-13T01:07:31.092644Z","end":"2026-08-13T01:07:31.297345Z","steps":["trace[1928641029] 'process raft request' (duration: 204.334683ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:31.297398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.852536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-13T01:07:31.297399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.509004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T01:07:31.297448Z","caller":"traceutil/trace.go:171","msg":"trace[812244268] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:48; }","duration":"109.933328ms","start":"2026-08-13T01:07:31.187498Z","end":"2026-08-13T01:07:31.297432Z","steps":["trace[812244268] 'agreement among raft nodes before linearized reading' (duration: 109.856316ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:31.297456Z","caller":"traceutil/trace.go:171","msg":"trace[1535797296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:48; }","duration":"140.600907ms","start":"2026-08-13T01:07:31.15684Z","end":"2026-08-13T01:07:31.297441Z","steps":["trace[1535797296] 'agreement among raft nodes before linearized reading' (duration: 140.434222ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:31.536974Z","caller":"traceutil/trace.go:171","msg":"trace[1299194877] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"236.495213ms","start":"2026-08-13T01:07:31.300449Z","end":"2026-08-13T01:07:31.536944Z","steps":["trace[1299194877] 'process raft request' (duration: 234.710143ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:32.168985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.578562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-13T01:07:32.169175Z","caller":"traceutil/trace.go:171","msg":"trace[489851768] linearizableReadLoop","detail":"{readStateIndex:54; appliedIndex:52; }","duration":"867.192408ms","start":"2026-08-13T01:07:31.301929Z","end":"2026-08-13T01:07:32.169122Z","steps":["trace[489851768] 'read index received' (duration: 232.808898ms)","trace[489851768] 'applied index is now lower than readState.Index' (duration: 634.3826ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:32.169264Z","caller":"traceutil/trace.go:171","msg":"trace[2098272965] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"867.107885ms","start":"2026-08-13T01:07:31.302146Z","end":"2026-08-13T01:07:32.169254Z","steps":["trace[2098272965] 'process raft request' (duration: 866.90874ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:32.169269Z","caller":"traceutil/trace.go:171","msg":"trace[2022750694] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"868.248219ms","start":"2026-08-13T01:07:31.300995Z","end":"2026-08-13T01:07:32.169244Z","steps":["trace[2022750694] 'process raft request' (duration: 584.362288ms)","trace[2022750694] 'compare' (duration: 283.450459ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:32.169424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:31.300964Z","time spent":"868.382742ms","remote":"127.0.0.1:51972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":632,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T01:07:32.169498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:31.302136Z","time spent":"867.155716ms","remote":"127.0.0.1:51972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1120,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:07:32.169494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.989256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T01:07:32.169589Z","caller":"traceutil/trace.go:171","msg":"trace[850212074] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:51; }","duration":"113.11979ms","start":"2026-08-13T01:07:32.05644Z","end":"2026-08-13T01:07:32.16956Z","steps":["trace[850212074] 'agreement among raft nodes before linearized reading' (duration: 112.978426ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:32.169683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.746053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T01:07:32.169744Z","caller":"traceutil/trace.go:171","msg":"trace[595135300] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:51; }","duration":"867.803565ms","start":"2026-08-13T01:07:31.301908Z","end":"2026-08-13T01:07:32.169711Z","steps":["trace[595135300] 'agreement among raft nodes before linearized reading' (duration: 867.706593ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:32.169775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:31.3019Z","time spent":"867.869157ms","remote":"127.0.0.1:51910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:aggregate-to-view\" "} {"level":"warn","ts":"2026-08-13T01:07:32.509954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.861835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-13T01:07:32.510114Z","caller":"traceutil/trace.go:171","msg":"trace[530932005] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"337.23773ms","start":"2026-08-13T01:07:32.172828Z","end":"2026-08-13T01:07:32.510066Z","steps":["trace[530932005] 'process raft request' (duration: 174.189331ms)","trace[530932005] 'compare' (duration: 162.696019ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:32.510171Z","caller":"traceutil/trace.go:171","msg":"trace[2040125292] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"336.073216ms","start":"2026-08-13T01:07:32.174064Z","end":"2026-08-13T01:07:32.510137Z","steps":["trace[2040125292] 'process raft request' (duration: 336.005685ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:32.510269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:32.172813Z","time spent":"337.373114ms","remote":"127.0.0.1:51972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":796,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T01:07:32.510295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:32.174054Z","time spent":"336.19669ms","remote":"127.0.0.1:51972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1059,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:07:32.510429Z","caller":"traceutil/trace.go:171","msg":"trace[1628351609] linearizableReadLoop","detail":"{readStateIndex:56; appliedIndex:55; }","duration":"336.812677ms","start":"2026-08-13T01:07:32.173607Z","end":"2026-08-13T01:07:32.51042Z","steps":["trace[1628351609] 'read index received' (duration: 173.444929ms)","trace[1628351609] 'applied index is now lower than readState.Index' (duration: 163.366718ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:32.510493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.881319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:discovery\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T01:07:32.510528Z","caller":"traceutil/trace.go:171","msg":"trace[721522466] range","detail":"{range_begin:/registry/clusterrolebindings/system:discovery; range_end:; response_count:0; response_revision:53; }","duration":"336.92871ms","start":"2026-08-13T01:07:32.173587Z","end":"2026-08-13T01:07:32.510516Z","steps":["trace[721522466] 'agreement among raft nodes before linearized reading' (duration: 336.875659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:32.510564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:32.173571Z","time spent":"336.985572ms","remote":"127.0.0.1:51914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/clusterrolebindings/system:discovery\" "} {"level":"info","ts":"2026-08-13T01:07:32.644612Z","caller":"traceutil/trace.go:171","msg":"trace[518443236] linearizableReadLoop","detail":"{readStateIndex:62; appliedIndex:61; }","duration":"111.326799ms","start":"2026-08-13T01:07:32.533264Z","end":"2026-08-13T01:07:32.644591Z","steps":["trace[518443236] 'read index received' (duration: 100.020976ms)","trace[518443236] 'applied index is now lower than readState.Index' (duration: 11.304813ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:32.64476Z","caller":"traceutil/trace.go:171","msg":"trace[382397446] transaction","detail":"{read_only:false; response_revision:58; number_of_response:1; }","duration":"111.826153ms","start":"2026-08-13T01:07:32.532886Z","end":"2026-08-13T01:07:32.644712Z","steps":["trace[382397446] 'process raft request' (duration: 100.306693ms)","trace[382397446] 'compare' (duration: 11.248452ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:32.644939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.651618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/system-leader-election\" ","response":"range_response_count:1 size:1228"} {"level":"info","ts":"2026-08-13T01:07:32.645035Z","caller":"traceutil/trace.go:171","msg":"trace[1849104812] range","detail":"{range_begin:/registry/flowschemas/system-leader-election; range_end:; response_count:1; response_revision:58; }","duration":"111.780002ms","start":"2026-08-13T01:07:32.533242Z","end":"2026-08-13T01:07:32.645022Z","steps":["trace[1849104812] 'agreement among raft nodes before linearized reading' (duration: 111.489323ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:32.64542Z","caller":"traceutil/trace.go:171","msg":"trace[1605683689] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"111.831103ms","start":"2026-08-13T01:07:32.533559Z","end":"2026-08-13T01:07:32.64539Z","steps":["trace[1605683689] 'process raft request' (duration: 111.74105ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:33.879752Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.219966362s","expected-duration":"1s"} {"level":"info","ts":"2026-08-13T01:07:33.909253Z","caller":"traceutil/trace.go:171","msg":"trace[1097087157] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"1.249435684s","start":"2026-08-13T01:07:32.659793Z","end":"2026-08-13T01:07:33.909229Z","steps":["trace[1097087157] 'process raft request' (duration: 1.24927414s)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:33.909295Z","caller":"traceutil/trace.go:171","msg":"trace[931427209] transaction","detail":"{read_only:false; response_revision:67; number_of_response:1; }","duration":"1.248025984s","start":"2026-08-13T01:07:32.661231Z","end":"2026-08-13T01:07:33.909257Z","steps":["trace[931427209] 'process raft request' (duration: 1.24787926s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:33.909422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:32.659779Z","time spent":"1.249585309s","remote":"127.0.0.1:51910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":665,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T01:07:33.909477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:32.661216Z","time spent":"1.248168078s","remote":"127.0.0.1:51972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1116,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:07:33.909373Z","caller":"traceutil/trace.go:171","msg":"trace[1205396847] transaction","detail":"{read_only:false; response_revision:65; number_of_response:1; }","duration":"1.249595509s","start":"2026-08-13T01:07:32.659746Z","end":"2026-08-13T01:07:33.909342Z","steps":["trace[1205396847] 'process raft request' (duration: 1.220165868s)","trace[1205396847] 'compare' (duration: 28.980739ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:33.910612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:32.659739Z","time spent":"1.250849826s","remote":"127.0.0.1:51972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T01:07:34.197368Z","caller":"traceutil/trace.go:171","msg":"trace[295803039] linearizableReadLoop","detail":"{readStateIndex:73; appliedIndex:71; }","duration":"283.509641ms","start":"2026-08-13T01:07:33.91383Z","end":"2026-08-13T01:07:34.197339Z","steps":["trace[295803039] 'read index received' (duration: 81.972315ms)","trace[295803039] 'applied index is now lower than readState.Index' (duration: 201.535736ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:34.197506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.654355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:public-info-viewer\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T01:07:34.19755Z","caller":"traceutil/trace.go:171","msg":"trace[612999857] range","detail":"{range_begin:/registry/clusterroles/system:public-info-viewer; range_end:; response_count:0; response_revision:72; }","duration":"283.732976ms","start":"2026-08-13T01:07:33.913806Z","end":"2026-08-13T01:07:34.197539Z","steps":["trace[612999857] 'agreement among raft nodes before linearized reading' (duration: 283.646274ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:34.197716Z","caller":"traceutil/trace.go:171","msg":"trace[1150606513] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"283.782709ms","start":"2026-08-13T01:07:33.913906Z","end":"2026-08-13T01:07:34.197688Z","steps":["trace[1150606513] 'process raft request' (duration: 283.214793ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:34.197899Z","caller":"traceutil/trace.go:171","msg":"trace[409755620] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"283.727667ms","start":"2026-08-13T01:07:33.914155Z","end":"2026-08-13T01:07:34.197882Z","steps":["trace[409755620] 'process raft request' (duration: 283.074299ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:34.198372Z","caller":"traceutil/trace.go:171","msg":"trace[780140647] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"284.703864ms","start":"2026-08-13T01:07:33.913649Z","end":"2026-08-13T01:07:34.198352Z","steps":["trace[780140647] 'process raft request' (duration: 250.353022ms)","trace[780140647] 'compare' (duration: 32.84722ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:34.198381Z","caller":"traceutil/trace.go:171","msg":"trace[36596822] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"210.547994ms","start":"2026-08-13T01:07:33.987812Z","end":"2026-08-13T01:07:34.19836Z","steps":["trace[36596822] 'process raft request' (duration: 209.471283ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:34.214804Z","caller":"traceutil/trace.go:171","msg":"trace[1507267314] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"215.222808ms","start":"2026-08-13T01:07:33.999562Z","end":"2026-08-13T01:07:34.214785Z","steps":["trace[1507267314] 'process raft request' (duration: 215.158546ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:34.579632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.774095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-13T01:07:34.580028Z","caller":"traceutil/trace.go:171","msg":"trace[1770806420] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"362.941164ms","start":"2026-08-13T01:07:34.216989Z","end":"2026-08-13T01:07:34.57993Z","steps":["trace[1770806420] 'process raft request' (duration: 183.813429ms)","trace[1770806420] 'compare' (duration: 178.559088ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:34.580162Z","caller":"traceutil/trace.go:171","msg":"trace[1584490609] linearizableReadLoop","detail":"{readStateIndex:80; appliedIndex:79; }","duration":"292.985862ms","start":"2026-08-13T01:07:34.2871Z","end":"2026-08-13T01:07:34.580086Z","steps":["trace[1584490609] 'read index received' (duration: 113.722504ms)","trace[1584490609] 'applied index is now lower than readState.Index' (duration: 179.260778ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:34.580298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.191469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T01:07:34.580361Z","caller":"traceutil/trace.go:171","msg":"trace[962390187] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:78; }","duration":"293.295032ms","start":"2026-08-13T01:07:34.287055Z","end":"2026-08-13T01:07:34.58035Z","steps":["trace[962390187] 'agreement among raft nodes before linearized reading' (duration: 293.157818ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:34.580416Z","caller":"traceutil/trace.go:171","msg":"trace[1613355080] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"291.535361ms","start":"2026-08-13T01:07:34.288857Z","end":"2026-08-13T01:07:34.580393Z","steps":["trace[1613355080] 'process raft request' (duration: 291.016526ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:34.580441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:34.216971Z","time spent":"363.13205ms","remote":"127.0.0.1:51984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":565,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T01:07:34.580052Z","caller":"traceutil/trace.go:171","msg":"trace[457518050] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"291.173681ms","start":"2026-08-13T01:07:34.288855Z","end":"2026-08-13T01:07:34.580029Z","steps":["trace[457518050] 'process raft request' (duration: 291.120479ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:34.844466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.481638ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-08-13T01:07:34.84477Z","caller":"traceutil/trace.go:171","msg":"trace[788007749] transaction","detail":"{read_only:false; response_revision:83; number_of_response:1; }","duration":"199.164848ms","start":"2026-08-13T01:07:34.645579Z","end":"2026-08-13T01:07:34.844744Z","steps":["trace[788007749] 'process raft request' (duration: 43.29518ms)","trace[788007749] 'compare' (duration: 155.344253ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:34.845183Z","caller":"traceutil/trace.go:171","msg":"trace[1871654406] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"181.264915ms","start":"2026-08-13T01:07:34.663904Z","end":"2026-08-13T01:07:34.845169Z","steps":["trace[1871654406] 'process raft request' (duration: 181.153292ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:34.883808Z","caller":"traceutil/trace.go:171","msg":"trace[1895306546] transaction","detail":"{read_only:false; response_revision:85; number_of_response:1; }","duration":"189.754479ms","start":"2026-08-13T01:07:34.694015Z","end":"2026-08-13T01:07:34.883769Z","steps":["trace[1895306546] 'process raft request' (duration: 189.587534ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:35.015587Z","caller":"traceutil/trace.go:171","msg":"trace[126251612] transaction","detail":"{read_only:false; response_revision:89; number_of_response:1; }","duration":"103.358647ms","start":"2026-08-13T01:07:34.912197Z","end":"2026-08-13T01:07:35.015555Z","steps":["trace[126251612] 'process raft request' (duration: 47.637323ms)","trace[126251612] 'compare' (duration: 55.466017ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:35.346077Z","caller":"traceutil/trace.go:171","msg":"trace[780743488] linearizableReadLoop","detail":"{readStateIndex:98; appliedIndex:97; }","duration":"136.078902ms","start":"2026-08-13T01:07:35.209974Z","end":"2026-08-13T01:07:35.346053Z","steps":["trace[780743488] 'read index received' (duration: 110.864031ms)","trace[780743488] 'applied index is now lower than readState.Index' (duration: 25.213981ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:35.346095Z","caller":"traceutil/trace.go:171","msg":"trace[1582652419] transaction","detail":"{read_only:false; response_revision:93; number_of_response:1; }","duration":"137.222205ms","start":"2026-08-13T01:07:35.208844Z","end":"2026-08-13T01:07:35.346066Z","steps":["trace[1582652419] 'process raft request' (duration: 112.062206ms)","trace[1582652419] 'compare' (duration: 24.999505ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:35.346254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.254888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T01:07:35.34635Z","caller":"traceutil/trace.go:171","msg":"trace[720486069] range","detail":"{range_begin:/registry/clusterroles/system:node; range_end:; response_count:0; response_revision:93; }","duration":"136.388151ms","start":"2026-08-13T01:07:35.209949Z","end":"2026-08-13T01:07:35.346337Z","steps":["trace[720486069] 'agreement among raft nodes before linearized reading' (duration: 136.195836ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:36.114964Z","caller":"traceutil/trace.go:171","msg":"trace[969934598] transaction","detail":"{read_only:false; response_revision:155; number_of_response:1; }","duration":"118.125061ms","start":"2026-08-13T01:07:35.996814Z","end":"2026-08-13T01:07:36.114939Z","steps":["trace[969934598] 'process raft request' (duration: 104.552282ms)","trace[969934598] 'compare' (duration: 13.426624ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:36.301108Z","caller":"traceutil/trace.go:171","msg":"trace[1057569543] transaction","detail":"{read_only:false; response_revision:159; number_of_response:1; }","duration":"125.017257ms","start":"2026-08-13T01:07:36.176071Z","end":"2026-08-13T01:07:36.301088Z","steps":["trace[1057569543] 'process raft request' (duration: 48.759505ms)","trace[1057569543] 'compare' (duration: 76.117858ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:36.305119Z","caller":"traceutil/trace.go:171","msg":"trace[129330488] linearizableReadLoop","detail":"{readStateIndex:165; appliedIndex:163; }","duration":"102.264365ms","start":"2026-08-13T01:07:36.202832Z","end":"2026-08-13T01:07:36.305096Z","steps":["trace[129330488] 'read index received' (duration: 22.0157ms)","trace[129330488] 'applied index is now lower than readState.Index' (duration: 80.247615ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:36.305253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.417821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-13T01:07:36.305289Z","caller":"traceutil/trace.go:171","msg":"trace[966481892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:160; }","duration":"102.489973ms","start":"2026-08-13T01:07:36.202791Z","end":"2026-08-13T01:07:36.305281Z","steps":["trace[966481892] 'agreement among raft nodes before linearized reading' (duration: 102.402261ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:36.305144Z","caller":"traceutil/trace.go:171","msg":"trace[1751011090] transaction","detail":"{read_only:false; response_revision:160; number_of_response:1; }","duration":"106.620521ms","start":"2026-08-13T01:07:36.198501Z","end":"2026-08-13T01:07:36.305121Z","steps":["trace[1751011090] 'process raft request' (duration: 106.482167ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:43.173628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.172253346s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-08-13T01:07:43.17369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.472802084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-08-13T01:07:43.173697Z","caller":"traceutil/trace.go:171","msg":"trace[1861064567] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:355; }","duration":"1.172350968s","start":"2026-08-13T01:07:42.001324Z","end":"2026-08-13T01:07:43.173675Z","steps":["trace[1861064567] 'range keys from in-memory index tree' (duration: 1.172188264s)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:43.173729Z","caller":"traceutil/trace.go:171","msg":"trace[1692528432] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:355; }","duration":"1.472868715s","start":"2026-08-13T01:07:41.700845Z","end":"2026-08-13T01:07:43.173713Z","steps":["trace[1692528432] 'range keys from in-memory index tree' (duration: 1.47268458s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:43.173808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.122636214s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-08-13T01:07:43.173833Z","caller":"traceutil/trace.go:171","msg":"trace[1102910080] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:355; }","duration":"1.122684636s","start":"2026-08-13T01:07:42.051141Z","end":"2026-08-13T01:07:43.173826Z","steps":["trace[1102910080] 'range keys from in-memory index tree' (duration: 1.122549092s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:43.173853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:41.700824Z","time spent":"1.472954878s","remote":"127.0.0.1:51756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-08-13T01:07:43.17387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:42.051129Z","time spent":"1.122731827s","remote":"127.0.0.1:51756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":256,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-08-13T01:07:43.173898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.323536839s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-08-13T01:07:43.173962Z","caller":"traceutil/trace.go:171","msg":"trace[145351676] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:355; }","duration":"1.323639712s","start":"2026-08-13T01:07:41.850308Z","end":"2026-08-13T01:07:43.173948Z","steps":["trace[145351676] 'range keys from in-memory index tree' (duration: 1.323415855s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:43.173986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073438615s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-08-13T01:07:43.173994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:41.850254Z","time spent":"1.323733934s","remote":"127.0.0.1:51756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"info","ts":"2026-08-13T01:07:43.174015Z","caller":"traceutil/trace.go:171","msg":"trace[27097805] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:355; }","duration":"1.073526098s","start":"2026-08-13T01:07:42.100479Z","end":"2026-08-13T01:07:43.174005Z","steps":["trace[27097805] 'range keys from in-memory index tree' (duration: 1.073339643s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:43.174039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:42.100462Z","time spent":"1.07357122s","remote":"127.0.0.1:51756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-08-13T01:07:43.173736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:42.001304Z","time spent":"1.17242451s","remote":"127.0.0.1:51756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-08-13T01:07:43.174082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.373624814s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-08-13T01:07:43.174078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.272945689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-08-13T01:07:43.174113Z","caller":"traceutil/trace.go:171","msg":"trace[701272584] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:355; }","duration":"1.373696026s","start":"2026-08-13T01:07:41.800408Z","end":"2026-08-13T01:07:43.174104Z","steps":["trace[701272584] 'range keys from in-memory index tree' (duration: 1.373472829s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:43.174117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.30331866s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4196"} {"level":"warn","ts":"2026-08-13T01:07:43.17408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.422904934s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-08-13T01:07:43.174139Z","caller":"traceutil/trace.go:171","msg":"trace[779162486] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:355; }","duration":"1.303363872s","start":"2026-08-13T01:07:41.870767Z","end":"2026-08-13T01:07:43.174131Z","steps":["trace[779162486] 'range keys from in-memory index tree' (duration: 1.303233917s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:43.174133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:41.800386Z","time spent":"1.373742677s","remote":"127.0.0.1:51756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":225,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-08-13T01:07:43.174138Z","caller":"traceutil/trace.go:171","msg":"trace[1064015243] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:355; }","duration":"1.273084674s","start":"2026-08-13T01:07:41.901037Z","end":"2026-08-13T01:07:43.174122Z","steps":["trace[1064015243] 'range keys from in-memory index tree' (duration: 1.272824256s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:43.174155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.890558ms","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-08-13T01:07:43.174159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:41.87075Z","time spent":"1.303405313s","remote":"127.0.0.1:51738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4218,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-08-13T01:07:43.174171Z","caller":"traceutil/trace.go:171","msg":"trace[1569755458] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:355; }","duration":"1.42306489s","start":"2026-08-13T01:07:41.751088Z","end":"2026-08-13T01:07:43.174153Z","steps":["trace[1569755458] 'range keys from in-memory index tree' (duration: 1.422776661s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:43.173609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.056533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-13T01:07:43.174183Z","caller":"traceutil/trace.go:171","msg":"trace[763949194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:355; }","duration":"565.954662ms","start":"2026-08-13T01:07:42.608219Z","end":"2026-08-13T01:07:43.174174Z","steps":["trace[763949194] 'range keys from in-memory index tree' (duration: 565.811416ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:43.174185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:41.900974Z","time spent":"1.273198237s","remote":"127.0.0.1:51756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-08-13T01:07:43.174207Z","caller":"traceutil/trace.go:171","msg":"trace[2069465545] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:355; }","duration":"102.721622ms","start":"2026-08-13T01:07:43.071479Z","end":"2026-08-13T01:07:43.174201Z","steps":["trace[2069465545] 'range keys from in-memory index tree' (duration: 101.96424ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:43.174215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:42.608199Z","time spent":"566.009532ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T01:07:43.17422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:41.751067Z","time spent":"1.423143913s","remote":"127.0.0.1:51756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-08-13T01:07:43.174225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045925129s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:07:43.174279Z","caller":"traceutil/trace.go:171","msg":"trace[1502518034] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:355; }","duration":"1.04599199s","start":"2026-08-13T01:07:42.128267Z","end":"2026-08-13T01:07:43.174259Z","steps":["trace[1502518034] 'range keys from in-memory index tree' (duration: 1.045862776s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:43.174124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.556156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T01:07:43.173609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.222792903s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-08-13T01:07:43.174322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:42.128215Z","time spent":"1.046095393s","remote":"127.0.0.1:51544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-13T01:07:43.174338Z","caller":"traceutil/trace.go:171","msg":"trace[993184982] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:355; }","duration":"744.806213ms","start":"2026-08-13T01:07:42.429526Z","end":"2026-08-13T01:07:43.174332Z","steps":["trace[993184982] 'range keys from in-memory index tree' (duration: 744.496054ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:43.174358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:42.429504Z","time spent":"744.847175ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-13T01:07:43.174357Z","caller":"traceutil/trace.go:171","msg":"trace[1301694198] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:355; }","duration":"1.223587376s","start":"2026-08-13T01:07:41.950755Z","end":"2026-08-13T01:07:43.174343Z","steps":["trace[1301694198] 'range keys from in-memory index tree' (duration: 1.222626798s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:43.174402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:41.950733Z","time spent":"1.223656129s","remote":"127.0.0.1:51756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-08-13T01:07:43.175185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.676341ms","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-08-13T01:07:43.175295Z","caller":"traceutil/trace.go:171","msg":"trace[928640450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:355; }","duration":"787.806924ms","start":"2026-08-13T01:07:42.387448Z","end":"2026-08-13T01:07:43.175255Z","steps":["trace[928640450] 'range keys from in-memory index tree' (duration: 787.498206ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:43.175341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:42.387434Z","time spent":"787.895487ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-13T01:07:45.462724Z","caller":"traceutil/trace.go:171","msg":"trace[1466655400] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"157.537502ms","start":"2026-08-13T01:07:45.305148Z","end":"2026-08-13T01:07:45.462686Z","steps":["trace[1466655400] 'process raft request' (duration: 126.649868ms)","trace[1466655400] 'compare' (duration: 30.487173ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:46.7815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.163174936s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T01:07:46.781614Z","caller":"traceutil/trace.go:171","msg":"trace[1062605573] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"1.309600979s","start":"2026-08-13T01:07:45.471991Z","end":"2026-08-13T01:07:46.781592Z","steps":["trace[1062605573] 'process raft request' (duration: 146.244818ms)","trace[1062605573] 'compare' (duration: 1.163041782s)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:46.781678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:45.471973Z","time spent":"1.309674652s","remote":"127.0.0.1:51740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6736,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:07:48.342139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.413183ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-08-13T01:07:48.34222Z","caller":"traceutil/trace.go:171","msg":"trace[1770740381] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"1.213623791s","start":"2026-08-13T01:07:47.128584Z","end":"2026-08-13T01:07:48.342208Z","steps":["trace[1770740381] 'read index received' (duration: 374.186838ms)","trace[1770740381] 'applied index is now lower than readState.Index' (duration: 839.435823ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:48.342305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.213724873s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T01:07:48.342267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:46.858646Z","time spent":"1.483614023s","remote":"127.0.0.1:51572","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-13T01:07:48.342328Z","caller":"traceutil/trace.go:171","msg":"trace[1674966908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:414; }","duration":"1.213788955s","start":"2026-08-13T01:07:47.128531Z","end":"2026-08-13T01:07:48.34232Z","steps":["trace[1674966908] 'agreement among raft nodes before linearized reading' (duration: 1.213730303s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:48.342353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:47.12848Z","time spent":"1.213868598s","remote":"127.0.0.1:51544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-13T01:07:48.342473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040667816s","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-08-13T01:07:48.342504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041540131s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-13T01:07:48.342569Z","caller":"traceutil/trace.go:171","msg":"trace[1705442175] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:414; }","duration":"1.04081553s","start":"2026-08-13T01:07:47.30173Z","end":"2026-08-13T01:07:48.342546Z","steps":["trace[1705442175] 'agreement among raft nodes before linearized reading' (duration: 1.040637165s)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:48.342585Z","caller":"traceutil/trace.go:171","msg":"trace[8925412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:414; }","duration":"1.041648084s","start":"2026-08-13T01:07:47.300918Z","end":"2026-08-13T01:07:48.342567Z","steps":["trace[8925412] 'agreement among raft nodes before linearized reading' (duration: 1.041462869s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:48.34262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:47.301686Z","time spent":"1.040923914s","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-13T01:07:48.342633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:47.300914Z","time spent":"1.041710637s","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-13T01:07:48.342656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040842511s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-13T01:07:48.34268Z","caller":"traceutil/trace.go:171","msg":"trace[41966431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:414; }","duration":"1.040880363s","start":"2026-08-13T01:07:47.301793Z","end":"2026-08-13T01:07:48.342674Z","steps":["trace[41966431] 'agreement among raft nodes before linearized reading' (duration: 1.040825041s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:48.342701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:47.301787Z","time spent":"1.040910075s","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T01:07:48.342939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046551367s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-13T01:07:48.343134Z","caller":"traceutil/trace.go:171","msg":"trace[2047874354] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:414; }","duration":"1.046730931s","start":"2026-08-13T01:07:47.296235Z","end":"2026-08-13T01:07:48.342966Z","steps":["trace[2047874354] 'agreement among raft nodes before linearized reading' (duration: 1.046088072s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:48.343172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:47.296216Z","time spent":"1.046945648s","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-13T01:07:48.908832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.4934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T01:07:48.90903Z","caller":"traceutil/trace.go:171","msg":"trace[1277428945] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"562.568504ms","start":"2026-08-13T01:07:48.346437Z","end":"2026-08-13T01:07:48.909006Z","steps":["trace[1277428945] 'process raft request' (duration: 123.809086ms)","trace[1277428945] 'compare' (duration: 438.376027ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:48.909152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:48.346388Z","time spent":"562.714718ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:07:48.90938Z","caller":"traceutil/trace.go:171","msg":"trace[17301992] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"562.615775ms","start":"2026-08-13T01:07:48.34675Z","end":"2026-08-13T01:07:48.909365Z","steps":["trace[17301992] 'process raft request' (duration: 562.370028ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:48.909447Z","caller":"traceutil/trace.go:171","msg":"trace[1292291422] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"431.109428ms","start":"2026-08-13T01:07:48.478305Z","end":"2026-08-13T01:07:48.909415Z","steps":["trace[1292291422] 'process raft request' (duration: 431.039396ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:48.90947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:48.346738Z","time spent":"562.690867ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:07:48.909517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:48.478283Z","time spent":"431.202801ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:07:48.90959Z","caller":"traceutil/trace.go:171","msg":"trace[1593742869] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"562.533312ms","start":"2026-08-13T01:07:48.347048Z","end":"2026-08-13T01:07:48.909581Z","steps":["trace[1593742869] 'process raft request' (duration: 562.149781ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:48.909609Z","caller":"traceutil/trace.go:171","msg":"trace[1590692425] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"562.906033ms","start":"2026-08-13T01:07:48.346685Z","end":"2026-08-13T01:07:48.909591Z","steps":["trace[1590692425] 'process raft request' (duration: 562.262014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:48.909639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:48.347038Z","time spent":"562.572323ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:07:48.909715Z","caller":"traceutil/trace.go:171","msg":"trace[1953773944] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:429; }","duration":"553.824593ms","start":"2026-08-13T01:07:48.35588Z","end":"2026-08-13T01:07:48.909705Z","steps":["trace[1953773944] 'read index received' (duration: 114.396556ms)","trace[1953773944] 'applied index is now lower than readState.Index' (duration: 439.426917ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:48.909722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:48.346665Z","time spent":"562.987775ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:07:48.909811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.924656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-08-13T01:07:48.909837Z","caller":"traceutil/trace.go:171","msg":"trace[1673766951] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:420; }","duration":"553.969287ms","start":"2026-08-13T01:07:48.35586Z","end":"2026-08-13T01:07:48.909829Z","steps":["trace[1673766951] 'agreement among raft nodes before linearized reading' (duration: 553.913666ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:48.909845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.238293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T01:07:48.909863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:48.355852Z","time spent":"554.004328ms","remote":"127.0.0.1:51726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":443,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-08-13T01:07:48.909873Z","caller":"traceutil/trace.go:171","msg":"trace[1221421178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"548.265973ms","start":"2026-08-13T01:07:48.361598Z","end":"2026-08-13T01:07:48.909864Z","steps":["trace[1221421178] 'agreement among raft nodes before linearized reading' (duration: 548.225252ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:48.909895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:48.361541Z","time spent":"548.347436ms","remote":"127.0.0.1:51544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-13T01:07:48.910018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.004696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4792"} {"level":"info","ts":"2026-08-13T01:07:48.910046Z","caller":"traceutil/trace.go:171","msg":"trace[65434886] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:420; }","duration":"518.053128ms","start":"2026-08-13T01:07:48.391985Z","end":"2026-08-13T01:07:48.910038Z","steps":["trace[65434886] 'agreement among raft nodes before linearized reading' (duration: 518.001287ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:48.910073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:48.391961Z","time spent":"518.10197ms","remote":"127.0.0.1:51740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4814,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-08-13T01:07:50.628554Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221825702272673,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T01:07:51.128888Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221825702272673,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T01:07:51.629644Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221825702272673,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T01:07:51.719009Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.799617818s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-13T01:07:51.914474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.17942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T01:07:51.914624Z","caller":"traceutil/trace.go:171","msg":"trace[717855925] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"2.996396545s","start":"2026-08-13T01:07:48.91821Z","end":"2026-08-13T01:07:51.914607Z","steps":["trace[717855925] 'process raft request' (duration: 2.800987828s)","trace[717855925] 'compare' (duration: 194.055087ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:51.914692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:48.9182Z","time spent":"2.996461986s","remote":"127.0.0.1:51740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4645,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:07:51.914843Z","caller":"traceutil/trace.go:171","msg":"trace[1401148414] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:434; }","duration":"1.786752408s","start":"2026-08-13T01:07:50.12806Z","end":"2026-08-13T01:07:51.914812Z","steps":["trace[1401148414] 'read index received' (duration: 1.591147115s)","trace[1401148414] 'applied index is now lower than readState.Index' (duration: 195.601583ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:51.915377Z","caller":"traceutil/trace.go:171","msg":"trace[1275951708] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"922.554454ms","start":"2026-08-13T01:07:50.992796Z","end":"2026-08-13T01:07:51.91535Z","steps":["trace[1275951708] 'process raft request' (duration: 921.768372ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:51.915432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.787356504s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:07:51.915492Z","caller":"traceutil/trace.go:171","msg":"trace[1711640108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:422; }","duration":"1.787465628s","start":"2026-08-13T01:07:50.12801Z","end":"2026-08-13T01:07:51.915475Z","steps":["trace[1711640108] 'agreement among raft nodes before linearized reading' (duration: 1.787335604s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:51.915552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:50.12799Z","time spent":"1.78754995s","remote":"127.0.0.1:51544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-13T01:07:51.915541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:50.99278Z","time spent":"922.677969ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:07:51.916011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001777963s","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-08-13T01:07:51.91606Z","caller":"traceutil/trace.go:171","msg":"trace[1380267988] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:422; }","duration":"1.001908088s","start":"2026-08-13T01:07:50.914141Z","end":"2026-08-13T01:07:51.916049Z","steps":["trace[1380267988] 'agreement among raft nodes before linearized reading' (duration: 1.001834416s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:51.916091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:50.914117Z","time spent":"1.001966709s","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-13T01:07:51.916215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001260408s","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-08-13T01:07:51.916249Z","caller":"traceutil/trace.go:171","msg":"trace[707138705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:422; }","duration":"1.001307569s","start":"2026-08-13T01:07:50.914933Z","end":"2026-08-13T01:07:51.916241Z","steps":["trace[707138705] 'agreement among raft nodes before linearized reading' (duration: 1.001252728s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:51.916273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:50.914927Z","time spent":"1.00134049s","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T01:07:51.916391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001573107s","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-08-13T01:07:51.916424Z","caller":"traceutil/trace.go:171","msg":"trace[165716199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:422; }","duration":"1.001623848s","start":"2026-08-13T01:07:50.914793Z","end":"2026-08-13T01:07:51.916417Z","steps":["trace[165716199] 'agreement among raft nodes before linearized reading' (duration: 1.001571847s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:51.916449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:50.914785Z","time spent":"1.001657939s","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-13T01:07:51.916726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002201725s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-13T01:07:51.916765Z","caller":"traceutil/trace.go:171","msg":"trace[82319776] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:422; }","duration":"1.002278437s","start":"2026-08-13T01:07:50.914477Z","end":"2026-08-13T01:07:51.916756Z","steps":["trace[82319776] 'agreement among raft nodes before linearized reading' (duration: 1.002195914s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:51.916792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:50.914456Z","time spent":"1.002330389s","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-13T01:07:52.058725Z","caller":"traceutil/trace.go:171","msg":"trace[149015534] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"137.543529ms","start":"2026-08-13T01:07:51.921154Z","end":"2026-08-13T01:07:52.058697Z","steps":["trace[149015534] 'process raft request' (duration: 134.178193ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:52.069587Z","caller":"traceutil/trace.go:171","msg":"trace[1635384615] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"147.273669ms","start":"2026-08-13T01:07:51.922212Z","end":"2026-08-13T01:07:52.069567Z","steps":["trace[1635384615] 'process raft request' (duration: 147.131645ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:52.069804Z","caller":"traceutil/trace.go:171","msg":"trace[418645041] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"147.156545ms","start":"2026-08-13T01:07:51.922633Z","end":"2026-08-13T01:07:52.06979Z","steps":["trace[418645041] 'process raft request' (duration: 146.887118ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:52.070405Z","caller":"traceutil/trace.go:171","msg":"trace[616702513] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"146.963109ms","start":"2026-08-13T01:07:51.923421Z","end":"2026-08-13T01:07:52.070384Z","steps":["trace[616702513] 'process raft request' (duration: 146.331011ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:52.070554Z","caller":"traceutil/trace.go:171","msg":"trace[1463351660] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:436; }","duration":"145.669952ms","start":"2026-08-13T01:07:51.924854Z","end":"2026-08-13T01:07:52.070524Z","steps":["trace[1463351660] 'read index received' (duration: 130.552789ms)","trace[1463351660] 'applied index is now lower than readState.Index' (duration: 15.114893ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:52.070737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.858807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:07:52.070747Z","caller":"traceutil/trace.go:171","msg":"trace[426129662] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"145.862428ms","start":"2026-08-13T01:07:51.924868Z","end":"2026-08-13T01:07:52.070731Z","steps":["trace[426129662] 'process raft request' (duration: 145.253331ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:52.070795Z","caller":"traceutil/trace.go:171","msg":"trace[904243965] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:427; }","duration":"145.96252ms","start":"2026-08-13T01:07:51.924822Z","end":"2026-08-13T01:07:52.070785Z","steps":["trace[904243965] 'agreement among raft nodes before linearized reading' (duration: 145.803316ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:52.662528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.202696ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-13T01:08:18.414945Z","caller":"traceutil/trace.go:171","msg":"trace[489616470] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"118.250965ms","start":"2026-08-13T01:08:18.296663Z","end":"2026-08-13T01:08:18.414914Z","steps":["trace[489616470] 'process raft request' (duration: 116.040906ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:08:19.361409Z","caller":"traceutil/trace.go:171","msg":"trace[1069619581] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"550.106574ms","start":"2026-08-13T01:08:18.811281Z","end":"2026-08-13T01:08:19.361388Z","steps":["trace[1069619581] 'process raft request' (duration: 549.93841ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:19.361708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:18.81126Z","time spent":"550.35659ms","remote":"127.0.0.1:51580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T01:08:19.367783Z","caller":"traceutil/trace.go:171","msg":"trace[1922341083] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"542.79549ms","start":"2026-08-13T01:08:18.82497Z","end":"2026-08-13T01:08:19.367765Z","steps":["trace[1922341083] 'process raft request' (duration: 542.473422ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:19.368043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:18.82495Z","time spent":"543.057856ms","remote":"127.0.0.1:51580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T01:08:19.368249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.51368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:08:19.368297Z","caller":"traceutil/trace.go:171","msg":"trace[406223809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:562; }","duration":"239.618442ms","start":"2026-08-13T01:08:19.128669Z","end":"2026-08-13T01:08:19.368287Z","steps":["trace[406223809] 'agreement among raft nodes before linearized reading' (duration: 239.51743ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:08:19.368548Z","caller":"traceutil/trace.go:171","msg":"trace[1199347966] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"543.509637ms","start":"2026-08-13T01:08:18.825028Z","end":"2026-08-13T01:08:19.368537Z","steps":["trace[1199347966] 'process raft request' (duration: 542.521994ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:19.368623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:18.825023Z","time spent":"543.557438ms","remote":"127.0.0.1:51580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T01:08:19.368708Z","caller":"traceutil/trace.go:171","msg":"trace[403600763] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"542.35066ms","start":"2026-08-13T01:08:18.82635Z","end":"2026-08-13T01:08:19.368701Z","steps":["trace[403600763] 'process raft request' (duration: 541.253865ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:19.368752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:18.826332Z","time spent":"542.401512ms","remote":"127.0.0.1:51580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T01:08:19.368812Z","caller":"traceutil/trace.go:171","msg":"trace[1488986793] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"540.54382ms","start":"2026-08-13T01:08:18.828262Z","end":"2026-08-13T01:08:19.368806Z","steps":["trace[1488986793] 'process raft request' (duration: 539.381813ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:19.368855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:18.828256Z","time spent":"540.58097ms","remote":"127.0.0.1:51580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T01:08:19.368927Z","caller":"traceutil/trace.go:171","msg":"trace[1515416542] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"538.527094ms","start":"2026-08-13T01:08:18.830393Z","end":"2026-08-13T01:08:19.36892Z","steps":["trace[1515416542] 'process raft request' (duration: 537.280746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:19.368969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:18.830375Z","time spent":"538.576156ms","remote":"127.0.0.1:51580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T01:08:19.369037Z","caller":"traceutil/trace.go:171","msg":"trace[1743028882] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"538.571106ms","start":"2026-08-13T01:08:18.830459Z","end":"2026-08-13T01:08:19.36903Z","steps":["trace[1743028882] 'process raft request' (duration: 537.251535ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:19.369078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:18.830455Z","time spent":"538.605936ms","remote":"127.0.0.1:51580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T01:08:19.369151Z","caller":"traceutil/trace.go:171","msg":"trace[2100332380] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"466.471217ms","start":"2026-08-13T01:08:18.902673Z","end":"2026-08-13T01:08:19.369144Z","steps":["trace[2100332380] 'process raft request' (duration: 465.269481ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:19.369192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:18.902641Z","time spent":"466.534378ms","remote":"127.0.0.1:51580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T01:08:19.369195Z","caller":"traceutil/trace.go:171","msg":"trace[27622861] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"500.232205ms","start":"2026-08-13T01:08:18.868917Z","end":"2026-08-13T01:08:19.369149Z","steps":["trace[27622861] 'process raft request' (duration: 498.939806ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:08:19.369258Z","caller":"traceutil/trace.go:171","msg":"trace[123454161] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"366.056921ms","start":"2026-08-13T01:08:19.003193Z","end":"2026-08-13T01:08:19.36925Z","steps":["trace[123454161] 'process raft request' (duration: 364.822634ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:19.369294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:19.003167Z","time spent":"366.111223ms","remote":"127.0.0.1:51580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T01:08:19.369376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:18.8689Z","time spent":"500.409209ms","remote":"127.0.0.1:51580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T01:08:19.369524Z","caller":"traceutil/trace.go:171","msg":"trace[690693438] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"537.4291ms","start":"2026-08-13T01:08:18.832079Z","end":"2026-08-13T01:08:19.369508Z","steps":["trace[690693438] 'process raft request' (duration: 535.686821ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:19.369582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:18.832072Z","time spent":"537.483741ms","remote":"127.0.0.1:51580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T01:08:19.36966Z","caller":"traceutil/trace.go:171","msg":"trace[631111100] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"517.570054ms","start":"2026-08-13T01:08:18.852082Z","end":"2026-08-13T01:08:19.369652Z","steps":["trace[631111100] 'process raft request' (duration: 515.725403ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:19.369705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:18.852068Z","time spent":"517.618325ms","remote":"127.0.0.1:51580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T01:08:19.368141Z","caller":"traceutil/trace.go:171","msg":"trace[65767891] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:572; }","duration":"239.396546ms","start":"2026-08-13T01:08:19.128724Z","end":"2026-08-13T01:08:19.368121Z","steps":["trace[65767891] 'read index received' (duration: 232.748166ms)","trace[65767891] 'applied index is now lower than readState.Index' (duration: 6.6475ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:08:25.600788Z","caller":"traceutil/trace.go:171","msg":"trace[342375485] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"153.441015ms","start":"2026-08-13T01:08:25.447305Z","end":"2026-08-13T01:08:25.600746Z","steps":["trace[342375485] 'process raft request' (duration: 152.688908ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:08:25.76106Z","caller":"traceutil/trace.go:171","msg":"trace[1588140011] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"142.02592ms","start":"2026-08-13T01:08:25.618992Z","end":"2026-08-13T01:08:25.761018Z","steps":["trace[1588140011] 'process raft request' (duration: 49.009551ms)","trace[1588140011] 'compare' (duration: 92.846996ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:08:26.518702Z","caller":"traceutil/trace.go:171","msg":"trace[96870073] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:686; }","duration":"304.377716ms","start":"2026-08-13T01:08:26.214268Z","end":"2026-08-13T01:08:26.518646Z","steps":["trace[96870073] 'read index received' (duration: 298.379981ms)","trace[96870073] 'applied index is now lower than readState.Index' (duration: 5.996745ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:08:26.518829Z","caller":"traceutil/trace.go:171","msg":"trace[1415707810] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"359.227747ms","start":"2026-08-13T01:08:26.15957Z","end":"2026-08-13T01:08:26.518798Z","steps":["trace[1415707810] 'process raft request' (duration: 356.23404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:26.518982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:26.15954Z","time spent":"359.3576ms","remote":"127.0.0.1:51636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:08:26.519096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.816616ms","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-08-13T01:08:26.519172Z","caller":"traceutil/trace.go:171","msg":"trace[1909497022] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:660; }","duration":"304.938418ms","start":"2026-08-13T01:08:26.214223Z","end":"2026-08-13T01:08:26.519162Z","steps":["trace[1909497022] 'agreement among raft nodes before linearized reading' (duration: 304.726874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:26.519216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:26.214205Z","time spent":"305.00323ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-13T01:08:26.519239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.891795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-13T01:08:26.519324Z","caller":"traceutil/trace.go:171","msg":"trace[831475648] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:660; }","duration":"227.027528ms","start":"2026-08-13T01:08:26.292277Z","end":"2026-08-13T01:08:26.519305Z","steps":["trace[831475648] 'agreement among raft nodes before linearized reading' (duration: 226.810534ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:26.51938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.351591ms","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-08-13T01:08:26.519427Z","caller":"traceutil/trace.go:171","msg":"trace[403627723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:660; }","duration":"221.434743ms","start":"2026-08-13T01:08:26.29798Z","end":"2026-08-13T01:08:26.519415Z","steps":["trace[403627723] 'agreement among raft nodes before linearized reading' (duration: 221.29587ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:27.260063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.614385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T01:08:27.260283Z","caller":"traceutil/trace.go:171","msg":"trace[2068238005] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"736.105692ms","start":"2026-08-13T01:08:26.524143Z","end":"2026-08-13T01:08:27.260249Z","steps":["trace[2068238005] 'process raft request' (duration: 35.224062ms)","trace[2068238005] 'compare' (duration: 700.42609ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:08:27.26033Z","caller":"traceutil/trace.go:171","msg":"trace[500092023] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"736.128462ms","start":"2026-08-13T01:08:26.524172Z","end":"2026-08-13T01:08:27.2603Z","steps":["trace[500092023] 'process raft request' (duration: 735.992879ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:27.260439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:26.524153Z","time spent":"736.220995ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:08:27.260443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:26.524115Z","time spent":"736.256155ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:08:27.260478Z","caller":"traceutil/trace.go:171","msg":"trace[704145522] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"736.196944ms","start":"2026-08-13T01:08:26.524221Z","end":"2026-08-13T01:08:27.260418Z","steps":["trace[704145522] 'process raft request' (duration: 735.990799ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:08:27.260295Z","caller":"traceutil/trace.go:171","msg":"trace[1440949240] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"735.99646ms","start":"2026-08-13T01:08:26.524282Z","end":"2026-08-13T01:08:27.260279Z","steps":["trace[1440949240] 'process raft request' (duration: 735.962509ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:27.260872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:26.524274Z","time spent":"736.406288ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:08:27.260776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:26.524208Z","time spent":"736.469959ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:08:27.265396Z","caller":"traceutil/trace.go:171","msg":"trace[275464377] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:691; }","duration":"403.904502ms","start":"2026-08-13T01:08:26.861469Z","end":"2026-08-13T01:08:27.265374Z","steps":["trace[275464377] 'read index received' (duration: 403.724708ms)","trace[275464377] 'applied index is now lower than readState.Index' (duration: 178.904µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:08:27.265669Z","caller":"traceutil/trace.go:171","msg":"trace[33952541] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"682.486948ms","start":"2026-08-13T01:08:26.583167Z","end":"2026-08-13T01:08:27.265654Z","steps":["trace[33952541] 'process raft request' (duration: 682.092469ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:27.26576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:26.583109Z","time spent":"682.601031ms","remote":"127.0.0.1:51800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T01:08:27.265747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.291903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:08:27.265821Z","caller":"traceutil/trace.go:171","msg":"trace[766186184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:665; }","duration":"137.399266ms","start":"2026-08-13T01:08:27.128397Z","end":"2026-08-13T01:08:27.265797Z","steps":["trace[766186184] 'agreement among raft nodes before linearized reading' (duration: 137.309594ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:27.265784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.308451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-13T01:08:27.2659Z","caller":"traceutil/trace.go:171","msg":"trace[1350897022] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:665; }","duration":"404.461484ms","start":"2026-08-13T01:08:26.861428Z","end":"2026-08-13T01:08:27.26589Z","steps":["trace[1350897022] 'agreement among raft nodes before linearized reading' (duration: 404.257999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:27.265928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:26.861408Z","time spent":"404.512765ms","remote":"127.0.0.1:51572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"warn","ts":"2026-08-13T01:08:28.470784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005541692s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-13T01:08:28.470914Z","caller":"traceutil/trace.go:171","msg":"trace[1147865703] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:694; }","duration":"341.807396ms","start":"2026-08-13T01:08:28.129076Z","end":"2026-08-13T01:08:28.470884Z","steps":["trace[1147865703] 'read index received' (duration: 22.891µs)","trace[1147865703] 'applied index is now lower than readState.Index' (duration: 341.782935ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:08:28.470934Z","caller":"traceutil/trace.go:171","msg":"trace[2086320446] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"1.180258316s","start":"2026-08-13T01:08:27.290638Z","end":"2026-08-13T01:08:28.470896Z","steps":["trace[2086320446] 'process raft request' (duration: 174.539609ms)","trace[2086320446] 'compare' (duration: 1.004798266s)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:08:28.47104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.9562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:08:28.471068Z","caller":"traceutil/trace.go:171","msg":"trace[743834016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:667; }","duration":"342.00941ms","start":"2026-08-13T01:08:28.12905Z","end":"2026-08-13T01:08:28.471059Z","steps":["trace[743834016] 'agreement among raft nodes before linearized reading' (duration: 341.901558ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:28.471111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:28.129035Z","time spent":"342.068002ms","remote":"127.0.0.1:51544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-13T01:08:28.471125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:27.290629Z","time spent":"1.180434589s","remote":"127.0.0.1:51740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T01:08:28.64053Z","caller":"traceutil/trace.go:171","msg":"trace[295812123] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"165.055575ms","start":"2026-08-13T01:08:28.475415Z","end":"2026-08-13T01:08:28.640471Z","steps":["trace[295812123] 'process raft request' (duration: 163.993892ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:08:28.64063Z","caller":"traceutil/trace.go:171","msg":"trace[1783811710] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"164.954775ms","start":"2026-08-13T01:08:28.47562Z","end":"2026-08-13T01:08:28.640575Z","steps":["trace[1783811710] 'process raft request' (duration: 164.755789ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:08:28.640864Z","caller":"traceutil/trace.go:171","msg":"trace[1059868803] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"163.38462ms","start":"2026-08-13T01:08:28.477462Z","end":"2026-08-13T01:08:28.640847Z","steps":["trace[1059868803] 'process raft request' (duration: 163.017682ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:08:30.086174Z","caller":"traceutil/trace.go:171","msg":"trace[623937174] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"442.106309ms","start":"2026-08-13T01:08:29.644048Z","end":"2026-08-13T01:08:30.086154Z","steps":["trace[623937174] 'process raft request' (duration: 344.103888ms)","trace[623937174] 'compare' (duration: 97.915149ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:08:30.086291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:29.644019Z","time spent":"442.218082ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:08:33.942076Z","caller":"traceutil/trace.go:171","msg":"trace[723513896] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"181.981207ms","start":"2026-08-13T01:08:33.76003Z","end":"2026-08-13T01:08:33.942011Z","steps":["trace[723513896] 'process raft request' (duration: 180.314369ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:42.066268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.733747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T01:08:42.066355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.416512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:08:42.06671Z","caller":"traceutil/trace.go:171","msg":"trace[1167237131] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:814; }","duration":"225.820572ms","start":"2026-08-13T01:08:41.840853Z","end":"2026-08-13T01:08:42.066674Z","steps":["trace[1167237131] 'count revisions from in-memory index tree' (duration: 225.349882ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:42.066786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.310557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:08:42.066856Z","caller":"traceutil/trace.go:171","msg":"trace[1308436119] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:814; }","duration":"240.363008ms","start":"2026-08-13T01:08:41.826466Z","end":"2026-08-13T01:08:42.066829Z","steps":["trace[1308436119] 'range keys from in-memory index tree' (duration: 240.219213ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:08:42.066864Z","caller":"traceutil/trace.go:171","msg":"trace[523606949] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:814; }","duration":"240.91202ms","start":"2026-08-13T01:08:41.825464Z","end":"2026-08-13T01:08:42.066376Z","steps":["trace[523606949] 'count revisions from in-memory index tree' (duration: 240.692535ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:42.067087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.860821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-13T01:08:42.066694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.269298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T01:08:42.067146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.127975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:08:42.067151Z","caller":"traceutil/trace.go:171","msg":"trace[347460248] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:814; }","duration":"241.740379ms","start":"2026-08-13T01:08:41.825391Z","end":"2026-08-13T01:08:42.067131Z","steps":["trace[347460248] 'range keys from in-memory index tree' (duration: 241.155366ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:08:42.067244Z","caller":"traceutil/trace.go:171","msg":"trace[1050036190] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:814; }","duration":"298.035576ms","start":"2026-08-13T01:08:41.769195Z","end":"2026-08-13T01:08:42.06723Z","steps":["trace[1050036190] 'count revisions from in-memory index tree' (duration: 297.79692ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:08:42.06725Z","caller":"traceutil/trace.go:171","msg":"trace[648151719] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:814; }","duration":"297.260738ms","start":"2026-08-13T01:08:41.769938Z","end":"2026-08-13T01:08:42.067199Z","steps":["trace[648151719] 'range keys from in-memory index tree' (duration: 297.050133ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:42.066611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.088915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:08:42.067502Z","caller":"traceutil/trace.go:171","msg":"trace[1607951823] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:814; }","duration":"226.022908ms","start":"2026-08-13T01:08:41.841463Z","end":"2026-08-13T01:08:42.067486Z","steps":["trace[1607951823] 'range keys from in-memory index tree' (duration: 224.969683ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:42.067676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.850706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:08:42.067767Z","caller":"traceutil/trace.go:171","msg":"trace[12481580] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:814; }","duration":"226.978239ms","start":"2026-08-13T01:08:41.840766Z","end":"2026-08-13T01:08:42.067744Z","steps":["trace[12481580] 'range keys from in-memory index tree' (duration: 225.718569ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:42.067693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.682548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:08:42.067853Z","caller":"traceutil/trace.go:171","msg":"trace[1031023213] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:814; }","duration":"297.876583ms","start":"2026-08-13T01:08:41.769961Z","end":"2026-08-13T01:08:42.067838Z","steps":["trace[1031023213] 'count revisions from in-memory index tree' (duration: 296.505431ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:08:44.821983Z","caller":"traceutil/trace.go:171","msg":"trace[1655368771] linearizableReadLoop","detail":"{readStateIndex:862; appliedIndex:861; }","duration":"183.508988ms","start":"2026-08-13T01:08:44.63845Z","end":"2026-08-13T01:08:44.821959Z","steps":["trace[1655368771] 'read index received' (duration: 183.285242ms)","trace[1655368771] 'applied index is now lower than readState.Index' (duration: 222.886µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:08:44.822216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.743873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ts8pz\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-08-13T01:08:44.82226Z","caller":"traceutil/trace.go:171","msg":"trace[111855898] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ts8pz; range_end:; response_count:1; response_revision:829; }","duration":"183.838685ms","start":"2026-08-13T01:08:44.638411Z","end":"2026-08-13T01:08:44.82225Z","steps":["trace[111855898] 'agreement among raft nodes before linearized reading' (duration: 183.648011ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:08:44.822422Z","caller":"traceutil/trace.go:171","msg":"trace[719116873] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"423.941945ms","start":"2026-08-13T01:08:44.398451Z","end":"2026-08-13T01:08:44.822393Z","steps":["trace[719116873] 'process raft request' (duration: 423.368992ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:44.82261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:44.39844Z","time spent":"424.056168ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:08:45.222523Z","caller":"traceutil/trace.go:171","msg":"trace[336797794] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"390.991237ms","start":"2026-08-13T01:08:44.831496Z","end":"2026-08-13T01:08:45.222487Z","steps":["trace[336797794] 'process raft request' (duration: 390.791293ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:45.222726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:44.831481Z","time spent":"391.13343ms","remote":"127.0.0.1:51740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:08:59.611178Z","caller":"traceutil/trace.go:171","msg":"trace[247909539] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"405.627425ms","start":"2026-08-13T01:08:59.205527Z","end":"2026-08-13T01:08:59.611154Z","steps":["trace[247909539] 'process raft request' (duration: 405.494742ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:59.611333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:08:59.205512Z","time spent":"405.734737ms","remote":"127.0.0.1:51740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5471,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:08:59.611839Z","caller":"traceutil/trace.go:171","msg":"trace[1841664963] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:995; }","duration":"123.247989ms","start":"2026-08-13T01:08:59.488541Z","end":"2026-08-13T01:08:59.611789Z","steps":["trace[1841664963] 'read index received' (duration: 123.231289ms)","trace[1841664963] 'applied index is now lower than readState.Index' (duration: 13.4µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:08:59.612318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.74754ms","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-08-13T01:08:59.612561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.247149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-13T01:08:59.612595Z","caller":"traceutil/trace.go:171","msg":"trace[1842870204] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:959; }","duration":"123.29541ms","start":"2026-08-13T01:08:59.489291Z","end":"2026-08-13T01:08:59.612587Z","steps":["trace[1842870204] 'agreement among raft nodes before linearized reading' (duration: 123.156166ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:08:59.612576Z","caller":"traceutil/trace.go:171","msg":"trace[1810425077] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:959; }","duration":"123.817402ms","start":"2026-08-13T01:08:59.488536Z","end":"2026-08-13T01:08:59.612353Z","steps":["trace[1810425077] 'agreement among raft nodes before linearized reading' (duration: 123.603737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:59.612774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.98301ms","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-08-13T01:08:59.612805Z","caller":"traceutil/trace.go:171","msg":"trace[1319276424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:959; }","duration":"122.024302ms","start":"2026-08-13T01:08:59.490765Z","end":"2026-08-13T01:08:59.61279Z","steps":["trace[1319276424] 'agreement among raft nodes before linearized reading' (duration: 121.965391ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:59.612917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.030845ms","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-08-13T01:08:59.612933Z","caller":"traceutil/trace.go:171","msg":"trace[11093050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:959; }","duration":"123.104486ms","start":"2026-08-13T01:08:59.489824Z","end":"2026-08-13T01:08:59.612928Z","steps":["trace[11093050] 'agreement among raft nodes before linearized reading' (duration: 123.044425ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:59.984139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.918991ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T01:08:59.984393Z","caller":"traceutil/trace.go:171","msg":"trace[487698145] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"115.635993ms","start":"2026-08-13T01:08:59.868739Z","end":"2026-08-13T01:08:59.984375Z","steps":["trace[487698145] 'process raft request' (duration: 115.534251ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:08:59.984371Z","caller":"traceutil/trace.go:171","msg":"trace[1732676910] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"206.565148ms","start":"2026-08-13T01:08:59.777776Z","end":"2026-08-13T01:08:59.984341Z","steps":["trace[1732676910] 'process raft request' (duration: 103.314739ms)","trace[1732676910] 'compare' (duration: 102.641815ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:09:00.84611Z","caller":"traceutil/trace.go:171","msg":"trace[1192340535] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"121.493829ms","start":"2026-08-13T01:09:00.724576Z","end":"2026-08-13T01:09:00.84607Z","steps":["trace[1192340535] 'process raft request' (duration: 121.248733ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:09:01.308735Z","caller":"traceutil/trace.go:171","msg":"trace[1522771649] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1011; }","duration":"179.540986ms","start":"2026-08-13T01:09:01.129113Z","end":"2026-08-13T01:09:01.308654Z","steps":["trace[1522771649] 'read index received' (duration: 179.24613ms)","trace[1522771649] 'applied index is now lower than readState.Index' (duration: 293.426µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:09:01.308818Z","caller":"traceutil/trace.go:171","msg":"trace[57551671] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"306.49157ms","start":"2026-08-13T01:09:01.002275Z","end":"2026-08-13T01:09:01.308767Z","steps":["trace[57551671] 'process raft request' (duration: 306.118262ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:01.308925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.789352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:09:01.308969Z","caller":"traceutil/trace.go:171","msg":"trace[1732307680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:976; }","duration":"179.906584ms","start":"2026-08-13T01:09:01.12905Z","end":"2026-08-13T01:09:01.308956Z","steps":["trace[1732307680] 'agreement among raft nodes before linearized reading' (duration: 179.773381ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:01.309053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:01.002254Z","time spent":"306.678515ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:09:02.497393Z","caller":"traceutil/trace.go:171","msg":"trace[1732092859] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1016; }","duration":"370.045305ms","start":"2026-08-13T01:09:02.127324Z","end":"2026-08-13T01:09:02.497369Z","steps":["trace[1732092859] 'read index received' (duration: 280.393859ms)","trace[1732092859] 'applied index is now lower than readState.Index' (duration: 89.650306ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:09:02.497478Z","caller":"traceutil/trace.go:171","msg":"trace[1255402980] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"508.518903ms","start":"2026-08-13T01:09:01.988924Z","end":"2026-08-13T01:09:02.497443Z","steps":["trace[1255402980] 'process raft request' (duration: 508.054083ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:02.497572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.599403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-13T01:09:02.497585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.209478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:09:02.497612Z","caller":"traceutil/trace.go:171","msg":"trace[1994334918] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:981; }","duration":"184.695915ms","start":"2026-08-13T01:09:02.312903Z","end":"2026-08-13T01:09:02.497599Z","steps":["trace[1994334918] 'agreement among raft nodes before linearized reading' (duration: 184.612403ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:09:02.497631Z","caller":"traceutil/trace.go:171","msg":"trace[2040080984] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:981; }","duration":"370.337081ms","start":"2026-08-13T01:09:02.12728Z","end":"2026-08-13T01:09:02.497617Z","steps":["trace[2040080984] 'agreement among raft nodes before linearized reading' (duration: 370.201578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:02.497664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:02.127263Z","time spent":"370.393472ms","remote":"127.0.0.1:51544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-13T01:09:02.497673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:01.988894Z","time spent":"508.674567ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:09:02.49765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.298927ms","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":"info","ts":"2026-08-13T01:09:02.497774Z","caller":"traceutil/trace.go:171","msg":"trace[155704192] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:981; }","duration":"142.440711ms","start":"2026-08-13T01:09:02.355284Z","end":"2026-08-13T01:09:02.497725Z","steps":["trace[155704192] 'agreement among raft nodes before linearized reading' (duration: 142.256357ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:09:03.424038Z","caller":"traceutil/trace.go:171","msg":"trace[1667344602] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"107.301332ms","start":"2026-08-13T01:09:03.316714Z","end":"2026-08-13T01:09:03.424015Z","steps":["trace[1667344602] 'process raft request' (duration: 107.147048ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:07.016307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.754418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-13T01:09:07.016364Z","caller":"traceutil/trace.go:171","msg":"trace[276317605] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:994; }","duration":"152.86449ms","start":"2026-08-13T01:09:06.863486Z","end":"2026-08-13T01:09:07.01635Z","steps":["trace[276317605] 'range keys from in-memory index tree' (duration: 152.624745ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:09:23.840458Z","caller":"traceutil/trace.go:171","msg":"trace[792584059] linearizableReadLoop","detail":"{readStateIndex:1096; appliedIndex:1095; }","duration":"101.247222ms","start":"2026-08-13T01:09:23.739187Z","end":"2026-08-13T01:09:23.840434Z","steps":["trace[792584059] 'read index received' (duration: 101.108889ms)","trace[792584059] 'applied index is now lower than readState.Index' (duration: 137.373µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:09:23.84053Z","caller":"traceutil/trace.go:171","msg":"trace[588709383] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"107.637539ms","start":"2026-08-13T01:09:23.732836Z","end":"2026-08-13T01:09:23.840474Z","steps":["trace[588709383] 'process raft request' (duration: 107.466625ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:23.840751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.491977ms","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-08-13T01:09:23.840876Z","caller":"traceutil/trace.go:171","msg":"trace[1943506653] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1054; }","duration":"101.675621ms","start":"2026-08-13T01:09:23.739183Z","end":"2026-08-13T01:09:23.840858Z","steps":["trace[1943506653] 'agreement among raft nodes before linearized reading' (duration: 101.349974ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:09:32.785095Z","caller":"traceutil/trace.go:171","msg":"trace[1680795379] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"105.142002ms","start":"2026-08-13T01:09:32.67993Z","end":"2026-08-13T01:09:32.785072Z","steps":["trace[1680795379] 'process raft request' (duration: 104.951127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:33.344205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.016515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:09:33.344269Z","caller":"traceutil/trace.go:171","msg":"trace[1879067270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1085; }","duration":"212.090286ms","start":"2026-08-13T01:09:33.132165Z","end":"2026-08-13T01:09:33.344255Z","steps":["trace[1879067270] 'range keys from in-memory index tree' (duration: 211.925172ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:09:34.1288Z","caller":"traceutil/trace.go:171","msg":"trace[1767676047] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1130; }","duration":"241.79055ms","start":"2026-08-13T01:09:33.886974Z","end":"2026-08-13T01:09:34.128764Z","steps":["trace[1767676047] 'read index received' (duration: 241.661707ms)","trace[1767676047] 'applied index is now lower than readState.Index' (duration: 128.033µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:09:34.128902Z","caller":"traceutil/trace.go:171","msg":"trace[28777340] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"246.14317ms","start":"2026-08-13T01:09:33.882751Z","end":"2026-08-13T01:09:34.128894Z","steps":["trace[28777340] 'process raft request' (duration: 245.877724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:34.129156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.153151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T01:09:34.129232Z","caller":"traceutil/trace.go:171","msg":"trace[1850368382] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1087; }","duration":"233.261414ms","start":"2026-08-13T01:09:33.895954Z","end":"2026-08-13T01:09:34.129215Z","steps":["trace[1850368382] 'agreement among raft nodes before linearized reading' (duration: 233.08274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:34.129348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.355443ms","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-08-13T01:09:34.129379Z","caller":"traceutil/trace.go:171","msg":"trace[572316166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1087; }","duration":"242.412165ms","start":"2026-08-13T01:09:33.886959Z","end":"2026-08-13T01:09:34.129371Z","steps":["trace[572316166] 'agreement among raft nodes before linearized reading' (duration: 242.289732ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:34.129417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.729045ms","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-08-13T01:09:34.129453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.953094ms","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-08-13T01:09:34.129492Z","caller":"traceutil/trace.go:171","msg":"trace[496812319] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1087; }","duration":"227.841578ms","start":"2026-08-13T01:09:33.901633Z","end":"2026-08-13T01:09:34.129474Z","steps":["trace[496812319] 'agreement among raft nodes before linearized reading' (duration: 227.605783ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:09:34.129528Z","caller":"traceutil/trace.go:171","msg":"trace[1084310429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1087; }","duration":"229.057356ms","start":"2026-08-13T01:09:33.900452Z","end":"2026-08-13T01:09:34.129509Z","steps":["trace[1084310429] 'agreement among raft nodes before linearized reading' (duration: 228.814371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:35.097456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.32097ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T01:09:35.097582Z","caller":"traceutil/trace.go:171","msg":"trace[39214959] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"964.204223ms","start":"2026-08-13T01:09:34.133343Z","end":"2026-08-13T01:09:35.097547Z","steps":["trace[39214959] 'process raft request' (duration: 303.556554ms)","trace[39214959] 'compare' (duration: 660.145666ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:09:35.09816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:34.13333Z","time spent":"964.292844ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:09:35.3919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.705833ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T01:09:35.392091Z","caller":"traceutil/trace.go:171","msg":"trace[123858096] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1131; }","duration":"1.084171366s","start":"2026-08-13T01:09:34.307904Z","end":"2026-08-13T01:09:35.392076Z","steps":["trace[123858096] 'read index received' (duration: 129.013472ms)","trace[123858096] 'applied index is now lower than readState.Index' (duration: 955.156844ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:09:35.392175Z","caller":"traceutil/trace.go:171","msg":"trace[1887114498] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"1.258413261s","start":"2026-08-13T01:09:34.133751Z","end":"2026-08-13T01:09:35.392164Z","steps":["trace[1887114498] 'process raft request' (duration: 1.258241937s)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:09:35.392197Z","caller":"traceutil/trace.go:171","msg":"trace[1350871992] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"1.258716957s","start":"2026-08-13T01:09:34.133458Z","end":"2026-08-13T01:09:35.392175Z","steps":["trace[1350871992] 'process raft request' (duration: 1.064633065s)","trace[1350871992] 'compare' (duration: 193.589121ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:09:35.39223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:34.133731Z","time spent":"1.258469511s","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:09:35.392309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:34.133431Z","time spent":"1.258815289s","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:09:35.392535Z","caller":"traceutil/trace.go:171","msg":"trace[783799305] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"600.684758ms","start":"2026-08-13T01:09:34.791842Z","end":"2026-08-13T01:09:35.392527Z","steps":["trace[783799305] 'process raft request' (duration: 600.205747ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:35.392576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:34.79182Z","time spent":"600.730549ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:09:35.392605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084704827s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T01:09:35.392638Z","caller":"traceutil/trace.go:171","msg":"trace[762659891] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1091; }","duration":"1.084781499s","start":"2026-08-13T01:09:34.307847Z","end":"2026-08-13T01:09:35.392628Z","steps":["trace[762659891] 'agreement among raft nodes before linearized reading' (duration: 1.084705027s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:35.392667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:34.307817Z","time spent":"1.084842831s","remote":"127.0.0.1:52000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-08-13T01:09:35.392852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.513003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:09:35.392888Z","caller":"traceutil/trace.go:171","msg":"trace[761541285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1092; }","duration":"264.592545ms","start":"2026-08-13T01:09:35.128287Z","end":"2026-08-13T01:09:35.39288Z","steps":["trace[761541285] 'agreement among raft nodes before linearized reading' (duration: 264.531773ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:09:35.392924Z","caller":"traceutil/trace.go:171","msg":"trace[928433072] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"595.942899ms","start":"2026-08-13T01:09:34.796973Z","end":"2026-08-13T01:09:35.392916Z","steps":["trace[928433072] 'process raft request' (duration: 595.469208ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:35.392968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:34.796965Z","time spent":"595.982129ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:09:35.393103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.645816ms","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-08-13T01:09:35.393194Z","caller":"traceutil/trace.go:171","msg":"trace[1384244977] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1092; }","duration":"152.84054ms","start":"2026-08-13T01:09:35.24034Z","end":"2026-08-13T01:09:35.39318Z","steps":["trace[1384244977] 'agreement among raft nodes before linearized reading' (duration: 152.591814ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:35.779565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.897808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T01:09:35.779603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.620404ms","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-08-13T01:09:35.77986Z","caller":"traceutil/trace.go:171","msg":"trace[1738258315] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1092; }","duration":"201.313718ms","start":"2026-08-13T01:09:35.578521Z","end":"2026-08-13T01:09:35.779835Z","steps":["trace[1738258315] 'count revisions from in-memory index tree' (duration: 200.816097ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:09:35.779913Z","caller":"traceutil/trace.go:171","msg":"trace[1595247457] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1092; }","duration":"125.978302ms","start":"2026-08-13T01:09:35.653915Z","end":"2026-08-13T01:09:35.779893Z","steps":["trace[1595247457] 'range keys from in-memory index tree' (duration: 125.43385ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:09:36.300095Z","caller":"traceutil/trace.go:171","msg":"trace[80277072] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"163.801304ms","start":"2026-08-13T01:09:36.136184Z","end":"2026-08-13T01:09:36.299985Z","steps":["trace[80277072] 'process raft request' (duration: 163.606369ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:09:37.509384Z","caller":"traceutil/trace.go:171","msg":"trace[1816499655] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"103.852514ms","start":"2026-08-13T01:09:37.40549Z","end":"2026-08-13T01:09:37.509343Z","steps":["trace[1816499655] 'process raft request' (duration: 103.613788ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:57.243271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.621334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T01:09:57.243331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.276034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-13T01:09:57.243351Z","caller":"traceutil/trace.go:171","msg":"trace[596802467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1219; }","duration":"113.739397ms","start":"2026-08-13T01:09:57.129595Z","end":"2026-08-13T01:09:57.243334Z","steps":["trace[596802467] 'range keys from in-memory index tree' (duration: 113.549713ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:09:57.243388Z","caller":"traceutil/trace.go:171","msg":"trace[130128848] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:1219; }","duration":"378.372287ms","start":"2026-08-13T01:09:56.865001Z","end":"2026-08-13T01:09:57.243373Z","steps":["trace[130128848] 'range keys from in-memory index tree' (duration: 378.09092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:57.243429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:56.864944Z","time spent":"378.469999ms","remote":"127.0.0.1:51572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"info","ts":"2026-08-13T01:09:57.720115Z","caller":"traceutil/trace.go:171","msg":"trace[925753470] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1269; }","duration":"286.915764ms","start":"2026-08-13T01:09:57.433176Z","end":"2026-08-13T01:09:57.720092Z","steps":["trace[925753470] 'read index received' (duration: 286.858023ms)","trace[925753470] 'applied index is now lower than readState.Index' (duration: 56.501µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:09:57.720148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:57.24461Z","time spent":"475.5318ms","remote":"127.0.0.1:51572","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-13T01:09:57.720328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.131789ms","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-08-13T01:09:57.720373Z","caller":"traceutil/trace.go:171","msg":"trace[97339392] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1219; }","duration":"287.219121ms","start":"2026-08-13T01:09:57.433142Z","end":"2026-08-13T01:09:57.720362Z","steps":["trace[97339392] 'agreement among raft nodes before linearized reading' (duration: 287.056148ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:59.586953Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.442106439s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-13T01:09:59.590725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.445859336s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T01:09:59.591105Z","caller":"traceutil/trace.go:171","msg":"trace[265758076] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"1.866525789s","start":"2026-08-13T01:09:57.724559Z","end":"2026-08-13T01:09:59.591085Z","steps":["trace[265758076] 'process raft request' (duration: 1.866471337s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:59.591289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:57.724477Z","time spent":"1.866763564s","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:09:59.591702Z","caller":"traceutil/trace.go:171","msg":"trace[1610206111] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"1.925620383s","start":"2026-08-13T01:09:57.666055Z","end":"2026-08-13T01:09:59.591675Z","steps":["trace[1610206111] 'process raft request' (duration: 478.706534ms)","trace[1610206111] 'compare' (duration: 1.445698481s)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:09:59.59178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:57.666021Z","time spent":"1.925710215s","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:09:59.592306Z","caller":"traceutil/trace.go:171","msg":"trace[786541383] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"1.870774736s","start":"2026-08-13T01:09:57.721489Z","end":"2026-08-13T01:09:59.592263Z","steps":["trace[786541383] 'process raft request' (duration: 1.869403245s)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:09:59.592416Z","caller":"traceutil/trace.go:171","msg":"trace[137882822] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1270; }","duration":"1.87221868s","start":"2026-08-13T01:09:57.720177Z","end":"2026-08-13T01:09:59.592395Z","steps":["trace[137882822] 'read index received' (duration: 424.705937ms)","trace[137882822] 'applied index is now lower than readState.Index' (duration: 1.447510083s)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:09:59.592476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:57.721467Z","time spent":"1.870915949s","remote":"127.0.0.1:51572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:09:59.592693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.936203478s","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-08-13T01:09:59.592728Z","caller":"traceutil/trace.go:171","msg":"trace[403987478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1222; }","duration":"1.936280081s","start":"2026-08-13T01:09:57.656438Z","end":"2026-08-13T01:09:59.592718Z","steps":["trace[403987478] 'agreement among raft nodes before linearized reading' (duration: 1.936129456s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:59.592762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:57.656422Z","time spent":"1.936333461s","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T01:09:59.592807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.923222898s","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-08-13T01:09:59.592884Z","caller":"traceutil/trace.go:171","msg":"trace[499216810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1222; }","duration":"1.923336341s","start":"2026-08-13T01:09:57.669528Z","end":"2026-08-13T01:09:59.592865Z","steps":["trace[499216810] 'agreement among raft nodes before linearized reading' (duration: 1.923026783s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:59.592935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:57.669516Z","time spent":"1.923408422s","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-13T01:09:59.592952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.65084624s","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-08-13T01:09:59.592981Z","caller":"traceutil/trace.go:171","msg":"trace[1174316496] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1222; }","duration":"1.650919071s","start":"2026-08-13T01:09:57.942054Z","end":"2026-08-13T01:09:59.592973Z","steps":["trace[1174316496] 'agreement among raft nodes before linearized reading' (duration: 1.650832529s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:59.593009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:57.942028Z","time spent":"1.650974572s","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-13T01:09:59.593073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.569981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:09:59.593125Z","caller":"traceutil/trace.go:171","msg":"trace[813922337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1222; }","duration":"465.619812ms","start":"2026-08-13T01:09:59.12749Z","end":"2026-08-13T01:09:59.59311Z","steps":["trace[813922337] 'agreement among raft nodes before linearized reading' (duration: 465.52881ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:59.593128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.176788983s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-13T01:09:59.593157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:59.127441Z","time spent":"465.709684ms","remote":"127.0.0.1:51544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-13T01:09:59.593158Z","caller":"traceutil/trace.go:171","msg":"trace[2100917504] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1222; }","duration":"1.176852993s","start":"2026-08-13T01:09:58.416296Z","end":"2026-08-13T01:09:59.593149Z","steps":["trace[2100917504] 'agreement among raft nodes before linearized reading' (duration: 1.176770442s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:59.593185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:58.416276Z","time spent":"1.176904665s","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-13T01:10:00.099679Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221825702274420,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-13T01:10:00.598961Z","caller":"traceutil/trace.go:171","msg":"trace[1389405040] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"1.000863202s","start":"2026-08-13T01:09:59.598069Z","end":"2026-08-13T01:10:00.598932Z","steps":["trace[1389405040] 'process raft request' (duration: 991.218119ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:10:00.599288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:59.598059Z","time spent":"1.000982785s","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:10:00.602935Z","caller":"traceutil/trace.go:171","msg":"trace[651384934] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"1.004496894s","start":"2026-08-13T01:09:59.598415Z","end":"2026-08-13T01:10:00.602912Z","steps":["trace[651384934] 'process raft request' (duration: 1.004331511s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:10:00.603073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:59.598407Z","time spent":"1.004594667s","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:10:00.603331Z","caller":"traceutil/trace.go:171","msg":"trace[1384449339] linearizableReadLoop","detail":"{readStateIndex:1277; appliedIndex:1273; }","duration":"1.00431606s","start":"2026-08-13T01:09:59.598997Z","end":"2026-08-13T01:10:00.603313Z","steps":["trace[1384449339] 'read index received' (duration: 990.269615ms)","trace[1384449339] 'applied index is now lower than readState.Index' (duration: 14.045695ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:10:00.603333Z","caller":"traceutil/trace.go:171","msg":"trace[608169834] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"1.004856763s","start":"2026-08-13T01:09:59.598458Z","end":"2026-08-13T01:10:00.603314Z","steps":["trace[608169834] 'process raft request' (duration: 1.004411713s)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:10:00.603376Z","caller":"traceutil/trace.go:171","msg":"trace[1249418110] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"1.004166277s","start":"2026-08-13T01:09:59.599195Z","end":"2026-08-13T01:10:00.603361Z","steps":["trace[1249418110] 'process raft request' (duration: 1.004028224s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:10:00.603437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:59.598453Z","time spent":"1.004940084s","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:10:00.603457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.114315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T01:10:00.603454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:59.599143Z","time spent":"1.004264069s","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:10:00.60348Z","caller":"traceutil/trace.go:171","msg":"trace[1692920976] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1226; }","duration":"881.182957ms","start":"2026-08-13T01:09:59.72229Z","end":"2026-08-13T01:10:00.603473Z","steps":["trace[1692920976] 'agreement among raft nodes before linearized reading' (duration: 881.127676ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:10:00.603502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:59.722274Z","time spent":"881.223048ms","remote":"127.0.0.1:51862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-08-13T01:10:00.603574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.970606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T01:10:00.603552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004536375s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-13T01:10:00.603611Z","caller":"traceutil/trace.go:171","msg":"trace[405999580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1226; }","duration":"475.030218ms","start":"2026-08-13T01:10:00.128568Z","end":"2026-08-13T01:10:00.603598Z","steps":["trace[405999580] 'agreement among raft nodes before linearized reading' (duration: 474.965427ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:10:00.60363Z","caller":"traceutil/trace.go:171","msg":"trace[739804809] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1226; }","duration":"1.004644177s","start":"2026-08-13T01:09:59.598968Z","end":"2026-08-13T01:10:00.603613Z","steps":["trace[739804809] 'agreement among raft nodes before linearized reading' (duration: 1.004433253s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:10:00.603639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:10:00.128553Z","time spent":"475.081389ms","remote":"127.0.0.1:51544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-13T01:10:00.603672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:59.598958Z","time spent":"1.004705659s","remote":"127.0.0.1:51854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-08-13T01:10:09.211954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.854436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T01:10:09.212089Z","caller":"traceutil/trace.go:171","msg":"trace[262783911] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"475.798886ms","start":"2026-08-13T01:10:08.736276Z","end":"2026-08-13T01:10:09.212075Z","steps":["trace[262783911] 'process raft request' (duration: 213.777406ms)","trace[262783911] 'compare' (duration: 261.631241ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:10:09.212138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:10:08.736264Z","time spent":"475.855778ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:10:09.299738Z","caller":"traceutil/trace.go:171","msg":"trace[1722378155] linearizableReadLoop","detail":"{readStateIndex:1306; appliedIndex:1304; }","duration":"171.07362ms","start":"2026-08-13T01:10:09.128644Z","end":"2026-08-13T01:10:09.299718Z","steps":["trace[1722378155] 'read index received' (duration: 170.832854ms)","trace[1722378155] 'applied index is now lower than readState.Index' (duration: 239.826µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:10:09.299829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.183313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:10:09.299861Z","caller":"traceutil/trace.go:171","msg":"trace[821491168] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1253; }","duration":"171.252275ms","start":"2026-08-13T01:10:09.128599Z","end":"2026-08-13T01:10:09.299851Z","steps":["trace[821491168] 'agreement among raft nodes before linearized reading' (duration: 171.193834ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:10:09.299872Z","caller":"traceutil/trace.go:171","msg":"trace[315673626] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"562.470708ms","start":"2026-08-13T01:10:08.737369Z","end":"2026-08-13T01:10:09.29984Z","steps":["trace[315673626] 'process raft request' (duration: 562.310384ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:10:09.300033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:10:08.737338Z","time spent":"562.607791ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:10:09.300207Z","caller":"traceutil/trace.go:171","msg":"trace[1044252197] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"562.821456ms","start":"2026-08-13T01:10:08.737371Z","end":"2026-08-13T01:10:09.300192Z","steps":["trace[1044252197] 'process raft request' (duration: 562.187521ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:10:09.300707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:10:08.73736Z","time spent":"562.874908ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:10:09.938295Z","caller":"traceutil/trace.go:171","msg":"trace[1819459186] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"151.235302ms","start":"2026-08-13T01:10:09.787038Z","end":"2026-08-13T01:10:09.938273Z","steps":["trace[1819459186] 'process raft request' (duration: 151.075858ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:10:11.596644Z","caller":"traceutil/trace.go:171","msg":"trace[990456177] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1309; }","duration":"292.793092ms","start":"2026-08-13T01:10:11.303825Z","end":"2026-08-13T01:10:11.596618Z","steps":["trace[990456177] 'read index received' (duration: 292.478344ms)","trace[990456177] 'applied index is now lower than readState.Index' (duration: 313.628µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:10:11.596784Z","caller":"traceutil/trace.go:171","msg":"trace[1443827704] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"378.555932ms","start":"2026-08-13T01:10:11.218212Z","end":"2026-08-13T01:10:11.596768Z","steps":["trace[1443827704] 'process raft request' (duration: 378.161142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:10:11.596882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.614207ms","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-08-13T01:10:11.596925Z","caller":"traceutil/trace.go:171","msg":"trace[1319466617] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1257; }","duration":"292.675338ms","start":"2026-08-13T01:10:11.30424Z","end":"2026-08-13T01:10:11.596915Z","steps":["trace[1319466617] 'agreement among raft nodes before linearized reading' (duration: 292.569276ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:10:11.596916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:10:11.218198Z","time spent":"378.625033ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:10:11.597041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.222531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-13T01:10:11.597124Z","caller":"traceutil/trace.go:171","msg":"trace[676455599] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1257; }","duration":"293.339434ms","start":"2026-08-13T01:10:11.30377Z","end":"2026-08-13T01:10:11.597109Z","steps":["trace[676455599] 'agreement among raft nodes before linearized reading' (duration: 293.098578ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:10:11.7648Z","caller":"traceutil/trace.go:171","msg":"trace[1032281922] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"164.017017ms","start":"2026-08-13T01:10:11.600762Z","end":"2026-08-13T01:10:11.764779Z","steps":["trace[1032281922] 'process raft request' (duration: 130.641987ms)","trace[1032281922] 'compare' (duration: 33.146644ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:10:11.776553Z","caller":"traceutil/trace.go:171","msg":"trace[1630012486] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"117.224066ms","start":"2026-08-13T01:10:11.659309Z","end":"2026-08-13T01:10:11.776533Z","steps":["trace[1630012486] 'process raft request' (duration: 117.196826ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:10:11.776604Z","caller":"traceutil/trace.go:171","msg":"trace[235843192] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"174.92351ms","start":"2026-08-13T01:10:11.60166Z","end":"2026-08-13T01:10:11.776583Z","steps":["trace[235843192] 'process raft request' (duration: 174.743975ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:10:12.381644Z","caller":"traceutil/trace.go:171","msg":"trace[202634841] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"416.873395ms","start":"2026-08-13T01:10:11.964747Z","end":"2026-08-13T01:10:12.381621Z","steps":["trace[202634841] 'process raft request' (duration: 416.705672ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:10:12.381779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:10:11.964726Z","time spent":"416.984598ms","remote":"127.0.0.1:51738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5780,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:10:12.685183Z","caller":"traceutil/trace.go:171","msg":"trace[837124916] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"640.904029ms","start":"2026-08-13T01:10:12.044259Z","end":"2026-08-13T01:10:12.685163Z","steps":["trace[837124916] 'process raft request' (duration: 590.077165ms)","trace[837124916] 'compare' (duration: 50.585419ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:10:12.685282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:10:12.044236Z","time spent":"640.995151ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:10:12.693558Z","caller":"traceutil/trace.go:171","msg":"trace[1120154975] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"308.043672ms","start":"2026-08-13T01:10:12.385494Z","end":"2026-08-13T01:10:12.693537Z","steps":["trace[1120154975] 'process raft request' (duration: 307.792727ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:10:12.693688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:10:12.385473Z","time spent":"308.146135ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:10:13.731523Z","caller":"traceutil/trace.go:171","msg":"trace[1639361999] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"123.583574ms","start":"2026-08-13T01:10:13.607909Z","end":"2026-08-13T01:10:13.731492Z","steps":["trace[1639361999] 'process raft request' (duration: 123.432231ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:10:15.003032Z","caller":"traceutil/trace.go:171","msg":"trace[727416434] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"309.442965ms","start":"2026-08-13T01:10:14.693552Z","end":"2026-08-13T01:10:15.002995Z","steps":["trace[727416434] 'process raft request' (duration: 309.262841ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:10:15.003023Z","caller":"traceutil/trace.go:171","msg":"trace[509136638] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1322; }","duration":"190.843947ms","start":"2026-08-13T01:10:14.812155Z","end":"2026-08-13T01:10:15.002999Z","steps":["trace[509136638] 'read index received' (duration: 190.783156ms)","trace[509136638] 'applied index is now lower than readState.Index' (duration: 59.711µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:10:15.003214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:10:14.693529Z","time spent":"309.594479ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:10:15.003743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.579874ms","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-08-13T01:10:15.003797Z","caller":"traceutil/trace.go:171","msg":"trace[592167731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1269; }","duration":"191.683637ms","start":"2026-08-13T01:10:14.812099Z","end":"2026-08-13T01:10:15.003783Z","steps":["trace[592167731] 'agreement among raft nodes before linearized reading' (duration: 191.047272ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:10:15.363521Z","caller":"traceutil/trace.go:171","msg":"trace[230216112] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1323; }","duration":"235.271662ms","start":"2026-08-13T01:10:15.12822Z","end":"2026-08-13T01:10:15.363492Z","steps":["trace[230216112] 'read index received' (duration: 175.305458ms)","trace[230216112] 'applied index is now lower than readState.Index' (duration: 59.964974ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:10:15.363711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.484378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:10:15.363753Z","caller":"traceutil/trace.go:171","msg":"trace[1301705098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1270; }","duration":"235.572019ms","start":"2026-08-13T01:10:15.128169Z","end":"2026-08-13T01:10:15.363741Z","steps":["trace[1301705098] 'agreement among raft nodes before linearized reading' (duration: 235.417686ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:10:15.363802Z","caller":"traceutil/trace.go:171","msg":"trace[85871622] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"356.246056ms","start":"2026-08-13T01:10:15.007535Z","end":"2026-08-13T01:10:15.363781Z","steps":["trace[85871622] 'process raft request' (duration: 295.993315ms)","trace[85871622] 'compare' (duration: 59.620747ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:10:15.36393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:10:15.007467Z","time spent":"356.383649ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:10:16.08659Z","caller":"traceutil/trace.go:171","msg":"trace[1206984158] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1325; }","duration":"250.211587ms","start":"2026-08-13T01:10:15.836346Z","end":"2026-08-13T01:10:16.086558Z","steps":["trace[1206984158] 'read index received' (duration: 209.059228ms)","trace[1206984158] 'applied index is now lower than readState.Index' (duration: 41.151059ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:10:16.086641Z","caller":"traceutil/trace.go:171","msg":"trace[1303714276] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"275.877329ms","start":"2026-08-13T01:10:15.810733Z","end":"2026-08-13T01:10:16.086611Z","steps":["trace[1303714276] 'process raft request' (duration: 234.696879ms)","trace[1303714276] 'compare' (duration: 40.964366ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:10:16.086767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.412622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T01:10:16.086807Z","caller":"traceutil/trace.go:171","msg":"trace[1435464406] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1272; }","duration":"250.558985ms","start":"2026-08-13T01:10:15.836239Z","end":"2026-08-13T01:10:16.086798Z","steps":["trace[1435464406] 'agreement among raft nodes before linearized reading' (duration: 250.453542ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:10:16.086894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.805188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-13T01:10:16.086935Z","caller":"traceutil/trace.go:171","msg":"trace[1383342557] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1272; }","duration":"249.897301ms","start":"2026-08-13T01:10:15.837024Z","end":"2026-08-13T01:10:16.086921Z","steps":["trace[1383342557] 'agreement among raft nodes before linearized reading' (duration: 249.742257ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:10:16.086922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.164373ms","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-08-13T01:10:16.086979Z","caller":"traceutil/trace.go:171","msg":"trace[849063692] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1272; }","duration":"249.267276ms","start":"2026-08-13T01:10:15.837697Z","end":"2026-08-13T01:10:16.086965Z","steps":["trace[849063692] 'agreement among raft nodes before linearized reading' (duration: 249.120982ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:10:16.341752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.077946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T01:10:16.3419Z","caller":"traceutil/trace.go:171","msg":"trace[43512625] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1328; }","duration":"145.446528ms","start":"2026-08-13T01:10:16.196435Z","end":"2026-08-13T01:10:16.341882Z","steps":["trace[43512625] 'read index received' (duration: 27.186777ms)","trace[43512625] 'applied index is now lower than readState.Index' (duration: 118.25771ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:10:16.341945Z","caller":"traceutil/trace.go:171","msg":"trace[1898376348] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"191.00334ms","start":"2026-08-13T01:10:16.150902Z","end":"2026-08-13T01:10:16.341905Z","steps":["trace[1898376348] 'process raft request' (duration: 72.672638ms)","trace[1898376348] 'compare' (duration: 117.920363ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:10:16.342007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.56882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T01:10:16.342035Z","caller":"traceutil/trace.go:171","msg":"trace[985711030] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1275; }","duration":"145.648683ms","start":"2026-08-13T01:10:16.196377Z","end":"2026-08-13T01:10:16.342026Z","steps":["trace[985711030] 'agreement among raft nodes before linearized reading' (duration: 145.567121ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:10:18.257664Z","caller":"traceutil/trace.go:171","msg":"trace[1562098498] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1335; }","duration":"159.389421ms","start":"2026-08-13T01:10:18.098249Z","end":"2026-08-13T01:10:18.257639Z","steps":["trace[1562098498] 'read index received' (duration: 86.535168ms)","trace[1562098498] 'applied index is now lower than readState.Index' (duration: 72.852933ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:10:18.257694Z","caller":"traceutil/trace.go:171","msg":"trace[623380989] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"163.884174ms","start":"2026-08-13T01:10:18.093774Z","end":"2026-08-13T01:10:18.257658Z","steps":["trace[623380989] 'process raft request' (duration: 91.081413ms)","trace[623380989] 'compare' (duration: 72.593006ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:10:18.257886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.17841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T01:10:18.257994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.717598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-13T01:10:18.258031Z","caller":"traceutil/trace.go:171","msg":"trace[1892404054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1281; }","duration":"128.320742ms","start":"2026-08-13T01:10:18.129619Z","end":"2026-08-13T01:10:18.25794Z","steps":["trace[1892404054] 'agreement among raft nodes before linearized reading' (duration: 128.19766ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:10:18.258084Z","caller":"traceutil/trace.go:171","msg":"trace[1758978913] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1281; }","duration":"159.853511ms","start":"2026-08-13T01:10:18.098207Z","end":"2026-08-13T01:10:18.258061Z","steps":["trace[1758978913] 'agreement among raft nodes before linearized reading' (duration: 159.568265ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:10:18.702502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.733947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T01:10:18.702601Z","caller":"traceutil/trace.go:171","msg":"trace[535317809] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"438.344471ms","start":"2026-08-13T01:10:18.264239Z","end":"2026-08-13T01:10:18.702584Z","steps":["trace[535317809] 'process raft request' (duration: 175.43425ms)","trace[535317809] 'compare' (duration: 262.555893ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:10:18.702657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:10:18.264217Z","time spent":"438.414383ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:10:19.890931Z","caller":"traceutil/trace.go:171","msg":"trace[1551972826] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"142.375557ms","start":"2026-08-13T01:10:19.748525Z","end":"2026-08-13T01:10:19.890901Z","steps":["trace[1551972826] 'process raft request' (duration: 73.574709ms)","trace[1551972826] 'compare' (duration: 68.294647ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:10:20.22591Z","caller":"traceutil/trace.go:171","msg":"trace[638716729] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"112.392626ms","start":"2026-08-13T01:10:20.113474Z","end":"2026-08-13T01:10:20.225867Z","steps":["trace[638716729] 'process raft request' (duration: 112.274813ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:10:20.226131Z","caller":"traceutil/trace.go:171","msg":"trace[8492176] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"111.507215ms","start":"2026-08-13T01:10:20.114596Z","end":"2026-08-13T01:10:20.226104Z","steps":["trace[8492176] 'process raft request' (duration: 111.218328ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:10:21.359419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.017968ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T01:10:21.359531Z","caller":"traceutil/trace.go:171","msg":"trace[153000586] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"566.679026ms","start":"2026-08-13T01:10:20.792833Z","end":"2026-08-13T01:10:21.359512Z","steps":["trace[153000586] 'process raft request' (duration: 107.490994ms)","trace[153000586] 'compare' (duration: 458.874944ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:10:21.35959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:10:20.792794Z","time spent":"566.768597ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:10:21.45303Z","caller":"traceutil/trace.go:171","msg":"trace[639188526] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1357; }","duration":"329.198891ms","start":"2026-08-13T01:10:21.123798Z","end":"2026-08-13T01:10:21.452997Z","steps":["trace[639188526] 'read index received' (duration: 328.969806ms)","trace[639188526] 'applied index is now lower than readState.Index' (duration: 227.575µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:10:21.45311Z","caller":"traceutil/trace.go:171","msg":"trace[19452141] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"645.891544ms","start":"2026-08-13T01:10:20.807187Z","end":"2026-08-13T01:10:21.453078Z","steps":["trace[19452141] 'process raft request' (duration: 645.643609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:10:21.45327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:10:20.807138Z","time spent":"646.052148ms","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":783,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T01:10:21.453316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.54424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T01:10:21.453495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.665813ms","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-08-13T01:10:21.453491Z","caller":"traceutil/trace.go:171","msg":"trace[1789299590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1303; }","duration":"323.853568ms","start":"2026-08-13T01:10:21.129624Z","end":"2026-08-13T01:10:21.453477Z","steps":["trace[1789299590] 'agreement among raft nodes before linearized reading' (duration: 323.584032ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:10:21.453602Z","caller":"traceutil/trace.go:171","msg":"trace[230270164] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1303; }","duration":"329.813506ms","start":"2026-08-13T01:10:21.123758Z","end":"2026-08-13T01:10:21.453572Z","steps":["trace[230270164] 'agreement among raft nodes before linearized reading' (duration: 329.417357ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:10:21.453625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:10:21.12952Z","time spent":"324.092213ms","remote":"127.0.0.1:51544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-13T01:10:21.453664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:10:21.12374Z","time spent":"329.912208ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-13T01:10:23.747117Z","caller":"traceutil/trace.go:171","msg":"trace[176773321] linearizableReadLoop","detail":"{readStateIndex:1369; appliedIndex:1368; }","duration":"163.891055ms","start":"2026-08-13T01:10:23.583183Z","end":"2026-08-13T01:10:23.747074Z","steps":["trace[176773321] 'read index received' (duration: 162.048052ms)","trace[176773321] 'applied index is now lower than readState.Index' (duration: 1.842113ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:10:23.747539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.313384ms","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-08-13T01:10:23.747575Z","caller":"traceutil/trace.go:171","msg":"trace[1389662235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1313; }","duration":"164.408837ms","start":"2026-08-13T01:10:23.583157Z","end":"2026-08-13T01:10:23.747566Z","steps":["trace[1389662235] 'agreement among raft nodes before linearized reading' (duration: 164.035989ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:10:23.747376Z","caller":"traceutil/trace.go:171","msg":"trace[1059576722] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"190.208742ms","start":"2026-08-13T01:10:23.557124Z","end":"2026-08-13T01:10:23.747333Z","steps":["trace[1059576722] 'process raft request' (duration: 188.145554ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:10:25.911635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.123361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:10:25.911743Z","caller":"traceutil/trace.go:171","msg":"trace[698621370] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1332; }","duration":"177.285934ms","start":"2026-08-13T01:10:25.734431Z","end":"2026-08-13T01:10:25.911716Z","steps":["trace[698621370] 'range keys from in-memory index tree' (duration: 176.944836ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:10:25.912644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.766479ms","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-08-13T01:10:25.912696Z","caller":"traceutil/trace.go:171","msg":"trace[729599775] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1332; }","duration":"149.863681ms","start":"2026-08-13T01:10:25.762821Z","end":"2026-08-13T01:10:25.912684Z","steps":["trace[729599775] 'range keys from in-memory index tree' (duration: 149.40762ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:10:25.912858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.81786ms","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-08-13T01:10:25.912888Z","caller":"traceutil/trace.go:171","msg":"trace[148178597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1332; }","duration":"149.883181ms","start":"2026-08-13T01:10:25.762993Z","end":"2026-08-13T01:10:25.912876Z","steps":["trace[148178597] 'range keys from in-memory index tree' (duration: 149.676666ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:13:40.905376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.398277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T01:13:40.90551Z","caller":"traceutil/trace.go:171","msg":"trace[858848041] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:2368; }","duration":"575.599501ms","start":"2026-08-13T01:13:40.329878Z","end":"2026-08-13T01:13:40.905478Z","steps":["trace[858848041] 'count revisions from in-memory index tree' (duration: 575.315605ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:13:40.905576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:13:40.329852Z","time spent":"575.706024ms","remote":"127.0.0.1:41048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":5,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-08-13T01:13:40.905844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.731801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:13:40.905877Z","caller":"traceutil/trace.go:171","msg":"trace[976471992] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:2368; }","duration":"382.822174ms","start":"2026-08-13T01:13:40.523045Z","end":"2026-08-13T01:13:40.905867Z","steps":["trace[976471992] 'count revisions from in-memory index tree' (duration: 382.63738ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:13:40.905915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:13:40.523016Z","time spent":"382.887064ms","remote":"127.0.0.1:41096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-08-13T01:13:40.905891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.394388ms","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-08-13T01:13:40.905997Z","caller":"traceutil/trace.go:171","msg":"trace[501678289] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2368; }","duration":"445.513972ms","start":"2026-08-13T01:13:40.460455Z","end":"2026-08-13T01:13:40.905968Z","steps":["trace[501678289] 'range keys from in-memory index tree' (duration: 445.020861ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:13:40.906045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:13:40.460366Z","time spent":"445.668414ms","remote":"127.0.0.1:51840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}