{"level":"warn","ts":"2026-05-31T05:49:01.432671Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-31T05:49:01.432758Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205: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.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-31T05:49:01.432953Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-31T05:49:01.432976Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-05-31T05:49:01.433051Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-31T05:49:01.43391Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-05-31T05:49:01.434337Z","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.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-31T05:49:01.436998Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.355915ms"} {"level":"info","ts":"2026-05-31T05:49:01.446056Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-05-31T05:49:01.446143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-05-31T05:49:01.446183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-05-31T05:49:01.446194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-31T05:49:01.446207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-05-31T05:49:01.446246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-05-31T05:49:01.454568Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-31T05:49:01.462769Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-31T05:49:01.464484Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-31T05:49:01.470033Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-31T05:49:01.470277Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-31T05:49:01.470333Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-31T05:49:01.470348Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-31T05:49:01.470464Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-31T05:49:01.471106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-05-31T05:49:01.471228Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-05-31T05:49:01.473343Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-31T05:49:01.473496Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-05-31T05:49:01.473538Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-05-31T05:49:01.473766Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-31T05:49:01.473814Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-31T05:49:02.346472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-05-31T05:49:02.346534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-31T05:49:02.346583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-05-31T05:49:02.346604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-05-31T05:49:02.346619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-05-31T05:49:02.346647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-05-31T05:49:02.346667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-05-31T05:49:02.347601Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-31T05:49:02.34849Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-31T05:49:02.348488Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-05-31T05:49:02.348737Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-31T05:49:02.348893Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-31T05:49:02.348995Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-31T05:49:02.349153Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-31T05:49:02.349323Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-31T05:49:02.349391Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-31T05:49:02.351753Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-31T05:49:02.352876Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"warn","ts":"2026-05-31T05:49:20.959841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.529536ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-31T05:49:20.959941Z","caller":"traceutil/trace.go:171","msg":"trace[1144329986] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:436; }","duration":"160.327436ms","start":"2026-05-31T05:49:20.7996Z","end":"2026-05-31T05:49:20.959927Z","steps":["trace[1144329986] 'read index received' (duration: 16.694177ms)","trace[1144329986] 'applied index is now lower than readState.Index' (duration: 143.632279ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:49:20.960036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.413518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:49:20.960054Z","caller":"traceutil/trace.go:171","msg":"trace[1592273093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:423; }","duration":"160.52468ms","start":"2026-05-31T05:49:20.799525Z","end":"2026-05-31T05:49:20.960049Z","steps":["trace[1592273093] 'agreement among raft nodes before linearized reading' (duration: 160.444579ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:49:21.287721Z","caller":"traceutil/trace.go:171","msg":"trace[1658328205] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"141.274362ms","start":"2026-05-31T05:49:21.146423Z","end":"2026-05-31T05:49:21.287697Z","steps":["trace[1658328205] 'process raft request' (duration: 141.118559ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:49:22.046767Z","caller":"traceutil/trace.go:171","msg":"trace[3545669] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"144.320624ms","start":"2026-05-31T05:49:21.902414Z","end":"2026-05-31T05:49:22.046735Z","steps":["trace[3545669] 'process raft request' (duration: 144.16235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:49:22.551787Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843765690370486,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-31T05:49:23.052987Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843765690370486,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-31T05:49:23.093079Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.04650649s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-31T05:49:23.310624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.031301ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:49:23.310775Z","caller":"traceutil/trace.go:171","msg":"trace[1582156043] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:425; }","duration":"812.185794ms","start":"2026-05-31T05:49:22.498559Z","end":"2026-05-31T05:49:23.310745Z","steps":["trace[1582156043] 'range keys from in-memory index tree' (duration: 812.01403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:49:23.311786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.663718ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:49:23.311946Z","caller":"traceutil/trace.go:171","msg":"trace[1405226727] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"1.405683687s","start":"2026-05-31T05:49:21.906239Z","end":"2026-05-31T05:49:23.311923Z","steps":["trace[1405226727] 'process raft request' (duration: 1.187105067s)","trace[1405226727] 'compare' (duration: 217.333831ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:49:23.311952Z","caller":"traceutil/trace.go:171","msg":"trace[1041560907] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:439; }","duration":"1.260487472s","start":"2026-05-31T05:49:22.051447Z","end":"2026-05-31T05:49:23.311934Z","steps":["trace[1041560907] 'read index received' (duration: 1.041841351s)","trace[1041560907] 'applied index is now lower than readState.Index' (duration: 218.644211ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:49:23.312086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:21.906222Z","time spent":"1.40578243s","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:49:23.312106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.260662517s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-31T05:49:23.312269Z","caller":"traceutil/trace.go:171","msg":"trace[1508562018] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:426; }","duration":"1.260856081s","start":"2026-05-31T05:49:22.0514Z","end":"2026-05-31T05:49:23.312256Z","steps":["trace[1508562018] 'agreement among raft nodes before linearized reading' (duration: 1.260581765s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:49:23.312318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.721445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:49:23.312406Z","caller":"traceutil/trace.go:171","msg":"trace[1331506369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:426; }","duration":"512.806627ms","start":"2026-05-31T05:49:22.79958Z","end":"2026-05-31T05:49:23.312387Z","steps":["trace[1331506369] 'agreement among raft nodes before linearized reading' (duration: 512.681244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:49:23.312674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:22.799519Z","time spent":"513.140355ms","remote":"127.0.0.1:47362","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-05-31T05:49:23.312372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:22.051382Z","time spent":"1.260976934s","remote":"127.0.0.1:47592","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-05-31T05:49:27.553906Z","caller":"traceutil/trace.go:171","msg":"trace[899013266] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"221.532518ms","start":"2026-05-31T05:49:27.332345Z","end":"2026-05-31T05:49:27.553877Z","steps":["trace[899013266] 'process raft request' (duration: 221.019986ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:49:27.555719Z","caller":"traceutil/trace.go:171","msg":"trace[656592659] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"219.383718ms","start":"2026-05-31T05:49:27.336303Z","end":"2026-05-31T05:49:27.555686Z","steps":["trace[656592659] 'process raft request' (duration: 219.347867ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:49:27.555831Z","caller":"traceutil/trace.go:171","msg":"trace[1241453526] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"220.489354ms","start":"2026-05-31T05:49:27.335316Z","end":"2026-05-31T05:49:27.555805Z","steps":["trace[1241453526] 'process raft request' (duration: 219.731996ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:49:29.700799Z","caller":"traceutil/trace.go:171","msg":"trace[1897061203] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"136.936932ms","start":"2026-05-31T05:49:29.563834Z","end":"2026-05-31T05:49:29.700771Z","steps":["trace[1897061203] 'process raft request' (duration: 136.874211ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:49:29.700838Z","caller":"traceutil/trace.go:171","msg":"trace[2086922228] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"137.367072ms","start":"2026-05-31T05:49:29.563448Z","end":"2026-05-31T05:49:29.700815Z","steps":["trace[2086922228] 'process raft request' (duration: 137.059125ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:49:35.495907Z","caller":"traceutil/trace.go:171","msg":"trace[18768850] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"121.795071ms","start":"2026-05-31T05:49:35.374086Z","end":"2026-05-31T05:49:35.495881Z","steps":["trace[18768850] 'process raft request' (duration: 121.564205ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:49:39.521065Z","caller":"traceutil/trace.go:171","msg":"trace[1481115050] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"575.842161ms","start":"2026-05-31T05:49:38.945193Z","end":"2026-05-31T05:49:39.521036Z","steps":["trace[1481115050] 'process raft request' (duration: 575.682407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:49:39.521205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:38.945171Z","time spent":"575.971215ms","remote":"127.0.0.1:47392","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-05-31T05:49:39.531398Z","caller":"traceutil/trace.go:171","msg":"trace[1010396406] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"583.055919ms","start":"2026-05-31T05:49:38.948329Z","end":"2026-05-31T05:49:39.531385Z","steps":["trace[1010396406] 'process raft request' (duration: 582.901945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:49:39.531474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:38.948301Z","time spent":"583.135611ms","remote":"127.0.0.1:47392","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-05-31T05:49:39.531527Z","caller":"traceutil/trace.go:171","msg":"trace[1849400863] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"561.468687ms","start":"2026-05-31T05:49:38.970034Z","end":"2026-05-31T05:49:39.531503Z","steps":["trace[1849400863] 'process raft request' (duration: 561.264773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:49:39.531651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:38.970017Z","time spent":"561.57566ms","remote":"127.0.0.1:47392","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-05-31T05:49:39.531759Z","caller":"traceutil/trace.go:171","msg":"trace[1793390273] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"552.516339ms","start":"2026-05-31T05:49:38.979233Z","end":"2026-05-31T05:49:39.531749Z","steps":["trace[1793390273] 'process raft request' (duration: 552.342875ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:49:39.531785Z","caller":"traceutil/trace.go:171","msg":"trace[1486508100] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"542.855845ms","start":"2026-05-31T05:49:38.988921Z","end":"2026-05-31T05:49:39.531777Z","steps":["trace[1486508100] 'process raft request' (duration: 542.675991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:49:39.531806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:38.979226Z","time spent":"552.55849ms","remote":"127.0.0.1:47392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T05:49:39.531824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:38.988907Z","time spent":"542.898176ms","remote":"127.0.0.1:47392","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-05-31T05:49:39.531891Z","caller":"traceutil/trace.go:171","msg":"trace[385331296] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"542.893156ms","start":"2026-05-31T05:49:38.98897Z","end":"2026-05-31T05:49:39.531864Z","steps":["trace[385331296] 'process raft request' (duration: 542.65898ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:49:39.531939Z","caller":"traceutil/trace.go:171","msg":"trace[1227362472] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"535.460783ms","start":"2026-05-31T05:49:38.99647Z","end":"2026-05-31T05:49:39.531931Z","steps":["trace[1227362472] 'process raft request' (duration: 535.191697ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:49:39.531892Z","caller":"traceutil/trace.go:171","msg":"trace[562559032] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"555.051288ms","start":"2026-05-31T05:49:38.976788Z","end":"2026-05-31T05:49:39.531839Z","steps":["trace[562559032] 'process raft request' (duration: 554.758821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:49:39.531963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:38.988967Z","time spent":"542.967688ms","remote":"127.0.0.1:47392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T05:49:39.531984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:38.996462Z","time spent":"535.499174ms","remote":"127.0.0.1:47392","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-05-31T05:49:39.532022Z","caller":"traceutil/trace.go:171","msg":"trace[704479160] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"533.553558ms","start":"2026-05-31T05:49:38.998462Z","end":"2026-05-31T05:49:39.532015Z","steps":["trace[704479160] 'process raft request' (duration: 533.286672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:49:39.53206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:38.998454Z","time spent":"533.59181ms","remote":"127.0.0.1:47392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T05:49:39.532066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:38.976774Z","time spent":"555.216272ms","remote":"127.0.0.1:47392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T05:49:39.532132Z","caller":"traceutil/trace.go:171","msg":"trace[1076430614] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"517.082056ms","start":"2026-05-31T05:49:39.015037Z","end":"2026-05-31T05:49:39.532119Z","steps":["trace[1076430614] 'process raft request' (duration: 516.900632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:49:39.532174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:39.015003Z","time spent":"517.152298ms","remote":"127.0.0.1:47392","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-05-31T05:49:39.532384Z","caller":"traceutil/trace.go:171","msg":"trace[263245877] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"431.142469ms","start":"2026-05-31T05:49:39.101231Z","end":"2026-05-31T05:49:39.532373Z","steps":["trace[263245877] 'process raft request' (duration: 431.071497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:49:39.532435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:39.101207Z","time spent":"431.206771ms","remote":"127.0.0.1:47392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T05:49:39.532368Z","caller":"traceutil/trace.go:171","msg":"trace[1060808443] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"562.244405ms","start":"2026-05-31T05:49:38.970089Z","end":"2026-05-31T05:49:39.532333Z","steps":["trace[1060808443] 'process raft request' (duration: 561.266512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:49:39.532681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:38.970086Z","time spent":"562.543223ms","remote":"127.0.0.1:47392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T05:49:45.477732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.628552ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:49:45.477976Z","caller":"traceutil/trace.go:171","msg":"trace[2029948140] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"130.036072ms","start":"2026-05-31T05:49:45.347854Z","end":"2026-05-31T05:49:45.47789Z","steps":["trace[2029948140] 'process raft request' (duration: 25.169705ms)","trace[2029948140] 'compare' (duration: 104.447607ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:49:45.530195Z","caller":"traceutil/trace.go:171","msg":"trace[76209958] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"131.267351ms","start":"2026-05-31T05:49:45.39889Z","end":"2026-05-31T05:49:45.530158Z","steps":["trace[76209958] 'process raft request' (duration: 130.409471ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:49:45.777457Z","caller":"traceutil/trace.go:171","msg":"trace[1667761114] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"188.45197ms","start":"2026-05-31T05:49:45.588953Z","end":"2026-05-31T05:49:45.777405Z","steps":["trace[1667761114] 'process raft request' (duration: 146.816092ms)","trace[1667761114] 'compare' (duration: 41.390262ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:49:46.395112Z","caller":"traceutil/trace.go:171","msg":"trace[630825580] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"188.145621ms","start":"2026-05-31T05:49:46.206942Z","end":"2026-05-31T05:49:46.395088Z","steps":["trace[630825580] 'process raft request' (duration: 187.618049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:49:47.118808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.28984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:49:47.11889Z","caller":"traceutil/trace.go:171","msg":"trace[1954395861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"319.422043ms","start":"2026-05-31T05:49:46.799451Z","end":"2026-05-31T05:49:47.118873Z","steps":["trace[1954395861] 'range keys from in-memory index tree' (duration: 319.148266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:49:47.118929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:46.799432Z","time spent":"319.488144ms","remote":"127.0.0.1:47362","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-05-31T05:49:48.299797Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843765690370962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-31T05:49:48.650795Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.22591078s","expected-duration":"1s"} {"level":"info","ts":"2026-05-31T05:49:48.65151Z","caller":"traceutil/trace.go:171","msg":"trace[883704358] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"1.226648847s","start":"2026-05-31T05:49:47.424775Z","end":"2026-05-31T05:49:48.651423Z","steps":["trace[883704358] 'process raft request' (duration: 1.22638175s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:49:48.651797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:47.424755Z","time spent":"1.226921942s","remote":"127.0.0.1:47526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:49:49.589745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.757069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:49:49.589836Z","caller":"traceutil/trace.go:171","msg":"trace[1603827637] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:668; }","duration":"1.790480959s","start":"2026-05-31T05:49:47.799339Z","end":"2026-05-31T05:49:49.58982Z","steps":["trace[1603827637] 'read index received' (duration: 851.805485ms)","trace[1603827637] 'applied index is now lower than readState.Index' (duration: 938.674154ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:49:49.590031Z","caller":"traceutil/trace.go:171","msg":"trace[401553304] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"1.573393565s","start":"2026-05-31T05:49:48.016625Z","end":"2026-05-31T05:49:49.590019Z","steps":["trace[401553304] 'process raft request' (duration: 1.235276107s)","trace[401553304] 'compare' (duration: 337.518544ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:49:49.590089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:48.016604Z","time spent":"1.573454816s","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:49:49.590235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.775821509s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-31T05:49:49.590318Z","caller":"traceutil/trace.go:171","msg":"trace[249721040] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"1.775948692s","start":"2026-05-31T05:49:47.814345Z","end":"2026-05-31T05:49:49.590293Z","steps":["trace[249721040] 'agreement among raft nodes before linearized reading' (duration: 1.775769208s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:49:49.590361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:47.814323Z","time spent":"1.776029363s","remote":"127.0.0.1:47592","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-05-31T05:49:49.590514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.585435184s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-31T05:49:49.590513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.791175845s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:49:49.590556Z","caller":"traceutil/trace.go:171","msg":"trace[461691164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"1.585516126s","start":"2026-05-31T05:49:48.005027Z","end":"2026-05-31T05:49:49.590543Z","steps":["trace[461691164] 'agreement among raft nodes before linearized reading' (duration: 1.585439914s)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:49:49.590565Z","caller":"traceutil/trace.go:171","msg":"trace[777357955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"1.791269387s","start":"2026-05-31T05:49:47.799281Z","end":"2026-05-31T05:49:49.59055Z","steps":["trace[777357955] 'agreement among raft nodes before linearized reading' (duration: 1.791189615s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:49:49.590587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:48.005009Z","time spent":"1.585571088s","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-31T05:49:49.590597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:47.799263Z","time spent":"1.791327649s","remote":"127.0.0.1:47350","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-05-31T05:49:49.590585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.620552521s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-31T05:49:49.590648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.391075158s","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-05-31T05:49:49.590657Z","caller":"traceutil/trace.go:171","msg":"trace[1023100503] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"1.620698184s","start":"2026-05-31T05:49:47.969939Z","end":"2026-05-31T05:49:49.590637Z","steps":["trace[1023100503] 'agreement among raft nodes before linearized reading' (duration: 1.62053441s)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:49:49.590703Z","caller":"traceutil/trace.go:171","msg":"trace[72570943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"1.3911763s","start":"2026-05-31T05:49:48.199509Z","end":"2026-05-31T05:49:49.590686Z","steps":["trace[72570943] 'agreement among raft nodes before linearized reading' (duration: 1.390955095s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:49:49.590712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:47.96992Z","time spent":"1.620782866s","remote":"127.0.0.1:47592","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-05-31T05:49:49.590737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:48.199483Z","time spent":"1.391246351s","remote":"127.0.0.1:47592","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-05-31T05:49:51.597062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000078569s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-31T05:49:51.597153Z","caller":"traceutil/trace.go:171","msg":"trace[750864343] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000205382s","start":"2026-05-31T05:49:49.596926Z","end":"2026-05-31T05:49:51.597131Z","steps":["trace[750864343] 'agreement among raft nodes before linearized reading' (duration: 2.000088789s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:49:51.597214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:49.59692Z","time spent":"2.000284494s","remote":"127.0.0.1:47350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-31T05:49:52.698784Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.987931177s","expected-duration":"1s"} {"level":"info","ts":"2026-05-31T05:49:52.699196Z","caller":"traceutil/trace.go:171","msg":"trace[1651441763] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"3.103531453s","start":"2026-05-31T05:49:49.595641Z","end":"2026-05-31T05:49:52.699173Z","steps":["trace[1651441763] 'process raft request' (duration: 3.103461641s)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:49:52.699258Z","caller":"traceutil/trace.go:171","msg":"trace[817689571] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"3.104403613s","start":"2026-05-31T05:49:49.594822Z","end":"2026-05-31T05:49:52.699225Z","steps":["trace[817689571] 'process raft request' (duration: 3.104145647s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:49:52.699354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:49.59563Z","time spent":"3.103638405s","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:49:52.699398Z","caller":"traceutil/trace.go:171","msg":"trace[1123964760] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:671; }","duration":"3.102432026s","start":"2026-05-31T05:49:49.596945Z","end":"2026-05-31T05:49:52.699377Z","steps":["trace[1123964760] 'read index received' (duration: 91.67114ms)","trace[1123964760] 'applied index is now lower than readState.Index' (duration: 3.010758346s)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:49:52.699431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:49.594797Z","time spent":"3.104526005s","remote":"127.0.0.1:47592","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-05-31T05:49:52.699624Z","caller":"traceutil/trace.go:171","msg":"trace[1651693688] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"3.102801015s","start":"2026-05-31T05:49:49.596775Z","end":"2026-05-31T05:49:52.699576Z","steps":["trace[1651693688] 'process raft request' (duration: 3.102364255s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:49:52.69987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:49.596756Z","time spent":"3.10299056s","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:49:52.938068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.451134ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-31T05:49:52.938182Z","caller":"traceutil/trace.go:171","msg":"trace[1421667494] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"238.695007ms","start":"2026-05-31T05:49:52.69947Z","end":"2026-05-31T05:49:52.938165Z","steps":["trace[1421667494] 'read index received' (duration: 135.074629ms)","trace[1421667494] 'applied index is now lower than readState.Index' (duration: 103.618808ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:49:52.938269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.34038457s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:49:52.938305Z","caller":"traceutil/trace.go:171","msg":"trace[672147273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"1.340450291s","start":"2026-05-31T05:49:51.597842Z","end":"2026-05-31T05:49:52.938292Z","steps":["trace[672147273] 'agreement among raft nodes before linearized reading' (duration: 1.340369969s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:49:52.938344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:51.597821Z","time spent":"1.340516833s","remote":"127.0.0.1:47362","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-05-31T05:49:52.93847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.24618086s","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-05-31T05:49:52.938571Z","caller":"traceutil/trace.go:171","msg":"trace[1972565534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:653; }","duration":"1.246327994s","start":"2026-05-31T05:49:51.692221Z","end":"2026-05-31T05:49:52.938549Z","steps":["trace[1972565534] 'agreement among raft nodes before linearized reading' (duration: 1.246065568s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:49:52.938638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:49:51.692204Z","time spent":"1.246419875s","remote":"127.0.0.1:47592","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-05-31T05:49:56.088427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.74832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:49:56.088515Z","caller":"traceutil/trace.go:171","msg":"trace[1528188225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:672; }","duration":"288.907693ms","start":"2026-05-31T05:49:55.799585Z","end":"2026-05-31T05:49:56.088493Z","steps":["trace[1528188225] 'range keys from in-memory index tree' (duration: 288.668289ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:50:00.739108Z","caller":"traceutil/trace.go:171","msg":"trace[475519877] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:773; }","duration":"133.487361ms","start":"2026-05-31T05:50:00.605577Z","end":"2026-05-31T05:50:00.739064Z","steps":["trace[475519877] 'read index received' (duration: 34.571162ms)","trace[475519877] 'applied index is now lower than readState.Index' (duration: 98.910559ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:50:00.739231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.138855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:833"} {"level":"warn","ts":"2026-05-31T05:50:00.739339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.429522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-05-31T05:50:00.739424Z","caller":"traceutil/trace.go:171","msg":"trace[1914344730] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:750; }","duration":"186.35811ms","start":"2026-05-31T05:50:00.553043Z","end":"2026-05-31T05:50:00.739401Z","steps":["trace[1914344730] 'agreement among raft nodes before linearized reading' (duration: 186.020632ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:50:00.739464Z","caller":"traceutil/trace.go:171","msg":"trace[1038195077] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:750; }","duration":"186.609786ms","start":"2026-05-31T05:50:00.552835Z","end":"2026-05-31T05:50:00.739445Z","steps":["trace[1038195077] 'agreement among raft nodes before linearized reading' (duration: 186.374071ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:50:01.135988Z","caller":"traceutil/trace.go:171","msg":"trace[1863890380] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"106.641719ms","start":"2026-05-31T05:50:01.029323Z","end":"2026-05-31T05:50:01.135965Z","steps":["trace[1863890380] 'process raft request' (duration: 106.384243ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:50:06.566032Z","caller":"traceutil/trace.go:171","msg":"trace[84821909] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"385.719724ms","start":"2026-05-31T05:50:06.180285Z","end":"2026-05-31T05:50:06.566004Z","steps":["trace[84821909] 'process raft request' (duration: 385.52588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:06.566209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:06.180255Z","time spent":"385.867527ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:50:09.090383Z","caller":"traceutil/trace.go:171","msg":"trace[1529047585] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:835; }","duration":"290.613713ms","start":"2026-05-31T05:50:08.799744Z","end":"2026-05-31T05:50:09.090357Z","steps":["trace[1529047585] 'read index received' (duration: 290.526081ms)","trace[1529047585] 'applied index is now lower than readState.Index' (duration: 86.602µs)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:50:09.090433Z","caller":"traceutil/trace.go:171","msg":"trace[1564802711] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"515.896849ms","start":"2026-05-31T05:50:08.574508Z","end":"2026-05-31T05:50:09.090405Z","steps":["trace[1564802711] 'process raft request' (duration: 515.707775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:09.090573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:08.574487Z","time spent":"516.013232ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:50:09.090732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.89162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:50:09.090887Z","caller":"traceutil/trace.go:171","msg":"trace[2016757264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:809; }","duration":"291.171197ms","start":"2026-05-31T05:50:08.79969Z","end":"2026-05-31T05:50:09.090861Z","steps":["trace[2016757264] 'agreement among raft nodes before linearized reading' (duration: 290.85179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:09.590857Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843765690371281,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-31T05:50:10.012537Z","caller":"traceutil/trace.go:171","msg":"trace[1013945634] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"1.185758617s","start":"2026-05-31T05:50:08.826758Z","end":"2026-05-31T05:50:10.012517Z","steps":["trace[1013945634] 'process raft request' (duration: 1.185575813s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:10.013036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:08.82673Z","time spent":"1.186008742s","remote":"127.0.0.1:47524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:50:10.020832Z","caller":"traceutil/trace.go:171","msg":"trace[1799246993] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"483.386484ms","start":"2026-05-31T05:50:09.537385Z","end":"2026-05-31T05:50:10.020772Z","steps":["trace[1799246993] 'process raft request' (duration: 482.980094ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:50:10.021008Z","caller":"traceutil/trace.go:171","msg":"trace[1211962652] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:836; }","duration":"930.523824ms","start":"2026-05-31T05:50:09.090453Z","end":"2026-05-31T05:50:10.020977Z","steps":["trace[1211962652] 'read index received' (duration: 921.948895ms)","trace[1211962652] 'applied index is now lower than readState.Index' (duration: 8.566229ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:50:10.02131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:09.537324Z","time spent":"483.654631ms","remote":"127.0.0.1:47564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:50:10.021343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068311206s","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-05-31T05:50:10.021797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.138958999s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-31T05:50:10.021864Z","caller":"traceutil/trace.go:171","msg":"trace[1011271678] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:812; }","duration":"1.139471001s","start":"2026-05-31T05:50:08.88238Z","end":"2026-05-31T05:50:10.021851Z","steps":["trace[1011271678] 'agreement among raft nodes before linearized reading' (duration: 1.138907218s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:10.021978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:08.882361Z","time spent":"1.139607505s","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-31T05:50:10.022053Z","caller":"traceutil/trace.go:171","msg":"trace[1761524532] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:812; }","duration":"1.06847719s","start":"2026-05-31T05:50:08.952965Z","end":"2026-05-31T05:50:10.021443Z","steps":["trace[1761524532] 'agreement among raft nodes before linearized reading' (duration: 1.068248385s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:10.022158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:08.952945Z","time spent":"1.069183537s","remote":"127.0.0.1:47592","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-05-31T05:50:10.02229Z","caller":"traceutil/trace.go:171","msg":"trace[2127670464] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"251.786072ms","start":"2026-05-31T05:50:09.770489Z","end":"2026-05-31T05:50:10.022275Z","steps":["trace[2127670464] 'process raft request' (duration: 250.193865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:10.022288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.212164ms","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-05-31T05:50:10.022375Z","caller":"traceutil/trace.go:171","msg":"trace[454872075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:812; }","duration":"852.322067ms","start":"2026-05-31T05:50:09.170016Z","end":"2026-05-31T05:50:10.022338Z","steps":["trace[454872075] 'agreement among raft nodes before linearized reading' (duration: 852.075671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:10.022415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:09.169994Z","time spent":"852.4126ms","remote":"127.0.0.1:47592","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-05-31T05:50:10.022445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.060339762s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-31T05:50:10.022478Z","caller":"traceutil/trace.go:171","msg":"trace[216206911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:812; }","duration":"1.060389923s","start":"2026-05-31T05:50:08.962077Z","end":"2026-05-31T05:50:10.022467Z","steps":["trace[216206911] 'agreement among raft nodes before linearized reading' (duration: 1.06026671s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:10.022502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:08.962067Z","time spent":"1.060430644s","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-31T05:50:10.022577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.482014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:50:10.022603Z","caller":"traceutil/trace.go:171","msg":"trace[67814034] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:812; }","duration":"930.538375ms","start":"2026-05-31T05:50:09.092058Z","end":"2026-05-31T05:50:10.022596Z","steps":["trace[67814034] 'agreement among raft nodes before linearized reading' (duration: 930.499254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:10.022653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:09.092039Z","time spent":"930.609547ms","remote":"127.0.0.1:47362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-31T05:50:17.186777Z","caller":"traceutil/trace.go:171","msg":"trace[1842080698] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"137.755387ms","start":"2026-05-31T05:50:17.048991Z","end":"2026-05-31T05:50:17.186747Z","steps":["trace[1842080698] 'process raft request' (duration: 94.23701ms)","trace[1842080698] 'compare' (duration: 43.379254ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:50:17.315639Z","caller":"traceutil/trace.go:171","msg":"trace[101896959] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"124.047726ms","start":"2026-05-31T05:50:17.191568Z","end":"2026-05-31T05:50:17.315616Z","steps":["trace[101896959] 'process raft request' (duration: 123.987715ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:50:17.316219Z","caller":"traceutil/trace.go:171","msg":"trace[434061855] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"263.261696ms","start":"2026-05-31T05:50:17.052943Z","end":"2026-05-31T05:50:17.316205Z","steps":["trace[434061855] 'process raft request' (duration: 234.515104ms)","trace[434061855] 'compare' (duration: 27.920184ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:50:17.316594Z","caller":"traceutil/trace.go:171","msg":"trace[2117692483] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"263.568104ms","start":"2026-05-31T05:50:17.053009Z","end":"2026-05-31T05:50:17.316577Z","steps":["trace[2117692483] 'process raft request' (duration: 262.478099ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:50:17.316659Z","caller":"traceutil/trace.go:171","msg":"trace[2033260152] linearizableReadLoop","detail":"{readStateIndex:914; appliedIndex:911; }","duration":"186.519434ms","start":"2026-05-31T05:50:17.130107Z","end":"2026-05-31T05:50:17.316627Z","steps":["trace[2033260152] 'read index received' (duration: 13.091617ms)","trace[2033260152] 'applied index is now lower than readState.Index' (duration: 173.425607ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:50:17.316996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.857851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-31T05:50:17.317051Z","caller":"traceutil/trace.go:171","msg":"trace[1971127239] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:886; }","duration":"186.973264ms","start":"2026-05-31T05:50:17.130058Z","end":"2026-05-31T05:50:17.317031Z","steps":["trace[1971127239] 'agreement among raft nodes before linearized reading' (duration: 186.78283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:17.317313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.507079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-wj5md\" ","response":"range_response_count:1 size:3871"} {"level":"info","ts":"2026-05-31T05:50:17.317349Z","caller":"traceutil/trace.go:171","msg":"trace[737359852] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-wj5md; range_end:; response_count:1; response_revision:886; }","duration":"125.573331ms","start":"2026-05-31T05:50:17.191766Z","end":"2026-05-31T05:50:17.31734Z","steps":["trace[737359852] 'agreement among raft nodes before linearized reading' (duration: 125.481239ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:50:26.250435Z","caller":"traceutil/trace.go:171","msg":"trace[1429769415] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"127.515245ms","start":"2026-05-31T05:50:26.122896Z","end":"2026-05-31T05:50:26.250411Z","steps":["trace[1429769415] 'process raft request' (duration: 127.33089ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:50:27.624284Z","caller":"traceutil/trace.go:171","msg":"trace[1737660665] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"173.285853ms","start":"2026-05-31T05:50:27.450971Z","end":"2026-05-31T05:50:27.624257Z","steps":["trace[1737660665] 'process raft request' (duration: 173.13914ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:50:31.00135Z","caller":"traceutil/trace.go:171","msg":"trace[225361199] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1008; }","duration":"201.663048ms","start":"2026-05-31T05:50:30.799655Z","end":"2026-05-31T05:50:31.001318Z","steps":["trace[225361199] 'read index received' (duration: 192.718115ms)","trace[225361199] 'applied index is now lower than readState.Index' (duration: 8.943493ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:50:31.001496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.825401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:50:31.00154Z","caller":"traceutil/trace.go:171","msg":"trace[83382648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:977; }","duration":"201.929954ms","start":"2026-05-31T05:50:30.799597Z","end":"2026-05-31T05:50:31.001527Z","steps":["trace[83382648] 'agreement among raft nodes before linearized reading' (duration: 201.834962ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:50:31.001576Z","caller":"traceutil/trace.go:171","msg":"trace[1725909153] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"245.41139ms","start":"2026-05-31T05:50:30.756145Z","end":"2026-05-31T05:50:31.001556Z","steps":["trace[1725909153] 'process raft request' (duration: 236.293373ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:50:31.780834Z","caller":"traceutil/trace.go:171","msg":"trace[920509668] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"118.03213ms","start":"2026-05-31T05:50:31.662777Z","end":"2026-05-31T05:50:31.780809Z","steps":["trace[920509668] 'process raft request' (duration: 117.880246ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:50:33.263134Z","caller":"traceutil/trace.go:171","msg":"trace[1157098774] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"256.123264ms","start":"2026-05-31T05:50:33.006987Z","end":"2026-05-31T05:50:33.263111Z","steps":["trace[1157098774] 'process raft request' (duration: 255.978131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:47.138821Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843765690371748,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-31T05:50:47.213422Z","caller":"traceutil/trace.go:171","msg":"trace[548110875] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"713.646118ms","start":"2026-05-31T05:50:46.499751Z","end":"2026-05-31T05:50:47.213397Z","steps":["trace[548110875] 'process raft request' (duration: 713.478955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:47.213567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:46.499715Z","time spent":"713.784262ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:50:49.180521Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.967276437s","expected-duration":"1s"} {"level":"info","ts":"2026-05-31T05:50:49.180913Z","caller":"traceutil/trace.go:171","msg":"trace[801392767] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1065; }","duration":"2.542513584s","start":"2026-05-31T05:50:46.638378Z","end":"2026-05-31T05:50:49.180891Z","steps":["trace[801392767] 'read index received' (duration: 574.921289ms)","trace[801392767] 'applied index is now lower than readState.Index' (duration: 1.967591315s)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:50:49.181024Z","caller":"traceutil/trace.go:171","msg":"trace[311196210] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"2.597570594s","start":"2026-05-31T05:50:46.583436Z","end":"2026-05-31T05:50:49.181006Z","steps":["trace[311196210] 'process raft request' (duration: 2.597294978s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:49.1812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:46.583395Z","time spent":"2.597733268s","remote":"127.0.0.1:47592","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-05-31T05:50:49.181626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.543245391s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-31T05:50:49.181659Z","caller":"traceutil/trace.go:171","msg":"trace[347779050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1031; }","duration":"2.543279462s","start":"2026-05-31T05:50:46.638371Z","end":"2026-05-31T05:50:49.18165Z","steps":["trace[347779050] 'agreement among raft nodes before linearized reading' (duration: 2.542722139s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:49.181681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:46.638318Z","time spent":"2.543358904s","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-31T05:50:49.187761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.53922314s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-31T05:50:49.187829Z","caller":"traceutil/trace.go:171","msg":"trace[1862960871] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1032; }","duration":"2.539340452s","start":"2026-05-31T05:50:46.648474Z","end":"2026-05-31T05:50:49.187814Z","steps":["trace[1862960871] 'agreement among raft nodes before linearized reading' (duration: 2.539125457s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:49.187783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.388753275s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-31T05:50:49.187846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.018141ms","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-05-31T05:50:49.187894Z","caller":"traceutil/trace.go:171","msg":"trace[60825062] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1032; }","duration":"1.388889708s","start":"2026-05-31T05:50:47.798981Z","end":"2026-05-31T05:50:49.187871Z","steps":["trace[60825062] 'agreement among raft nodes before linearized reading' (duration: 1.388742165s)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:50:49.187921Z","caller":"traceutil/trace.go:171","msg":"trace[290702687] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"1.753517675s","start":"2026-05-31T05:50:47.43438Z","end":"2026-05-31T05:50:49.187897Z","steps":["trace[290702687] 'process raft request' (duration: 1.753105905s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:49.187943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:47.798966Z","time spent":"1.38896806s","remote":"127.0.0.1:47350","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-05-31T05:50:49.187949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.320341172s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-31T05:50:49.187996Z","caller":"traceutil/trace.go:171","msg":"trace[1635814310] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1032; }","duration":"1.320420504s","start":"2026-05-31T05:50:47.867564Z","end":"2026-05-31T05:50:49.187984Z","steps":["trace[1635814310] 'agreement among raft nodes before linearized reading' (duration: 1.32023363s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:49.188024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:47.867544Z","time spent":"1.320472075s","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-31T05:50:49.188032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:47.434357Z","time spent":"1.753602737s","remote":"127.0.0.1:47592","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-05-31T05:50:49.188064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.522693714s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-31T05:50:49.188096Z","caller":"traceutil/trace.go:171","msg":"trace[446931353] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1032; }","duration":"2.522772596s","start":"2026-05-31T05:50:46.665315Z","end":"2026-05-31T05:50:49.188087Z","steps":["trace[446931353] 'agreement among raft nodes before linearized reading' (duration: 2.522632863s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:49.188118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:46.665293Z","time spent":"2.522820527s","remote":"127.0.0.1:47592","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-05-31T05:50:49.188145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.589751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-31T05:50:49.188165Z","caller":"traceutil/trace.go:171","msg":"trace[791158010] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1032; }","duration":"429.647043ms","start":"2026-05-31T05:50:48.758508Z","end":"2026-05-31T05:50:49.188156Z","steps":["trace[791158010] 'agreement among raft nodes before linearized reading' (duration: 429.576441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:49.18818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:48.758484Z","time spent":"429.692234ms","remote":"127.0.0.1:47684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-05-31T05:50:49.187931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.011446589s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-31T05:50:49.188191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.712918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-31T05:50:49.188239Z","caller":"traceutil/trace.go:171","msg":"trace[398596030] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1032; }","duration":"471.80564ms","start":"2026-05-31T05:50:48.716419Z","end":"2026-05-31T05:50:49.188225Z","steps":["trace[398596030] 'agreement among raft nodes before linearized reading' (duration: 471.727619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:49.188275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:48.716389Z","time spent":"471.876861ms","remote":"127.0.0.1:47516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-05-31T05:50:49.188207Z","caller":"traceutil/trace.go:171","msg":"trace[1476538402] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1032; }","duration":"2.011754557s","start":"2026-05-31T05:50:47.176438Z","end":"2026-05-31T05:50:49.188192Z","steps":["trace[1476538402] 'agreement among raft nodes before linearized reading' (duration: 2.01144674s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:49.188365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.198649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-31T05:50:49.18836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:47.176396Z","time spent":"2.011945131s","remote":"127.0.0.1:47898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-05-31T05:50:49.187862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:46.648457Z","time spent":"2.539398344s","remote":"127.0.0.1:47592","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-05-31T05:50:49.187916Z","caller":"traceutil/trace.go:171","msg":"trace[385710099] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1032; }","duration":"704.135444ms","start":"2026-05-31T05:50:48.483756Z","end":"2026-05-31T05:50:49.187892Z","steps":["trace[385710099] 'agreement among raft nodes before linearized reading' (duration: 704.010161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:49.188434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:48.483734Z","time spent":"704.688837ms","remote":"127.0.0.1:32970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-05-31T05:50:49.188536Z","caller":"traceutil/trace.go:171","msg":"trace[484893070] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1032; }","duration":"483.879194ms","start":"2026-05-31T05:50:48.704505Z","end":"2026-05-31T05:50:49.188384Z","steps":["trace[484893070] 'agreement among raft nodes before linearized reading' (duration: 483.167738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:49.188581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:48.70449Z","time spent":"484.082029ms","remote":"127.0.0.1:32944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-31T05:50:53.374737Z","caller":"traceutil/trace.go:171","msg":"trace[283618966] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"102.074037ms","start":"2026-05-31T05:50:53.272607Z","end":"2026-05-31T05:50:53.374681Z","steps":["trace[283618966] 'process raft request' (duration: 51.260983ms)","trace[283618966] 'compare' (duration: 50.700461ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:50:53.719648Z","caller":"traceutil/trace.go:171","msg":"trace[920149336] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"443.743233ms","start":"2026-05-31T05:50:53.275887Z","end":"2026-05-31T05:50:53.71963Z","steps":["trace[920149336] 'process raft request' (duration: 443.713852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:53.719762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:53.275881Z","time spent":"443.824585ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:50:53.719828Z","caller":"traceutil/trace.go:171","msg":"trace[406918799] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"446.655539ms","start":"2026-05-31T05:50:53.273159Z","end":"2026-05-31T05:50:53.719814Z","steps":["trace[406918799] 'process raft request' (duration: 446.386803ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:50:53.719851Z","caller":"traceutil/trace.go:171","msg":"trace[1240295781] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"445.226556ms","start":"2026-05-31T05:50:53.274619Z","end":"2026-05-31T05:50:53.719845Z","steps":["trace[1240295781] 'process raft request' (duration: 444.95668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:53.719881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:53.274598Z","time spent":"445.266807ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:50:53.719895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:53.273151Z","time spent":"446.71264ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:50:53.719977Z","caller":"traceutil/trace.go:171","msg":"trace[1301617197] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"446.896295ms","start":"2026-05-31T05:50:53.273067Z","end":"2026-05-31T05:50:53.719963Z","steps":["trace[1301617197] 'process raft request' (duration: 442.782121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:53.720035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:50:53.273043Z","time spent":"446.964696ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:50:54.06871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.352617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-31T05:50:54.068788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.766943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:50:54.068843Z","caller":"traceutil/trace.go:171","msg":"trace[361652728] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1050; }","duration":"232.868116ms","start":"2026-05-31T05:50:53.835961Z","end":"2026-05-31T05:50:54.06883Z","steps":["trace[361652728] 'count revisions from in-memory index tree' (duration: 232.701882ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:50:54.068801Z","caller":"traceutil/trace.go:171","msg":"trace[543944506] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1050; }","duration":"144.49731ms","start":"2026-05-31T05:50:53.924281Z","end":"2026-05-31T05:50:54.068778Z","steps":["trace[543944506] 'count revisions from in-memory index tree' (duration: 144.267165ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:09.668516Z","caller":"traceutil/trace.go:171","msg":"trace[1791956642] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1136; }","duration":"228.775243ms","start":"2026-05-31T05:51:09.439714Z","end":"2026-05-31T05:51:09.668489Z","steps":["trace[1791956642] 'read index received' (duration: 228.62057ms)","trace[1791956642] 'applied index is now lower than readState.Index' (duration: 153.533µs)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:51:09.668586Z","caller":"traceutil/trace.go:171","msg":"trace[710659289] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"339.64185ms","start":"2026-05-31T05:51:09.32888Z","end":"2026-05-31T05:51:09.668521Z","steps":["trace[710659289] 'process raft request' (duration: 339.431505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:09.668779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:09.328854Z","time spent":"339.799144ms","remote":"127.0.0.1:47592","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-05-31T05:51:09.668895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.149222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-31T05:51:09.668982Z","caller":"traceutil/trace.go:171","msg":"trace[1968084865] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1097; }","duration":"229.227294ms","start":"2026-05-31T05:51:09.439699Z","end":"2026-05-31T05:51:09.668926Z","steps":["trace[1968084865] 'agreement among raft nodes before linearized reading' (duration: 228.930957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:10.270147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.855925ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:51:10.270245Z","caller":"traceutil/trace.go:171","msg":"trace[1661948550] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"651.606142ms","start":"2026-05-31T05:51:09.61862Z","end":"2026-05-31T05:51:10.270226Z","steps":["trace[1661948550] 'process raft request' (duration: 537.587073ms)","trace[1661948550] 'compare' (duration: 113.375544ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:51:10.270303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:09.618593Z","time spent":"651.682584ms","remote":"127.0.0.1:47592","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-05-31T05:51:10.766599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.207309ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:51:10.766768Z","caller":"traceutil/trace.go:171","msg":"trace[1303750198] linearizableReadLoop","detail":"{readStateIndex:1139; appliedIndex:1137; }","duration":"974.895131ms","start":"2026-05-31T05:51:09.791851Z","end":"2026-05-31T05:51:10.766746Z","steps":["trace[1303750198] 'read index received' (duration: 364.360101ms)","trace[1303750198] 'applied index is now lower than readState.Index' (duration: 610.53078ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:51:10.766903Z","caller":"traceutil/trace.go:171","msg":"trace[714886700] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"1.093901422s","start":"2026-05-31T05:51:09.672984Z","end":"2026-05-31T05:51:10.766886Z","steps":["trace[714886700] 'process raft request' (duration: 973.300884ms)","trace[714886700] 'compare' (duration: 119.997904ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:51:10.766995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:09.672965Z","time spent":"1.093983913s","remote":"127.0.0.1:47592","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-05-31T05:51:10.85345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061583018s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-31T05:51:10.85348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0599059s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-31T05:51:10.853554Z","caller":"traceutil/trace.go:171","msg":"trace[1853538075] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1099; }","duration":"1.060018933s","start":"2026-05-31T05:51:09.793518Z","end":"2026-05-31T05:51:10.853537Z","steps":["trace[1853538075] 'agreement among raft nodes before linearized reading' (duration: 1.059801328s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:10.853598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:09.793508Z","time spent":"1.060081594s","remote":"127.0.0.1:47592","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-05-31T05:51:10.853731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047420287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-31T05:51:10.85387Z","caller":"traceutil/trace.go:171","msg":"trace[481134032] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1099; }","duration":"1.04756815s","start":"2026-05-31T05:51:09.806271Z","end":"2026-05-31T05:51:10.853839Z","steps":["trace[481134032] 'agreement among raft nodes before linearized reading' (duration: 1.047202891s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:10.853893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052926071s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:51:10.853999Z","caller":"traceutil/trace.go:171","msg":"trace[1655318162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1099; }","duration":"1.053039374s","start":"2026-05-31T05:51:09.800937Z","end":"2026-05-31T05:51:10.853977Z","steps":["trace[1655318162] 'agreement among raft nodes before linearized reading' (duration: 1.05288037s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:10.85405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:09.800903Z","time spent":"1.053138286s","remote":"127.0.0.1:47350","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-05-31T05:51:10.85402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:09.806259Z","time spent":"1.047701553s","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-31T05:51:10.854201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058459548s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-31T05:51:10.854272Z","caller":"traceutil/trace.go:171","msg":"trace[356128276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1099; }","duration":"1.05858887s","start":"2026-05-31T05:51:09.795666Z","end":"2026-05-31T05:51:10.854255Z","steps":["trace[356128276] 'agreement among raft nodes before linearized reading' (duration: 1.058112909s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:10.854514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:09.795654Z","time spent":"1.058835696s","remote":"127.0.0.1:47592","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":"info","ts":"2026-05-31T05:51:10.853769Z","caller":"traceutil/trace.go:171","msg":"trace[1908147671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1099; }","duration":"1.061742651s","start":"2026-05-31T05:51:09.791782Z","end":"2026-05-31T05:51:10.853525Z","steps":["trace[1908147671] 'agreement among raft nodes before linearized reading' (duration: 975.080274ms)","trace[1908147671] 'range keys from in-memory index tree' (duration: 86.411181ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:51:10.854658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:09.791763Z","time spent":"1.062879988s","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-31T05:51:11.238674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.063642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:51:11.238948Z","caller":"traceutil/trace.go:171","msg":"trace[2097228826] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"378.990023ms","start":"2026-05-31T05:51:10.859935Z","end":"2026-05-31T05:51:11.238925Z","steps":["trace[2097228826] 'process raft request' (duration: 174.580023ms)","trace[2097228826] 'compare' (duration: 203.387527ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:51:11.23904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:10.859913Z","time spent":"379.086164ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:51:11.23907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.616819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:51:11.239127Z","caller":"traceutil/trace.go:171","msg":"trace[1203282087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1103; }","duration":"376.674261ms","start":"2026-05-31T05:51:10.862419Z","end":"2026-05-31T05:51:11.239093Z","steps":["trace[1203282087] 'agreement among raft nodes before linearized reading' (duration: 376.571508ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:11.239136Z","caller":"traceutil/trace.go:171","msg":"trace[1912865633] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"379.160586ms","start":"2026-05-31T05:51:10.859957Z","end":"2026-05-31T05:51:11.239117Z","steps":["trace[1912865633] 'process raft request' (duration: 378.817148ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:11.239229Z","caller":"traceutil/trace.go:171","msg":"trace[602075951] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"379.169126ms","start":"2026-05-31T05:51:10.860045Z","end":"2026-05-31T05:51:11.239214Z","steps":["trace[602075951] 'process raft request' (duration: 378.860749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:11.239287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:10.859933Z","time spent":"379.30635ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:51:11.239359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:10.860037Z","time spent":"379.259619ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:51:11.239186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:10.862406Z","time spent":"376.751162ms","remote":"127.0.0.1:47350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-31T05:51:11.238965Z","caller":"traceutil/trace.go:171","msg":"trace[243360967] linearizableReadLoop","detail":"{readStateIndex:1144; appliedIndex:1140; }","duration":"376.504646ms","start":"2026-05-31T05:51:10.862447Z","end":"2026-05-31T05:51:11.238952Z","steps":["trace[243360967] 'read index received' (duration: 20.624278ms)","trace[243360967] 'applied index is now lower than readState.Index' (duration: 355.879488ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:51:11.240028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.607606ms","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-05-31T05:51:11.240084Z","caller":"traceutil/trace.go:171","msg":"trace[1411487453] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1103; }","duration":"112.700028ms","start":"2026-05-31T05:51:11.127372Z","end":"2026-05-31T05:51:11.240072Z","steps":["trace[1411487453] 'agreement among raft nodes before linearized reading' (duration: 112.619756ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:12.876853Z","caller":"traceutil/trace.go:171","msg":"trace[1552014024] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"102.048546ms","start":"2026-05-31T05:51:12.774776Z","end":"2026-05-31T05:51:12.876825Z","steps":["trace[1552014024] 'process raft request' (duration: 101.810201ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:13.296662Z","caller":"traceutil/trace.go:171","msg":"trace[1196717084] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"408.055463ms","start":"2026-05-31T05:51:12.88858Z","end":"2026-05-31T05:51:13.296635Z","steps":["trace[1196717084] 'process raft request' (duration: 382.244157ms)","trace[1196717084] 'compare' (duration: 25.10285ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:51:13.296791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:12.888568Z","time spent":"408.157115ms","remote":"127.0.0.1:47592","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-05-31T05:51:15.095487Z","caller":"traceutil/trace.go:171","msg":"trace[1926366928] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"209.332562ms","start":"2026-05-31T05:51:14.88613Z","end":"2026-05-31T05:51:15.095463Z","steps":["trace[1926366928] 'process raft request' (duration: 209.178828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:15.507885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.695039ms","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-05-31T05:51:15.50793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.94883ms","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-05-31T05:51:15.50803Z","caller":"traceutil/trace.go:171","msg":"trace[274532707] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1112; }","duration":"205.890154ms","start":"2026-05-31T05:51:15.30211Z","end":"2026-05-31T05:51:15.508Z","steps":["trace[274532707] 'range keys from in-memory index tree' (duration: 205.487734ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:15.508057Z","caller":"traceutil/trace.go:171","msg":"trace[1446334297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1112; }","duration":"141.144674ms","start":"2026-05-31T05:51:15.366886Z","end":"2026-05-31T05:51:15.50803Z","steps":["trace[1446334297] 'range keys from in-memory index tree' (duration: 140.668743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:15.508121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.165588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-31T05:51:15.508193Z","caller":"traceutil/trace.go:171","msg":"trace[1864808929] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:1112; }","duration":"161.21929ms","start":"2026-05-31T05:51:15.346928Z","end":"2026-05-31T05:51:15.508148Z","steps":["trace[1864808929] 'range keys from in-memory index tree' (duration: 161.066406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:15.508271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.545103ms","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-05-31T05:51:15.508335Z","caller":"traceutil/trace.go:171","msg":"trace[1023292050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1112; }","duration":"141.663536ms","start":"2026-05-31T05:51:15.366656Z","end":"2026-05-31T05:51:15.508319Z","steps":["trace[1023292050] 'range keys from in-memory index tree' (duration: 141.332628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:15.508035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.021847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-31T05:51:15.508447Z","caller":"traceutil/trace.go:171","msg":"trace[1354333225] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1112; }","duration":"184.483288ms","start":"2026-05-31T05:51:15.323957Z","end":"2026-05-31T05:51:15.50844Z","steps":["trace[1354333225] 'range keys from in-memory index tree' (duration: 183.840063ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:15.779954Z","caller":"traceutil/trace.go:171","msg":"trace[1855207449] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"266.552081ms","start":"2026-05-31T05:51:15.513372Z","end":"2026-05-31T05:51:15.779924Z","steps":["trace[1855207449] 'process raft request' (duration: 266.268705ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:15.780529Z","caller":"traceutil/trace.go:171","msg":"trace[1140755363] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"266.910429ms","start":"2026-05-31T05:51:15.513599Z","end":"2026-05-31T05:51:15.780509Z","steps":["trace[1140755363] 'process raft request' (duration: 266.620172ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:15.780839Z","caller":"traceutil/trace.go:171","msg":"trace[269325905] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"267.198396ms","start":"2026-05-31T05:51:15.513593Z","end":"2026-05-31T05:51:15.780792Z","steps":["trace[269325905] 'process raft request' (duration: 266.794207ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:15.780488Z","caller":"traceutil/trace.go:171","msg":"trace[193666282] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"267.071463ms","start":"2026-05-31T05:51:15.513392Z","end":"2026-05-31T05:51:15.780463Z","steps":["trace[193666282] 'process raft request' (duration: 266.463969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:25.582861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.675335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-31T05:51:25.582979Z","caller":"traceutil/trace.go:171","msg":"trace[2065821204] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:1145; }","duration":"234.845009ms","start":"2026-05-31T05:51:25.348107Z","end":"2026-05-31T05:51:25.582953Z","steps":["trace[2065821204] 'range keys from in-memory index tree' (duration: 234.46032ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:33.886183Z","caller":"traceutil/trace.go:171","msg":"trace[1441477272] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"620.123487ms","start":"2026-05-31T05:51:33.266029Z","end":"2026-05-31T05:51:33.886153Z","steps":["trace[1441477272] 'process raft request' (duration: 619.513503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:33.88634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:33.266007Z","time spent":"620.253151ms","remote":"127.0.0.1:47592","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-05-31T05:51:34.438929Z","caller":"traceutil/trace.go:171","msg":"trace[1456362185] linearizableReadLoop","detail":"{readStateIndex:1272; appliedIndex:1271; }","duration":"396.060692ms","start":"2026-05-31T05:51:34.042842Z","end":"2026-05-31T05:51:34.438903Z","steps":["trace[1456362185] 'read index received' (duration: 395.201542ms)","trace[1456362185] 'applied index is now lower than readState.Index' (duration: 857.75µs)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:51:34.438951Z","caller":"traceutil/trace.go:171","msg":"trace[1486743924] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"456.615201ms","start":"2026-05-31T05:51:33.982304Z","end":"2026-05-31T05:51:34.438919Z","steps":["trace[1486743924] 'process raft request' (duration: 455.754331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:34.439121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:33.982273Z","time spent":"456.751605ms","remote":"127.0.0.1:47592","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-05-31T05:51:34.439169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.054738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-31T05:51:34.439154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.293677ms","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-05-31T05:51:34.439208Z","caller":"traceutil/trace.go:171","msg":"trace[1438773438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1225; }","duration":"392.152711ms","start":"2026-05-31T05:51:34.047042Z","end":"2026-05-31T05:51:34.439195Z","steps":["trace[1438773438] 'agreement among raft nodes before linearized reading' (duration: 392.005147ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:34.439225Z","caller":"traceutil/trace.go:171","msg":"trace[1988401483] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1225; }","duration":"396.40321ms","start":"2026-05-31T05:51:34.042802Z","end":"2026-05-31T05:51:34.439205Z","steps":["trace[1988401483] 'agreement among raft nodes before linearized reading' (duration: 396.179685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:34.439241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:34.047015Z","time spent":"392.217782ms","remote":"127.0.0.1:47592","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-05-31T05:51:34.439266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:34.042785Z","time spent":"396.472461ms","remote":"127.0.0.1:47592","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-05-31T05:51:34.439249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.121816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-05-31T05:51:34.439268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.670823ms","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-05-31T05:51:34.439343Z","caller":"traceutil/trace.go:171","msg":"trace[1914141716] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1225; }","duration":"391.256539ms","start":"2026-05-31T05:51:34.048059Z","end":"2026-05-31T05:51:34.439316Z","steps":["trace[1914141716] 'agreement among raft nodes before linearized reading' (duration: 391.014734ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:34.439351Z","caller":"traceutil/trace.go:171","msg":"trace[1547604748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1225; }","duration":"389.776405ms","start":"2026-05-31T05:51:34.049552Z","end":"2026-05-31T05:51:34.439329Z","steps":["trace[1547604748] 'agreement among raft nodes before linearized reading' (duration: 389.515609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:34.43938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:34.048042Z","time spent":"391.330762ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-31T05:51:34.439395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:34.049545Z","time spent":"389.842267ms","remote":"127.0.0.1:47592","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":"info","ts":"2026-05-31T05:51:44.028363Z","caller":"traceutil/trace.go:171","msg":"trace[1814285769] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"100.51237ms","start":"2026-05-31T05:51:43.927831Z","end":"2026-05-31T05:51:44.028344Z","steps":["trace[1814285769] 'process raft request' (duration: 100.391837ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:50.654803Z","caller":"traceutil/trace.go:171","msg":"trace[394288311] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"592.969986ms","start":"2026-05-31T05:51:50.061812Z","end":"2026-05-31T05:51:50.654782Z","steps":["trace[394288311] 'process raft request' (duration: 592.845013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:50.655118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:50.061799Z","time spent":"593.0983ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:51:51.687645Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.032833447s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-31T05:51:51.828245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.068831ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:51:51.828367Z","caller":"traceutil/trace.go:171","msg":"trace[2094827624] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1334; }","duration":"1.244169167s","start":"2026-05-31T05:51:50.584184Z","end":"2026-05-31T05:51:51.828353Z","steps":["trace[2094827624] 'read index received' (duration: 70.651645ms)","trace[2094827624] 'applied index is now lower than readState.Index' (duration: 1.173516362s)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:51:51.828419Z","caller":"traceutil/trace.go:171","msg":"trace[804646811] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"1.306079558s","start":"2026-05-31T05:51:50.522331Z","end":"2026-05-31T05:51:51.82841Z","steps":["trace[804646811] 'process raft request' (duration: 1.165781642s)","trace[804646811] 'compare' (duration: 139.883797ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:51:51.828488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:50.522309Z","time spent":"1.306126689s","remote":"127.0.0.1:47592","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-05-31T05:51:51.828598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244364241s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-31T05:51:51.828661Z","caller":"traceutil/trace.go:171","msg":"trace[699323419] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1285; }","duration":"1.244521975s","start":"2026-05-31T05:51:50.584122Z","end":"2026-05-31T05:51:51.828644Z","steps":["trace[699323419] 'agreement among raft nodes before linearized reading' (duration: 1.2443141s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:51.828704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:50.584089Z","time spent":"1.244605357s","remote":"127.0.0.1:47592","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-05-31T05:51:51.927534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.316101842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-31T05:51:51.927584Z","caller":"traceutil/trace.go:171","msg":"trace[1965153697] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1286; }","duration":"1.316188724s","start":"2026-05-31T05:51:50.611385Z","end":"2026-05-31T05:51:51.927573Z","steps":["trace[1965153697] 'agreement among raft nodes before linearized reading' (duration: 1.316033811s)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:51.927584Z","caller":"traceutil/trace.go:171","msg":"trace[1809970219] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"1.161465361s","start":"2026-05-31T05:51:50.766102Z","end":"2026-05-31T05:51:51.927567Z","steps":["trace[1809970219] 'process raft request' (duration: 1.161152754s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:51.927587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.283152274s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-31T05:51:51.927661Z","caller":"traceutil/trace.go:171","msg":"trace[219249962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1286; }","duration":"1.283269217s","start":"2026-05-31T05:51:50.644379Z","end":"2026-05-31T05:51:51.927649Z","steps":["trace[219249962] 'agreement among raft nodes before linearized reading' (duration: 1.283123004s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:51.92767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.304426459s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-31T05:51:51.9277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:50.644361Z","time spent":"1.283333439s","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-31T05:51:51.927618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:50.611369Z","time spent":"1.316243615s","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-31T05:51:51.927702Z","caller":"traceutil/trace.go:171","msg":"trace[806519471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1286; }","duration":"1.304495031s","start":"2026-05-31T05:51:50.623199Z","end":"2026-05-31T05:51:51.927694Z","steps":["trace[806519471] 'agreement among raft nodes before linearized reading' (duration: 1.30443179s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:51.927802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:50.623181Z","time spent":"1.304615474s","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-31T05:51:51.927526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.898168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:51:51.927863Z","caller":"traceutil/trace.go:171","msg":"trace[791753414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1286; }","duration":"131.332518ms","start":"2026-05-31T05:51:51.796521Z","end":"2026-05-31T05:51:51.927853Z","steps":["trace[791753414] 'agreement among raft nodes before linearized reading' (duration: 130.961179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:51.927693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:50.766077Z","time spent":"1.161566584s","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:51:51.981028Z","caller":"traceutil/trace.go:171","msg":"trace[528021961] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"148.765763ms","start":"2026-05-31T05:51:51.832243Z","end":"2026-05-31T05:51:51.981009Z","steps":["trace[528021961] 'process raft request' (duration: 127.415097ms)","trace[528021961] 'compare' (duration: 21.011478ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:51:52.935713Z","caller":"traceutil/trace.go:171","msg":"trace[1998849848] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1341; }","duration":"276.378375ms","start":"2026-05-31T05:51:52.659313Z","end":"2026-05-31T05:51:52.935691Z","steps":["trace[1998849848] 'read index received' (duration: 276.215022ms)","trace[1998849848] 'applied index is now lower than readState.Index' (duration: 162.503µs)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:51:52.935773Z","caller":"traceutil/trace.go:171","msg":"trace[1699374623] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"348.091635ms","start":"2026-05-31T05:51:52.58765Z","end":"2026-05-31T05:51:52.935742Z","steps":["trace[1699374623] 'process raft request' (duration: 347.925941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:52.935906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:52.587624Z","time spent":"348.209887ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:51:52.935924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.460637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:51:52.93601Z","caller":"traceutil/trace.go:171","msg":"trace[194057187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1291; }","duration":"136.61401ms","start":"2026-05-31T05:51:52.799378Z","end":"2026-05-31T05:51:52.935992Z","steps":["trace[194057187] 'agreement among raft nodes before linearized reading' (duration: 136.451707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:52.936489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.170674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-31T05:51:52.936537Z","caller":"traceutil/trace.go:171","msg":"trace[817712102] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1291; }","duration":"277.243095ms","start":"2026-05-31T05:51:52.659283Z","end":"2026-05-31T05:51:52.936526Z","steps":["trace[817712102] 'agreement among raft nodes before linearized reading' (duration: 277.086331ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:53.273177Z","caller":"traceutil/trace.go:171","msg":"trace[225233788] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"333.235469ms","start":"2026-05-31T05:51:52.939923Z","end":"2026-05-31T05:51:53.273158Z","steps":["trace[225233788] 'process raft request' (duration: 333.134646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:53.273288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:52.939904Z","time spent":"333.326911ms","remote":"127.0.0.1:47592","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-05-31T05:51:53.335343Z","caller":"traceutil/trace.go:171","msg":"trace[631705678] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"368.759156ms","start":"2026-05-31T05:51:52.966562Z","end":"2026-05-31T05:51:53.335321Z","steps":["trace[631705678] 'process raft request' (duration: 368.632343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:53.33545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:52.96654Z","time spent":"368.860288ms","remote":"127.0.0.1:47422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T05:51:53.335332Z","caller":"traceutil/trace.go:171","msg":"trace[1745396530] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1342; }","duration":"159.973235ms","start":"2026-05-31T05:51:53.175331Z","end":"2026-05-31T05:51:53.335305Z","steps":["trace[1745396530] 'read index received' (duration: 97.787817ms)","trace[1745396530] 'applied index is now lower than readState.Index' (duration: 62.184328ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:51:53.3355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.153148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-31T05:51:53.335551Z","caller":"traceutil/trace.go:171","msg":"trace[2052966996] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1293; }","duration":"160.268871ms","start":"2026-05-31T05:51:53.175268Z","end":"2026-05-31T05:51:53.335537Z","steps":["trace[2052966996] 'agreement among raft nodes before linearized reading' (duration: 160.117078ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:54.265096Z","caller":"traceutil/trace.go:171","msg":"trace[1021582977] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"179.637432ms","start":"2026-05-31T05:51:54.085434Z","end":"2026-05-31T05:51:54.265071Z","steps":["trace[1021582977] 'process raft request' (duration: 179.587331ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:54.265155Z","caller":"traceutil/trace.go:171","msg":"trace[1335440023] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"180.889682ms","start":"2026-05-31T05:51:54.084234Z","end":"2026-05-31T05:51:54.265124Z","steps":["trace[1335440023] 'process raft request' (duration: 155.347467ms)","trace[1335440023] 'compare' (duration: 25.298009ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:01.941032Z","caller":"traceutil/trace.go:171","msg":"trace[1513740620] linearizableReadLoop","detail":"{readStateIndex:1425; appliedIndex:1424; }","duration":"142.908498ms","start":"2026-05-31T05:52:01.798091Z","end":"2026-05-31T05:52:01.940999Z","steps":["trace[1513740620] 'read index received' (duration: 142.656942ms)","trace[1513740620] 'applied index is now lower than readState.Index' (duration: 250.416µs)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:52:01.941216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.101832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:52:01.941257Z","caller":"traceutil/trace.go:171","msg":"trace[2120173283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1372; }","duration":"143.226255ms","start":"2026-05-31T05:52:01.79802Z","end":"2026-05-31T05:52:01.941247Z","steps":["trace[2120173283] 'agreement among raft nodes before linearized reading' (duration: 143.109993ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:01.941397Z","caller":"traceutil/trace.go:171","msg":"trace[1930049222] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"153.200128ms","start":"2026-05-31T05:52:01.788136Z","end":"2026-05-31T05:52:01.941336Z","steps":["trace[1930049222] 'process raft request' (duration: 152.702706ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:02.125358Z","caller":"traceutil/trace.go:171","msg":"trace[800202896] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"174.269928ms","start":"2026-05-31T05:52:01.951055Z","end":"2026-05-31T05:52:02.125325Z","steps":["trace[800202896] 'process raft request' (duration: 147.633908ms)","trace[800202896] 'compare' (duration: 26.505107ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:02.340042Z","caller":"traceutil/trace.go:171","msg":"trace[1659982928] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"178.408823ms","start":"2026-05-31T05:52:02.161411Z","end":"2026-05-31T05:52:02.33982Z","steps":["trace[1659982928] 'process raft request' (duration: 178.213179ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:02.374531Z","caller":"traceutil/trace.go:171","msg":"trace[2053932038] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"210.136672ms","start":"2026-05-31T05:52:02.164352Z","end":"2026-05-31T05:52:02.374489Z","steps":["trace[2053932038] 'process raft request' (duration: 209.890076ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:02.374543Z","caller":"traceutil/trace.go:171","msg":"trace[1646258382] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"158.173872ms","start":"2026-05-31T05:52:02.216331Z","end":"2026-05-31T05:52:02.374505Z","steps":["trace[1646258382] 'process raft request' (duration: 158.08395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:02.627533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.002023ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:52:02.627657Z","caller":"traceutil/trace.go:171","msg":"trace[695561522] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1376; }","duration":"129.142697ms","start":"2026-05-31T05:52:02.498498Z","end":"2026-05-31T05:52:02.62764Z","steps":["trace[695561522] 'range keys from in-memory index tree' (duration: 128.983933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:02.627877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.164049ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:52:02.628048Z","caller":"traceutil/trace.go:171","msg":"trace[44953417] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"247.652187ms","start":"2026-05-31T05:52:02.380377Z","end":"2026-05-31T05:52:02.628029Z","steps":["trace[44953417] 'process raft request' (duration: 81.255453ms)","trace[44953417] 'compare' (duration: 166.027795ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:02.628389Z","caller":"traceutil/trace.go:171","msg":"trace[1678962941] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"247.116454ms","start":"2026-05-31T05:52:02.381254Z","end":"2026-05-31T05:52:02.62837Z","steps":["trace[1678962941] 'process raft request' (duration: 246.728035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:03.755147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.875902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:52:03.755219Z","caller":"traceutil/trace.go:171","msg":"trace[290604656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1378; }","duration":"954.991974ms","start":"2026-05-31T05:52:02.800207Z","end":"2026-05-31T05:52:03.755199Z","steps":["trace[290604656] 'range keys from in-memory index tree' (duration: 954.78234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:03.755268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:02.800186Z","time spent":"955.067597ms","remote":"127.0.0.1:47350","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-05-31T05:52:03.755443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.482716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:52:03.755584Z","caller":"traceutil/trace.go:171","msg":"trace[551338560] linearizableReadLoop","detail":"{readStateIndex:1432; appliedIndex:1431; }","duration":"727.090298ms","start":"2026-05-31T05:52:03.028453Z","end":"2026-05-31T05:52:03.755543Z","steps":["trace[551338560] 'read index received' (duration: 42.472959ms)","trace[551338560] 'applied index is now lower than readState.Index' (duration: 684.616109ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:03.755627Z","caller":"traceutil/trace.go:171","msg":"trace[1291475314] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"732.811372ms","start":"2026-05-31T05:52:03.022793Z","end":"2026-05-31T05:52:03.755605Z","steps":["trace[1291475314] 'process raft request' (duration: 48.08635ms)","trace[1291475314] 'compare' (duration: 684.358503ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:52:03.755729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:03.022751Z","time spent":"732.926935ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:52:03.755777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.320084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-05-31T05:52:03.755818Z","caller":"traceutil/trace.go:171","msg":"trace[2083084123] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1379; }","duration":"727.392835ms","start":"2026-05-31T05:52:03.028412Z","end":"2026-05-31T05:52:03.755805Z","steps":["trace[2083084123] 'agreement among raft nodes before linearized reading' (duration: 727.251102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:03.75587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:03.028398Z","time spent":"727.452437ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-31T05:52:03.756256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.800194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-31T05:52:03.756297Z","caller":"traceutil/trace.go:171","msg":"trace[228066140] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1379; }","duration":"297.868035ms","start":"2026-05-31T05:52:03.45842Z","end":"2026-05-31T05:52:03.756288Z","steps":["trace[228066140] 'agreement among raft nodes before linearized reading' (duration: 297.766952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:03.75671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.486488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-31T05:52:03.757023Z","caller":"traceutil/trace.go:171","msg":"trace[1446561937] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1379; }","duration":"444.801826ms","start":"2026-05-31T05:52:03.312214Z","end":"2026-05-31T05:52:03.757016Z","steps":["trace[1446561937] 'agreement among raft nodes before linearized reading' (duration: 443.782242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:03.757063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:03.31216Z","time spent":"444.880388ms","remote":"127.0.0.1:47592","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-05-31T05:52:04.262209Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843765690372549,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-31T05:52:04.415463Z","caller":"traceutil/trace.go:171","msg":"trace[358968888] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"654.414977ms","start":"2026-05-31T05:52:03.761027Z","end":"2026-05-31T05:52:04.415442Z","steps":["trace[358968888] 'process raft request' (duration: 654.288044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:04.41561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:03.761012Z","time spent":"654.523189ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:52:04.487081Z","caller":"traceutil/trace.go:171","msg":"trace[166712438] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"725.0091ms","start":"2026-05-31T05:52:03.762047Z","end":"2026-05-31T05:52:04.487056Z","steps":["trace[166712438] 'process raft request' (duration: 724.947759ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:04.487114Z","caller":"traceutil/trace.go:171","msg":"trace[504409034] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"725.969763ms","start":"2026-05-31T05:52:03.761106Z","end":"2026-05-31T05:52:04.487076Z","steps":["trace[504409034] 'process raft request' (duration: 725.768008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:04.487218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:03.762033Z","time spent":"725.113772ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:52:04.487314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:03.761081Z","time spent":"726.122126ms","remote":"127.0.0.1:47592","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-05-31T05:52:04.487455Z","caller":"traceutil/trace.go:171","msg":"trace[1205595997] linearizableReadLoop","detail":"{readStateIndex:1436; appliedIndex:1433; }","duration":"726.119586ms","start":"2026-05-31T05:52:03.761303Z","end":"2026-05-31T05:52:04.487423Z","steps":["trace[1205595997] 'read index received' (duration: 654.16076ms)","trace[1205595997] 'applied index is now lower than readState.Index' (duration: 71.889264ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:04.487586Z","caller":"traceutil/trace.go:171","msg":"trace[571327037] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"356.259535ms","start":"2026-05-31T05:52:04.131294Z","end":"2026-05-31T05:52:04.487554Z","steps":["trace[571327037] 'process raft request' (duration: 355.727262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:04.488066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:04.131268Z","time spent":"356.734325ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:52:04.487792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.469835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:5 size:6175"} {"level":"info","ts":"2026-05-31T05:52:04.488274Z","caller":"traceutil/trace.go:171","msg":"trace[511415716] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:5; response_revision:1383; }","duration":"726.995756ms","start":"2026-05-31T05:52:03.761264Z","end":"2026-05-31T05:52:04.48826Z","steps":["trace[511415716] 'agreement among raft nodes before linearized reading' (duration: 726.345691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:04.488309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:03.761256Z","time spent":"727.041327ms","remote":"127.0.0.1:47534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":5,"response size":6197,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"warn","ts":"2026-05-31T05:52:04.488508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.410953ms","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-05-31T05:52:04.489045Z","caller":"traceutil/trace.go:171","msg":"trace[1911045406] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1383; }","duration":"144.980765ms","start":"2026-05-31T05:52:04.344046Z","end":"2026-05-31T05:52:04.489027Z","steps":["trace[1911045406] 'agreement among raft nodes before linearized reading' (duration: 144.360851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:04.488856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.85807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-31T05:52:04.489239Z","caller":"traceutil/trace.go:171","msg":"trace[457272647] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1383; }","duration":"129.260349ms","start":"2026-05-31T05:52:04.359956Z","end":"2026-05-31T05:52:04.489216Z","steps":["trace[457272647] 'agreement among raft nodes before linearized reading' (duration: 128.822209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:04.488955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.791277ms","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-05-31T05:52:04.489413Z","caller":"traceutil/trace.go:171","msg":"trace[2012480324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1383; }","duration":"110.290008ms","start":"2026-05-31T05:52:04.379106Z","end":"2026-05-31T05:52:04.489396Z","steps":["trace[2012480324] 'agreement among raft nodes before linearized reading' (duration: 109.667853ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:06.616141Z","caller":"traceutil/trace.go:171","msg":"trace[613286330] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"123.816483ms","start":"2026-05-31T05:52:06.492298Z","end":"2026-05-31T05:52:06.616115Z","steps":["trace[613286330] 'process raft request' (duration: 123.653199ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:06.619634Z","caller":"traceutil/trace.go:171","msg":"trace[521602196] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"123.685939ms","start":"2026-05-31T05:52:06.495927Z","end":"2026-05-31T05:52:06.619613Z","steps":["trace[521602196] 'process raft request' (duration: 123.507535ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:06.619691Z","caller":"traceutil/trace.go:171","msg":"trace[1916933689] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"123.527846ms","start":"2026-05-31T05:52:06.496127Z","end":"2026-05-31T05:52:06.619655Z","steps":["trace[1916933689] 'process raft request' (duration: 123.446525ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:10.627992Z","caller":"traceutil/trace.go:171","msg":"trace[1727550669] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"127.397736ms","start":"2026-05-31T05:52:10.500566Z","end":"2026-05-31T05:52:10.627964Z","steps":["trace[1727550669] 'process raft request' (duration: 127.234362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:23.161526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.379405ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:52:23.161749Z","caller":"traceutil/trace.go:171","msg":"trace[410042851] transaction","detail":"{read_only:false; response_revision:1466; number_of_response:1; }","duration":"339.986196ms","start":"2026-05-31T05:52:22.821745Z","end":"2026-05-31T05:52:23.161731Z","steps":["trace[410042851] 'process raft request' (duration: 339.895444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:23.16182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:22.821732Z","time spent":"340.056227ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:52:23.161827Z","caller":"traceutil/trace.go:171","msg":"trace[1667887082] transaction","detail":"{read_only:false; response_revision:1465; number_of_response:1; }","duration":"357.835412ms","start":"2026-05-31T05:52:22.80385Z","end":"2026-05-31T05:52:23.161686Z","steps":["trace[1667887082] 'process raft request' (duration: 78.207772ms)","trace[1667887082] 'compare' (duration: 279.046716ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:23.161887Z","caller":"traceutil/trace.go:171","msg":"trace[1379383190] linearizableReadLoop","detail":"{readStateIndex:1522; appliedIndex:1521; }","duration":"356.591272ms","start":"2026-05-31T05:52:22.805242Z","end":"2026-05-31T05:52:23.161833Z","steps":["trace[1379383190] 'read index received' (duration: 76.801188ms)","trace[1379383190] 'applied index is now lower than readState.Index' (duration: 279.787554ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:52:23.16209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:22.803836Z","time spent":"358.123768ms","remote":"127.0.0.1:47592","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-05-31T05:52:23.162218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.956611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-31T05:52:23.162297Z","caller":"traceutil/trace.go:171","msg":"trace[1941848542] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1466; }","duration":"357.077414ms","start":"2026-05-31T05:52:22.805207Z","end":"2026-05-31T05:52:23.162285Z","steps":["trace[1941848542] 'agreement among raft nodes before linearized reading' (duration: 356.731216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:23.162339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.194747ms","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-05-31T05:52:23.162356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.74316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-31T05:52:23.162394Z","caller":"traceutil/trace.go:171","msg":"trace[688926252] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1466; }","duration":"348.805141ms","start":"2026-05-31T05:52:22.81358Z","end":"2026-05-31T05:52:23.162385Z","steps":["trace[688926252] 'agreement among raft nodes before linearized reading' (duration: 348.653897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:23.162439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:22.813572Z","time spent":"348.860772ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-05-31T05:52:23.162425Z","caller":"traceutil/trace.go:171","msg":"trace[1698736848] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1466; }","duration":"354.310529ms","start":"2026-05-31T05:52:22.808098Z","end":"2026-05-31T05:52:23.162408Z","steps":["trace[1698736848] 'agreement among raft nodes before linearized reading' (duration: 354.084473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:23.162361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:22.805195Z","time spent":"357.154295ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-31T05:52:23.162331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.480289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-31T05:52:23.162547Z","caller":"traceutil/trace.go:171","msg":"trace[1872005281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1466; }","duration":"353.717415ms","start":"2026-05-31T05:52:22.808822Z","end":"2026-05-31T05:52:23.162539Z","steps":["trace[1872005281] 'agreement among raft nodes before linearized reading' (duration: 353.371357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:23.162575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:22.80874Z","time spent":"353.827637ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-31T05:52:23.16253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:22.808083Z","time spent":"354.435372ms","remote":"127.0.0.1:47592","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-05-31T05:52:23.16253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.403348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-31T05:52:23.16288Z","caller":"traceutil/trace.go:171","msg":"trace[1151747480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1466; }","duration":"353.777696ms","start":"2026-05-31T05:52:22.809088Z","end":"2026-05-31T05:52:23.162865Z","steps":["trace[1151747480] 'agreement among raft nodes before linearized reading' (duration: 353.0912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:23.163112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:22.809081Z","time spent":"354.015932ms","remote":"127.0.0.1:47592","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":"info","ts":"2026-05-31T05:52:23.267073Z","caller":"traceutil/trace.go:171","msg":"trace[873223075] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"100.196283ms","start":"2026-05-31T05:52:23.16685Z","end":"2026-05-31T05:52:23.267046Z","steps":["trace[873223075] 'process raft request' (duration: 99.816034ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:23.267436Z","caller":"traceutil/trace.go:171","msg":"trace[60541327] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"100.382798ms","start":"2026-05-31T05:52:23.167034Z","end":"2026-05-31T05:52:23.267417Z","steps":["trace[60541327] 'process raft request' (duration: 100.07391ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:23.266763Z","caller":"traceutil/trace.go:171","msg":"trace[1141599100] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"100.030819ms","start":"2026-05-31T05:52:23.166693Z","end":"2026-05-31T05:52:23.266724Z","steps":["trace[1141599100] 'process raft request' (duration: 98.189137ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:29.486203Z","caller":"traceutil/trace.go:171","msg":"trace[478000516] transaction","detail":"{read_only:false; response_revision:1495; number_of_response:1; }","duration":"129.376764ms","start":"2026-05-31T05:52:29.356798Z","end":"2026-05-31T05:52:29.486175Z","steps":["trace[478000516] 'process raft request' (duration: 128.906813ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:29.486452Z","caller":"traceutil/trace.go:171","msg":"trace[1802057984] transaction","detail":"{read_only:false; response_revision:1496; number_of_response:1; }","duration":"129.511947ms","start":"2026-05-31T05:52:29.356917Z","end":"2026-05-31T05:52:29.486429Z","steps":["trace[1802057984] 'process raft request' (duration: 129.20519ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:29.486736Z","caller":"traceutil/trace.go:171","msg":"trace[1177248099] transaction","detail":"{read_only:false; response_revision:1497; number_of_response:1; }","duration":"129.781104ms","start":"2026-05-31T05:52:29.356895Z","end":"2026-05-31T05:52:29.486676Z","steps":["trace[1177248099] 'process raft request' (duration: 129.427365ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:29.487532Z","caller":"traceutil/trace.go:171","msg":"trace[1430420606] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"130.126632ms","start":"2026-05-31T05:52:29.357356Z","end":"2026-05-31T05:52:29.487483Z","steps":["trace[1430420606] 'process raft request' (duration: 129.21625ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:44.747241Z","caller":"traceutil/trace.go:171","msg":"trace[974161309] transaction","detail":"{read_only:false; response_revision:1565; number_of_response:1; }","duration":"121.582441ms","start":"2026-05-31T05:52:44.625636Z","end":"2026-05-31T05:52:44.747218Z","steps":["trace[974161309] 'process raft request' (duration: 121.463229ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:45.898663Z","caller":"traceutil/trace.go:171","msg":"trace[1527534190] transaction","detail":"{read_only:false; response_revision:1575; number_of_response:1; }","duration":"186.552044ms","start":"2026-05-31T05:52:45.712088Z","end":"2026-05-31T05:52:45.89864Z","steps":["trace[1527534190] 'process raft request' (duration: 186.419391ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:07.893461Z","caller":"traceutil/trace.go:171","msg":"trace[637977125] transaction","detail":"{read_only:false; response_revision:1675; number_of_response:1; }","duration":"120.153327ms","start":"2026-05-31T05:53:07.773281Z","end":"2026-05-31T05:53:07.893435Z","steps":["trace[637977125] 'process raft request' (duration: 115.550979ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:10.026207Z","caller":"traceutil/trace.go:171","msg":"trace[973538212] transaction","detail":"{read_only:false; response_revision:1684; number_of_response:1; }","duration":"104.191043ms","start":"2026-05-31T05:53:09.921959Z","end":"2026-05-31T05:53:10.02615Z","steps":["trace[973538212] 'process raft request' (duration: 86.441807ms)","trace[973538212] 'compare' (duration: 17.581132ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:53:15.646175Z","caller":"traceutil/trace.go:171","msg":"trace[1766628744] transaction","detail":"{read_only:false; response_revision:1707; number_of_response:1; }","duration":"133.718455ms","start":"2026-05-31T05:53:15.512425Z","end":"2026-05-31T05:53:15.646144Z","steps":["trace[1766628744] 'process raft request' (duration: 133.50544ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:54:34.217759Z","caller":"traceutil/trace.go:171","msg":"trace[1645972192] linearizableReadLoop","detail":"{readStateIndex:2147; appliedIndex:2146; }","duration":"131.534483ms","start":"2026-05-31T05:54:34.086201Z","end":"2026-05-31T05:54:34.217735Z","steps":["trace[1645972192] 'read index received' (duration: 131.362709ms)","trace[1645972192] 'applied index is now lower than readState.Index' (duration: 170.514µs)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:54:34.217825Z","caller":"traceutil/trace.go:171","msg":"trace[745063666] transaction","detail":"{read_only:false; response_revision:2064; number_of_response:1; }","duration":"174.955011ms","start":"2026-05-31T05:54:34.042836Z","end":"2026-05-31T05:54:34.217791Z","steps":["trace[745063666] 'process raft request' (duration: 174.720486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:54:34.217924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.699708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:54:34.217962Z","caller":"traceutil/trace.go:171","msg":"trace[1329386781] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:2064; }","duration":"131.755559ms","start":"2026-05-31T05:54:34.086195Z","end":"2026-05-31T05:54:34.21795Z","steps":["trace[1329386781] 'agreement among raft nodes before linearized reading' (duration: 131.680147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:54:34.218161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.894171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-31T05:54:34.21813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.396157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-31T05:54:34.218238Z","caller":"traceutil/trace.go:171","msg":"trace[163088862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2064; }","duration":"122.996813ms","start":"2026-05-31T05:54:34.095222Z","end":"2026-05-31T05:54:34.218219Z","steps":["trace[163088862] 'agreement among raft nodes before linearized reading' (duration: 122.799519ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:54:34.218251Z","caller":"traceutil/trace.go:171","msg":"trace[335607827] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2064; }","duration":"130.552571ms","start":"2026-05-31T05:54:34.08768Z","end":"2026-05-31T05:54:34.218233Z","steps":["trace[335607827] 'agreement among raft nodes before linearized reading' (duration: 130.229083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:55:18.886924Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843765690374747,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-31T05:55:19.146305Z","caller":"traceutil/trace.go:171","msg":"trace[100411746] linearizableReadLoop","detail":"{readStateIndex:2360; appliedIndex:2359; }","duration":"759.879741ms","start":"2026-05-31T05:55:18.386394Z","end":"2026-05-31T05:55:19.146274Z","steps":["trace[100411746] 'read index received' (duration: 759.629665ms)","trace[100411746] 'applied index is now lower than readState.Index' (duration: 248.786µs)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:55:19.146342Z","caller":"traceutil/trace.go:171","msg":"trace[1047366542] transaction","detail":"{read_only:false; response_revision:2268; number_of_response:1; }","duration":"762.403781ms","start":"2026-05-31T05:55:18.383906Z","end":"2026-05-31T05:55:19.14631Z","steps":["trace[1047366542] 'process raft request' (duration: 762.179605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:55:19.146489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:55:18.383873Z","time spent":"762.538824ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:55:19.14664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.883936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:55:19.146681Z","caller":"traceutil/trace.go:171","msg":"trace[1620109218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2268; }","duration":"346.950128ms","start":"2026-05-31T05:55:18.799716Z","end":"2026-05-31T05:55:19.146666Z","steps":["trace[1620109218] 'agreement among raft nodes before linearized reading' (duration: 346.855146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:55:19.146691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.794179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-31T05:55:19.146711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:55:18.799701Z","time spent":"347.004039ms","remote":"127.0.0.1:47350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-31T05:55:19.146737Z","caller":"traceutil/trace.go:171","msg":"trace[228406792] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2268; }","duration":"727.863831ms","start":"2026-05-31T05:55:18.41886Z","end":"2026-05-31T05:55:19.146723Z","steps":["trace[228406792] 'agreement among raft nodes before linearized reading' (duration: 727.634086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:55:19.146768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:55:18.418847Z","time spent":"727.914042ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-31T05:55:19.146773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.865175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-31T05:55:19.146779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.368513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-31T05:55:19.146778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.460805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-31T05:55:19.146838Z","caller":"traceutil/trace.go:171","msg":"trace[1953743087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2268; }","duration":"509.982826ms","start":"2026-05-31T05:55:18.636836Z","end":"2026-05-31T05:55:19.146818Z","steps":["trace[1953743087] 'agreement among raft nodes before linearized reading' (duration: 509.742941ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:55:19.146839Z","caller":"traceutil/trace.go:171","msg":"trace[549434171] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2268; }","duration":"760.472795ms","start":"2026-05-31T05:55:18.386353Z","end":"2026-05-31T05:55:19.146826Z","steps":["trace[549434171] 'agreement among raft nodes before linearized reading' (duration: 760.085246ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:55:19.146861Z","caller":"traceutil/trace.go:171","msg":"trace[1343422565] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2268; }","duration":"512.615899ms","start":"2026-05-31T05:55:18.634223Z","end":"2026-05-31T05:55:19.146839Z","steps":["trace[1343422565] 'agreement among raft nodes before linearized reading' (duration: 512.345893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:55:19.146889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:55:18.636817Z","time spent":"510.062099ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-31T05:55:19.146892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:55:18.386325Z","time spent":"760.554667ms","remote":"127.0.0.1:47706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-31T05:55:19.146907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:55:18.634206Z","time spent":"512.69225ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-31T05:55:19.146969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.702019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-31T05:55:19.147001Z","caller":"traceutil/trace.go:171","msg":"trace[457897296] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2268; }","duration":"675.751371ms","start":"2026-05-31T05:55:18.47124Z","end":"2026-05-31T05:55:19.146992Z","steps":["trace[457897296] 'agreement among raft nodes before linearized reading' (duration: 675.660928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:55:19.147027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:55:18.471231Z","time spent":"675.789921ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-31T05:55:19.147106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.712773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-31T05:55:19.147122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.437057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-31T05:55:19.147144Z","caller":"traceutil/trace.go:171","msg":"trace[445655923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2268; }","duration":"714.773605ms","start":"2026-05-31T05:55:18.432361Z","end":"2026-05-31T05:55:19.147135Z","steps":["trace[445655923] 'agreement among raft nodes before linearized reading' (duration: 714.670702ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:55:19.147154Z","caller":"traceutil/trace.go:171","msg":"trace[2003792233] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2268; }","duration":"685.487958ms","start":"2026-05-31T05:55:18.461656Z","end":"2026-05-31T05:55:19.147144Z","steps":["trace[2003792233] 'agreement among raft nodes before linearized reading' (duration: 685.393146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:55:19.147172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:55:18.43235Z","time spent":"714.816176ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-31T05:55:19.147181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:55:18.461646Z","time spent":"685.5298ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-31T05:55:25.861624Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843765690374816,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-31T05:55:26.362669Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843765690374816,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-31T05:55:26.863088Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646843765690374816,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-31T05:55:27.069768Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.816959123s","expected-duration":"1s"} {"level":"info","ts":"2026-05-31T05:55:27.071267Z","caller":"traceutil/trace.go:171","msg":"trace[1703537728] transaction","detail":"{read_only:false; response_revision:2297; number_of_response:1; }","duration":"1.818600781s","start":"2026-05-31T05:55:25.252632Z","end":"2026-05-31T05:55:27.071233Z","steps":["trace[1703537728] 'process raft request' (duration: 1.817376783s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:55:27.071627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:55:25.252614Z","time spent":"1.818895939s","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:55:27.071276Z","caller":"traceutil/trace.go:171","msg":"trace[228775666] transaction","detail":"{read_only:false; response_revision:2299; number_of_response:1; }","duration":"1.818611212s","start":"2026-05-31T05:55:25.25264Z","end":"2026-05-31T05:55:27.071252Z","steps":["trace[228775666] 'process raft request' (duration: 1.818570321s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:55:27.072163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:55:25.252626Z","time spent":"1.819463611s","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:55:27.071337Z","caller":"traceutil/trace.go:171","msg":"trace[1523563433] transaction","detail":"{read_only:false; response_revision:2298; number_of_response:1; }","duration":"1.818679923s","start":"2026-05-31T05:55:25.252633Z","end":"2026-05-31T05:55:27.071312Z","steps":["trace[1523563433] 'process raft request' (duration: 1.818496209s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:55:27.072443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:55:25.252618Z","time spent":"1.819731797s","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:55:27.074186Z","caller":"traceutil/trace.go:171","msg":"trace[1089148584] linearizableReadLoop","detail":"{readStateIndex:2395; appliedIndex:2389; }","duration":"1.712645949s","start":"2026-05-31T05:55:25.361523Z","end":"2026-05-31T05:55:27.074169Z","steps":["trace[1089148584] 'read index received' (duration: 1.708513082s)","trace[1089148584] 'applied index is now lower than readState.Index' (duration: 4.132007ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:55:27.074412Z","caller":"traceutil/trace.go:171","msg":"trace[884190753] transaction","detail":"{read_only:false; response_revision:2303; number_of_response:1; }","duration":"121.513366ms","start":"2026-05-31T05:55:26.952879Z","end":"2026-05-31T05:55:27.074393Z","steps":["trace[884190753] 'process raft request' (duration: 121.26429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:55:27.074554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.713022939s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-31T05:55:27.074582Z","caller":"traceutil/trace.go:171","msg":"trace[1363572682] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:2303; }","duration":"1.71309888s","start":"2026-05-31T05:55:25.361475Z","end":"2026-05-31T05:55:27.074574Z","steps":["trace[1363572682] 'agreement among raft nodes before linearized reading' (duration: 1.712973537s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:55:27.074604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:55:25.361455Z","time spent":"1.713143421s","remote":"127.0.0.1:47386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.205\" "} {"level":"warn","ts":"2026-05-31T05:55:27.07476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.274779912s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:55:27.074792Z","caller":"traceutil/trace.go:171","msg":"trace[1706980268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2303; }","duration":"1.274849943s","start":"2026-05-31T05:55:25.799935Z","end":"2026-05-31T05:55:27.074785Z","steps":["trace[1706980268] 'agreement among raft nodes before linearized reading' (duration: 1.274801462s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:55:27.074814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:55:25.799916Z","time spent":"1.274894285s","remote":"127.0.0.1:47350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-31T05:55:27.074815Z","caller":"traceutil/trace.go:171","msg":"trace[1540477595] transaction","detail":"{read_only:false; response_revision:2302; number_of_response:1; }","duration":"1.452577187s","start":"2026-05-31T05:55:25.622126Z","end":"2026-05-31T05:55:27.074703Z","steps":["trace[1540477595] 'process raft request' (duration: 1.451978382s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:55:27.075016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:55:25.6221Z","time spent":"1.452845293s","remote":"127.0.0.1:47592","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-05-31T05:55:27.075064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.059205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-31T05:55:27.075157Z","caller":"traceutil/trace.go:171","msg":"trace[871550575] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2303; }","duration":"845.188668ms","start":"2026-05-31T05:55:26.22996Z","end":"2026-05-31T05:55:27.075149Z","steps":["trace[871550575] 'agreement among raft nodes before linearized reading' (duration: 845.049235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:55:27.075185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:55:26.229942Z","time spent":"845.23685ms","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-31T05:55:27.075297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246245353s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-31T05:55:27.075457Z","caller":"traceutil/trace.go:171","msg":"trace[1127099219] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2303; }","duration":"1.246443408s","start":"2026-05-31T05:55:25.828998Z","end":"2026-05-31T05:55:27.075442Z","steps":["trace[1127099219] 'agreement among raft nodes before linearized reading' (duration: 1.246160391s)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:55:27.075468Z","caller":"traceutil/trace.go:171","msg":"trace[1027796985] transaction","detail":"{read_only:false; response_revision:2301; number_of_response:1; }","duration":"1.822347739s","start":"2026-05-31T05:55:25.252989Z","end":"2026-05-31T05:55:27.075336Z","steps":["trace[1027796985] 'process raft request' (duration: 1.82108993s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:55:27.075555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:55:25.828979Z","time spent":"1.246541421s","remote":"127.0.0.1:47534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-05-31T05:55:27.075619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:55:25.25298Z","time spent":"1.822566804s","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:55:27.075546Z","caller":"traceutil/trace.go:171","msg":"trace[312704858] transaction","detail":"{read_only:false; response_revision:2300; number_of_response:1; }","duration":"1.822888502s","start":"2026-05-31T05:55:25.252643Z","end":"2026-05-31T05:55:27.075531Z","steps":["trace[312704858] 'process raft request' (duration: 1.821333135s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:55:27.075808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:55:25.252621Z","time spent":"1.823117847s","remote":"127.0.0.1:47592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}