{"level":"warn","ts":"2026-08-16T12:46:45.239871Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-16T12:46:45.239968Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140: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.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-16T12:46:45.240044Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-16T12:46:45.240059Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-08-16T12:46:45.240081Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-16T12:46:45.240949Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-08-16T12:46:45.241043Z","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.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-16T12:46:45.245499Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.148008ms"} {"level":"info","ts":"2026-08-16T12:46:45.258707Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-08-16T12:46:45.25879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-08-16T12:46:45.258807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-08-16T12:46:45.258817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-16T12:46:45.258825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-08-16T12:46:45.258857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-08-16T12:46:45.267021Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-16T12:46:45.27177Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-16T12:46:45.273022Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-16T12:46:45.275351Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-16T12:46:45.275527Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-16T12:46:45.275787Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-16T12:46:45.275883Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-16T12:46:45.275919Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-16T12:46:45.27799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-08-16T12:46:45.278327Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-08-16T12:46:45.281631Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-16T12:46:45.28181Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-08-16T12:46:45.281832Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-08-16T12:46:45.282048Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-16T12:46:45.282111Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-16T12:46:45.960088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-08-16T12:46:45.960276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-08-16T12:46:45.960341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-08-16T12:46:45.960378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-08-16T12:46:45.960398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-08-16T12:46:45.960421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-08-16T12:46:45.960451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-08-16T12:46:45.970136Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:46:45.972339Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-16T12:46:45.972344Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-08-16T12:46:45.972409Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-16T12:46:45.974199Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"info","ts":"2026-08-16T12:46:45.975378Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:46:45.975532Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:46:45.97562Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:46:45.975879Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-16T12:46:45.981722Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-16T12:46:45.981798Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-08-16T12:46:49.251898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.663253ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:46:49.252037Z","caller":"traceutil/trace.go:171","msg":"trace[814139998] transaction","detail":"{read_only:false; response_revision:144; number_of_response:1; }","duration":"178.409064ms","start":"2026-08-16T12:46:49.073608Z","end":"2026-08-16T12:46:49.252017Z","steps":["trace[814139998] 'process raft request' (duration: 70.83143ms)","trace[814139998] 'compare' (duration: 106.464168ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:46:51.467562Z","caller":"traceutil/trace.go:171","msg":"trace[951939432] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"114.838252ms","start":"2026-08-16T12:46:51.352688Z","end":"2026-08-16T12:46:51.467526Z","steps":["trace[951939432] 'process raft request' (duration: 40.305913ms)","trace[951939432] 'compare' (duration: 74.279359ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:46:51.790267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.785395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:46:51.790418Z","caller":"traceutil/trace.go:171","msg":"trace[1243104597] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"269.748199ms","start":"2026-08-16T12:46:51.520638Z","end":"2026-08-16T12:46:51.790386Z","steps":["trace[1243104597] 'process raft request' (duration: 50.756968ms)","trace[1243104597] 'compare' (duration: 218.254085ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:46:51.790428Z","caller":"traceutil/trace.go:171","msg":"trace[1882795579] linearizableReadLoop","detail":"{readStateIndex:235; appliedIndex:234; }","duration":"240.593855ms","start":"2026-08-16T12:46:51.549815Z","end":"2026-08-16T12:46:51.790409Z","steps":["trace[1882795579] 'read index received' (duration: 21.587072ms)","trace[1882795579] 'applied index is now lower than readState.Index' (duration: 219.005333ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:46:51.790656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.79595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-08-16T12:46:51.790702Z","caller":"traceutil/trace.go:171","msg":"trace[83859243] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:229; }","duration":"240.902028ms","start":"2026-08-16T12:46:51.549787Z","end":"2026-08-16T12:46:51.790689Z","steps":["trace[83859243] 'agreement among raft nodes before linearized reading' (duration: 240.737136ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:46:51.803139Z","caller":"traceutil/trace.go:171","msg":"trace[7540798] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"253.186344ms","start":"2026-08-16T12:46:51.54992Z","end":"2026-08-16T12:46:51.803107Z","steps":["trace[7540798] 'process raft request' (duration: 253.040343ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:46:51.803262Z","caller":"traceutil/trace.go:171","msg":"trace[506606356] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"252.751761ms","start":"2026-08-16T12:46:51.550491Z","end":"2026-08-16T12:46:51.803243Z","steps":["trace[506606356] 'process raft request' (duration: 252.569417ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:46:51.866579Z","caller":"traceutil/trace.go:171","msg":"trace[21171625] transaction","detail":"{read_only:false; number_of_response:0; response_revision:231; }","duration":"270.108716ms","start":"2026-08-16T12:46:51.596455Z","end":"2026-08-16T12:46:51.866564Z","steps":["trace[21171625] 'process raft request' (duration: 269.867188ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:46:51.866661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.823632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:46:51.866657Z","caller":"traceutil/trace.go:171","msg":"trace[64244733] transaction","detail":"{read_only:false; number_of_response:0; response_revision:233; }","duration":"253.926582ms","start":"2026-08-16T12:46:51.612697Z","end":"2026-08-16T12:46:51.866623Z","steps":["trace[64244733] 'process raft request' (duration: 253.831425ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:46:51.866761Z","caller":"traceutil/trace.go:171","msg":"trace[1646432456] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"252.581097ms","start":"2026-08-16T12:46:51.614173Z","end":"2026-08-16T12:46:51.866754Z","steps":["trace[1646432456] 'process raft request' (duration: 252.368221ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:46:51.866755Z","caller":"traceutil/trace.go:171","msg":"trace[689544692] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"256.673242ms","start":"2026-08-16T12:46:51.610045Z","end":"2026-08-16T12:46:51.866718Z","steps":["trace[689544692] 'process raft request' (duration: 256.375529ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:46:51.866794Z","caller":"traceutil/trace.go:171","msg":"trace[377706552] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"256.108768ms","start":"2026-08-16T12:46:51.610639Z","end":"2026-08-16T12:46:51.866748Z","steps":["trace[377706552] 'process raft request' (duration: 255.860219ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:46:51.866731Z","caller":"traceutil/trace.go:171","msg":"trace[1064102528] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:235; }","duration":"260.92377ms","start":"2026-08-16T12:46:51.605791Z","end":"2026-08-16T12:46:51.866715Z","steps":["trace[1064102528] 'agreement among raft nodes before linearized reading' (duration: 260.809651ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:46:51.867075Z","caller":"traceutil/trace.go:171","msg":"trace[58191884] transaction","detail":"{read_only:false; number_of_response:0; response_revision:232; }","duration":"256.481927ms","start":"2026-08-16T12:46:51.610566Z","end":"2026-08-16T12:46:51.867048Z","steps":["trace[58191884] 'process raft request' (duration: 255.908353ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:46:57.623875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.689441ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:46:57.62428Z","caller":"traceutil/trace.go:171","msg":"trace[2079666807] linearizableReadLoop","detail":"{readStateIndex:358; appliedIndex:356; }","duration":"318.676636ms","start":"2026-08-16T12:46:57.305586Z","end":"2026-08-16T12:46:57.624262Z","steps":["trace[2079666807] 'read index received' (duration: 125.522686ms)","trace[2079666807] 'applied index is now lower than readState.Index' (duration: 193.15309ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:46:57.624291Z","caller":"traceutil/trace.go:171","msg":"trace[1508291600] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"352.125978ms","start":"2026-08-16T12:46:57.272132Z","end":"2026-08-16T12:46:57.624258Z","steps":["trace[1508291600] 'process raft request' (duration: 158.964708ms)","trace[1508291600] 'compare' (duration: 192.552992ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:46:57.624369Z","caller":"traceutil/trace.go:171","msg":"trace[128817540] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"351.541993ms","start":"2026-08-16T12:46:57.272759Z","end":"2026-08-16T12:46:57.624301Z","steps":["trace[128817540] 'process raft request' (duration: 351.430306ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:46:57.624441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.810914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-16T12:46:57.624701Z","caller":"traceutil/trace.go:171","msg":"trace[1938809355] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:344; }","duration":"319.126175ms","start":"2026-08-16T12:46:57.305544Z","end":"2026-08-16T12:46:57.62467Z","steps":["trace[1938809355] 'agreement among raft nodes before linearized reading' (duration: 318.793984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:46:57.624797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:46:57.272105Z","time spent":"352.46279ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-16T12:46:57.624786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:46:57.305524Z","time spent":"319.237072ms","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-16T12:46:57.625417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:46:57.272749Z","time spent":"351.884575ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:46:57.633221Z","caller":"traceutil/trace.go:171","msg":"trace[485270770] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"321.407806ms","start":"2026-08-16T12:46:57.311771Z","end":"2026-08-16T12:46:57.633179Z","steps":["trace[485270770] 'process raft request' (duration: 321.203193ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:46:57.633234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.567642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:46:57.633341Z","caller":"traceutil/trace.go:171","msg":"trace[1898016497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:345; }","duration":"256.706802ms","start":"2026-08-16T12:46:57.376614Z","end":"2026-08-16T12:46:57.633321Z","steps":["trace[1898016497] 'agreement among raft nodes before linearized reading' (duration: 256.582873ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:46:57.633351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:46:57.311749Z","time spent":"321.535905ms","remote":"127.0.0.1:49978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:46:57.63315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.183975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-08-16T12:46:57.633703Z","caller":"traceutil/trace.go:171","msg":"trace[758101343] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:345; }","duration":"303.778134ms","start":"2026-08-16T12:46:57.329912Z","end":"2026-08-16T12:46:57.63369Z","steps":["trace[758101343] 'agreement among raft nodes before linearized reading' (duration: 303.176455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:46:57.63385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:46:57.329891Z","time spent":"303.943485ms","remote":"127.0.0.1:49724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":208,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"info","ts":"2026-08-16T12:47:14.058224Z","caller":"traceutil/trace.go:171","msg":"trace[1173628543] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:444; }","duration":"274.751521ms","start":"2026-08-16T12:47:13.783454Z","end":"2026-08-16T12:47:14.058206Z","steps":["trace[1173628543] 'read index received' (duration: 274.577076ms)","trace[1173628543] 'applied index is now lower than readState.Index' (duration: 173.415µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:47:14.058296Z","caller":"traceutil/trace.go:171","msg":"trace[1935501529] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"275.688374ms","start":"2026-08-16T12:47:13.78257Z","end":"2026-08-16T12:47:14.058259Z","steps":["trace[1935501529] 'process raft request' (duration: 275.513349ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:14.058385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.912167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T12:47:14.058408Z","caller":"traceutil/trace.go:171","msg":"trace[486410860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:427; }","duration":"274.986314ms","start":"2026-08-16T12:47:13.783416Z","end":"2026-08-16T12:47:14.058402Z","steps":["trace[486410860] 'agreement among raft nodes before linearized reading' (duration: 274.859878ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:14.587021Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555607884551425734,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:47:15.087731Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555607884551425734,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:47:15.235715Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.173157408s","expected-duration":"1s"} {"level":"info","ts":"2026-08-16T12:47:15.237378Z","caller":"traceutil/trace.go:171","msg":"trace[1734043049] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"1.174870745s","start":"2026-08-16T12:47:14.062485Z","end":"2026-08-16T12:47:15.237356Z","steps":["trace[1734043049] 'process raft request' (duration: 1.173558649s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:15.237525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:14.062465Z","time spent":"1.174983002s","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:47:15.299626Z","caller":"traceutil/trace.go:171","msg":"trace[357923314] linearizableReadLoop","detail":"{readStateIndex:447; appliedIndex:445; }","duration":"1.212870252s","start":"2026-08-16T12:47:14.086726Z","end":"2026-08-16T12:47:15.299596Z","steps":["trace[357923314] 'read index received' (duration: 1.149282791s)","trace[357923314] 'applied index is now lower than readState.Index' (duration: 63.586091ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:47:15.29983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.829585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:47:15.299921Z","caller":"traceutil/trace.go:171","msg":"trace[513962346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:428; }","duration":"922.949242ms","start":"2026-08-16T12:47:14.376938Z","end":"2026-08-16T12:47:15.299887Z","steps":["trace[513962346] 'agreement among raft nodes before linearized reading' (duration: 922.833165ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:15.299932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.186736ms","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-08-16T12:47:15.299977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:14.376921Z","time spent":"923.045729ms","remote":"127.0.0.1:49516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-16T12:47:15.300016Z","caller":"traceutil/trace.go:171","msg":"trace[1413272608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:428; }","duration":"333.310883ms","start":"2026-08-16T12:47:14.966687Z","end":"2026-08-16T12:47:15.299998Z","steps":["trace[1413272608] 'agreement among raft nodes before linearized reading' (duration: 333.103338ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:15.300092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:14.966674Z","time spent":"333.408471ms","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-16T12:47:15.299829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.213069277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-16T12:47:15.300163Z","caller":"traceutil/trace.go:171","msg":"trace[665496646] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:428; }","duration":"1.213443188s","start":"2026-08-16T12:47:14.086704Z","end":"2026-08-16T12:47:15.300147Z","steps":["trace[665496646] 'agreement among raft nodes before linearized reading' (duration: 1.213014639s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:15.300212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:14.086697Z","time spent":"1.213501357s","remote":"127.0.0.1:49788","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":"info","ts":"2026-08-16T12:47:15.504787Z","caller":"traceutil/trace.go:171","msg":"trace[866913461] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"200.775657ms","start":"2026-08-16T12:47:15.303988Z","end":"2026-08-16T12:47:15.504763Z","steps":["trace[866913461] 'process raft request' (duration: 200.62528ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:47:18.106082Z","caller":"traceutil/trace.go:171","msg":"trace[1414921070] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"121.94344ms","start":"2026-08-16T12:47:17.984122Z","end":"2026-08-16T12:47:18.106065Z","steps":["trace[1414921070] 'process raft request' (duration: 121.846712ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:47:20.408833Z","caller":"traceutil/trace.go:171","msg":"trace[26455508] linearizableReadLoop","detail":"{readStateIndex:461; appliedIndex:460; }","duration":"220.671814ms","start":"2026-08-16T12:47:20.18814Z","end":"2026-08-16T12:47:20.408812Z","steps":["trace[26455508] 'read index received' (duration: 220.525007ms)","trace[26455508] 'applied index is now lower than readState.Index' (duration: 145.868µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:47:20.408999Z","caller":"traceutil/trace.go:171","msg":"trace[679605452] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"268.644742ms","start":"2026-08-16T12:47:20.14031Z","end":"2026-08-16T12:47:20.408955Z","steps":["trace[679605452] 'process raft request' (duration: 268.394287ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:20.409335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.185745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12665"} {"level":"info","ts":"2026-08-16T12:47:20.4094Z","caller":"traceutil/trace.go:171","msg":"trace[1333293908] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:441; }","duration":"221.289823ms","start":"2026-08-16T12:47:20.188092Z","end":"2026-08-16T12:47:20.409382Z","steps":["trace[1333293908] 'agreement among raft nodes before linearized reading' (duration: 221.173935ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:47:21.526521Z","caller":"traceutil/trace.go:171","msg":"trace[1673058279] linearizableReadLoop","detail":"{readStateIndex:462; appliedIndex:461; }","duration":"195.495048ms","start":"2026-08-16T12:47:21.330943Z","end":"2026-08-16T12:47:21.526438Z","steps":["trace[1673058279] 'read index received' (duration: 195.348691ms)","trace[1673058279] 'applied index is now lower than readState.Index' (duration: 145.317µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:47:21.526548Z","caller":"traceutil/trace.go:171","msg":"trace[116691276] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"211.394032ms","start":"2026-08-16T12:47:21.315099Z","end":"2026-08-16T12:47:21.526493Z","steps":["trace[116691276] 'process raft request' (duration: 211.210596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:21.526701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.88079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-16T12:47:21.52671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.740584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-16T12:47:21.526775Z","caller":"traceutil/trace.go:171","msg":"trace[2107741689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:442; }","duration":"151.006238ms","start":"2026-08-16T12:47:21.375751Z","end":"2026-08-16T12:47:21.526757Z","steps":["trace[2107741689] 'agreement among raft nodes before linearized reading' (duration: 150.87725ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:47:21.526794Z","caller":"traceutil/trace.go:171","msg":"trace[91824903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:442; }","duration":"195.873401ms","start":"2026-08-16T12:47:21.330901Z","end":"2026-08-16T12:47:21.526775Z","steps":["trace[91824903] 'agreement among raft nodes before linearized reading' (duration: 195.694795ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:22.03405Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555607884551425782,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:47:22.37298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.110691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:47:22.373111Z","caller":"traceutil/trace.go:171","msg":"trace[1497045266] linearizableReadLoop","detail":"{readStateIndex:463; appliedIndex:462; }","duration":"839.708311ms","start":"2026-08-16T12:47:21.533384Z","end":"2026-08-16T12:47:22.373093Z","steps":["trace[1497045266] 'read index received' (duration: 580.292615ms)","trace[1497045266] 'applied index is now lower than readState.Index' (duration: 259.413886ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:47:22.373195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.81032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:47:22.373219Z","caller":"traceutil/trace.go:171","msg":"trace[185036665] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:0; response_revision:443; }","duration":"839.836989ms","start":"2026-08-16T12:47:21.533375Z","end":"2026-08-16T12:47:22.373212Z","steps":["trace[185036665] 'agreement among raft nodes before linearized reading' (duration: 839.79187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:22.373264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:21.533311Z","time spent":"839.927088ms","remote":"127.0.0.1:49604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/kube-system/cilium-config\" "} {"level":"warn","ts":"2026-08-16T12:47:22.37323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.910146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-16T12:47:22.373294Z","caller":"traceutil/trace.go:171","msg":"trace[928986644] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:443; }","duration":"834.986854ms","start":"2026-08-16T12:47:21.538298Z","end":"2026-08-16T12:47:22.373285Z","steps":["trace[928986644] 'agreement among raft nodes before linearized reading' (duration: 834.897125ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:22.373323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:21.538291Z","time spent":"835.024943ms","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-16T12:47:22.373128Z","caller":"traceutil/trace.go:171","msg":"trace[1187793759] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"841.339634ms","start":"2026-08-16T12:47:21.531771Z","end":"2026-08-16T12:47:22.37311Z","steps":["trace[1187793759] 'process raft request' (duration: 581.999197ms)","trace[1187793759] 'compare' (duration: 258.984623ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:47:22.373623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:21.531693Z","time spent":"841.901624ms","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:47:23.280696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.635782ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:47:23.281536Z","caller":"traceutil/trace.go:171","msg":"trace[1312902147] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"313.529975ms","start":"2026-08-16T12:47:22.967948Z","end":"2026-08-16T12:47:23.281478Z","steps":["trace[1312902147] 'process raft request' (duration: 90.907067ms)","trace[1312902147] 'compare' (duration: 221.502784ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:47:23.281662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:22.96793Z","time spent":"313.674803ms","remote":"127.0.0.1:49978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4339,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:47:23.281671Z","caller":"traceutil/trace.go:171","msg":"trace[914355974] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"311.34276ms","start":"2026-08-16T12:47:22.970302Z","end":"2026-08-16T12:47:23.281644Z","steps":["trace[914355974] 'process raft request' (duration: 311.059334ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:47:23.28192Z","caller":"traceutil/trace.go:171","msg":"trace[2045711074] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"287.63979ms","start":"2026-08-16T12:47:22.994264Z","end":"2026-08-16T12:47:23.281903Z","steps":["trace[2045711074] 'process raft request' (duration: 287.509262ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:47:23.281968Z","caller":"traceutil/trace.go:171","msg":"trace[1111198099] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:492; }","duration":"273.394399ms","start":"2026-08-16T12:47:23.008539Z","end":"2026-08-16T12:47:23.281933Z","steps":["trace[1111198099] 'read index received' (duration: 50.376827ms)","trace[1111198099] 'applied index is now lower than readState.Index' (duration: 223.016622ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:47:23.282084Z","caller":"traceutil/trace.go:171","msg":"trace[1063841639] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"277.228742ms","start":"2026-08-16T12:47:23.004825Z","end":"2026-08-16T12:47:23.282054Z","steps":["trace[1063841639] 'process raft request' (duration: 277.021155ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:23.282366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.818992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329948"} {"level":"info","ts":"2026-08-16T12:47:23.282429Z","caller":"traceutil/trace.go:171","msg":"trace[1965419984] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:476; }","duration":"273.886911ms","start":"2026-08-16T12:47:23.008525Z","end":"2026-08-16T12:47:23.282412Z","steps":["trace[1965419984] 'agreement among raft nodes before linearized reading' (duration: 273.532307ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:23.28264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:22.970294Z","time spent":"311.43906ms","remote":"127.0.0.1:49946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4617,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:47:25.665793Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.039608453s","expected-duration":"1s"} {"level":"info","ts":"2026-08-16T12:47:25.666215Z","caller":"traceutil/trace.go:171","msg":"trace[1091992507] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"1.040102267s","start":"2026-08-16T12:47:24.62609Z","end":"2026-08-16T12:47:25.666192Z","steps":["trace[1091992507] 'process raft request' (duration: 1.039989979s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:25.666341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:24.626068Z","time spent":"1.040207626s","remote":"127.0.0.1:49548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-16T12:47:26.225884Z","caller":"traceutil/trace.go:171","msg":"trace[2090428351] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"1.592188344s","start":"2026-08-16T12:47:24.633663Z","end":"2026-08-16T12:47:26.225851Z","steps":["trace[2090428351] 'process raft request' (duration: 1.591964197s)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:47:26.225924Z","caller":"traceutil/trace.go:171","msg":"trace[1121380204] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"1.556274359s","start":"2026-08-16T12:47:24.669631Z","end":"2026-08-16T12:47:26.225905Z","steps":["trace[1121380204] 'process raft request' (duration: 1.556233849s)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:47:26.225973Z","caller":"traceutil/trace.go:171","msg":"trace[115518475] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"1.592243104s","start":"2026-08-16T12:47:24.633704Z","end":"2026-08-16T12:47:26.225948Z","steps":["trace[115518475] 'process raft request' (duration: 1.592019357s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:26.226024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:24.633651Z","time spent":"1.592309743s","remote":"127.0.0.1:49548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-16T12:47:26.22602Z","caller":"traceutil/trace.go:171","msg":"trace[916416363] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"1.567896262s","start":"2026-08-16T12:47:24.658094Z","end":"2026-08-16T12:47:26.22599Z","steps":["trace[916416363] 'process raft request' (duration: 1.567730554s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:26.226059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:24.633701Z","time spent":"1.592307433s","remote":"127.0.0.1:49548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-16T12:47:26.226054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:24.669612Z","time spent":"1.556393298s","remote":"127.0.0.1:49548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-16T12:47:26.226193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:24.658077Z","time spent":"1.56803673s","remote":"127.0.0.1:49548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-16T12:47:26.227179Z","caller":"traceutil/trace.go:171","msg":"trace[707234955] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.544579129s","start":"2026-08-16T12:47:24.682585Z","end":"2026-08-16T12:47:26.227164Z","steps":["trace[707234955] 'process raft request' (duration: 1.543961716s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:26.227249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:24.682571Z","time spent":"1.544648219s","remote":"127.0.0.1:49548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-16T12:47:26.227346Z","caller":"traceutil/trace.go:171","msg":"trace[1634454265] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"1.537800221s","start":"2026-08-16T12:47:24.689533Z","end":"2026-08-16T12:47:26.227334Z","steps":["trace[1634454265] 'process raft request' (duration: 1.53706089s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:26.227401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:24.689522Z","time spent":"1.537855821s","remote":"127.0.0.1:49548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-16T12:47:26.22754Z","caller":"traceutil/trace.go:171","msg":"trace[249299300] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"1.535969406s","start":"2026-08-16T12:47:24.691561Z","end":"2026-08-16T12:47:26.227531Z","steps":["trace[249299300] 'process raft request' (duration: 1.535080826s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:26.227591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:24.691537Z","time spent":"1.536033136s","remote":"127.0.0.1:49548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-16T12:47:26.227703Z","caller":"traceutil/trace.go:171","msg":"trace[957468954] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"1.499038505s","start":"2026-08-16T12:47:24.728655Z","end":"2026-08-16T12:47:26.227694Z","steps":["trace[957468954] 'process raft request' (duration: 1.498042177s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:26.22775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:24.728632Z","time spent":"1.499098005s","remote":"127.0.0.1:49548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-16T12:47:26.228038Z","caller":"traceutil/trace.go:171","msg":"trace[198211244] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"1.550267794s","start":"2026-08-16T12:47:24.677761Z","end":"2026-08-16T12:47:26.228029Z","steps":["trace[198211244] 'process raft request' (duration: 1.548585394s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:26.228095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:24.677739Z","time spent":"1.550332203s","remote":"127.0.0.1:49548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-16T12:47:26.228161Z","caller":"traceutil/trace.go:171","msg":"trace[1979479333] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"1.550236165s","start":"2026-08-16T12:47:24.677873Z","end":"2026-08-16T12:47:26.228109Z","steps":["trace[1979479333] 'process raft request' (duration: 1.548612363s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:26.228332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:24.677869Z","time spent":"1.550395394s","remote":"127.0.0.1:49548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-16T12:47:26.245774Z","caller":"traceutil/trace.go:171","msg":"trace[209317091] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"1.382508213s","start":"2026-08-16T12:47:24.863247Z","end":"2026-08-16T12:47:26.245755Z","steps":["trace[209317091] 'process raft request' (duration: 1.378578986s)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:47:26.245817Z","caller":"traceutil/trace.go:171","msg":"trace[1590246452] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:505; }","duration":"870.564844ms","start":"2026-08-16T12:47:25.375205Z","end":"2026-08-16T12:47:26.24577Z","steps":["trace[1590246452] 'read index received' (duration: 291.00534ms)","trace[1590246452] 'applied index is now lower than readState.Index' (duration: 579.556414ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:47:26.24585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:24.863215Z","time spent":"1.382602991s","remote":"127.0.0.1:49548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-16T12:47:26.245839Z","caller":"traceutil/trace.go:171","msg":"trace[1402729245] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"571.661691ms","start":"2026-08-16T12:47:25.674161Z","end":"2026-08-16T12:47:26.245823Z","steps":["trace[1402729245] 'process raft request' (duration: 571.596302ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:26.245942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.722672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:47:26.245969Z","caller":"traceutil/trace.go:171","msg":"trace[1457079460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:498; }","duration":"870.795031ms","start":"2026-08-16T12:47:25.375166Z","end":"2026-08-16T12:47:26.245961Z","steps":["trace[1457079460] 'agreement among raft nodes before linearized reading' (duration: 870.710222ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:26.245994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:25.375148Z","time spent":"870.84158ms","remote":"127.0.0.1:49516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-16T12:47:26.246033Z","caller":"traceutil/trace.go:171","msg":"trace[613069696] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"574.288869ms","start":"2026-08-16T12:47:25.671728Z","end":"2026-08-16T12:47:26.246016Z","steps":["trace[613069696] 'process raft request' (duration: 573.966043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:26.246076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:25.671704Z","time spent":"574.357309ms","remote":"127.0.0.1:50026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-16T12:47:26.24615Z","caller":"traceutil/trace.go:171","msg":"trace[1558306478] transaction","detail":"{read_only:false; number_of_response:1; response_revision:498; }","duration":"571.201337ms","start":"2026-08-16T12:47:25.674923Z","end":"2026-08-16T12:47:26.246124Z","steps":["trace[1558306478] 'process raft request' (duration: 570.869251ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:26.246215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:25.6749Z","time spent":"571.274346ms","remote":"127.0.0.1:49548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:47:26.246333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:25.674143Z","time spent":"572.103445ms","remote":"127.0.0.1:49548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:47:26.259086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.397536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T12:47:26.259141Z","caller":"traceutil/trace.go:171","msg":"trace[1630210294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:506; }","duration":"679.490675ms","start":"2026-08-16T12:47:25.579639Z","end":"2026-08-16T12:47:26.25913Z","steps":["trace[1630210294] 'agreement among raft nodes before linearized reading' (duration: 679.407845ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:26.259164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:25.579621Z","time spent":"679.538174ms","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-16T12:47:32.811568Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555607884551426069,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-16T12:47:33.070061Z","caller":"traceutil/trace.go:171","msg":"trace[334054769] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"758.627066ms","start":"2026-08-16T12:47:32.311379Z","end":"2026-08-16T12:47:33.070006Z","steps":["trace[334054769] 'read index received' (duration: 752.654355ms)","trace[334054769] 'applied index is now lower than readState.Index' (duration: 5.971861ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:47:33.070287Z","caller":"traceutil/trace.go:171","msg":"trace[110021763] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"996.989136ms","start":"2026-08-16T12:47:32.073228Z","end":"2026-08-16T12:47:33.070217Z","steps":["trace[110021763] 'process raft request' (duration: 995.326181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:33.070506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:32.073205Z","time spent":"997.219995ms","remote":"127.0.0.1:49590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:47:33.070498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.082295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T12:47:33.070616Z","caller":"traceutil/trace.go:171","msg":"trace[1882294794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"759.316164ms","start":"2026-08-16T12:47:32.311277Z","end":"2026-08-16T12:47:33.070593Z","steps":["trace[1882294794] 'agreement among raft nodes before linearized reading' (duration: 758.883316ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:33.070716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:32.31126Z","time spent":"759.420184ms","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-16T12:47:33.098411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.308613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:47:33.098499Z","caller":"traceutil/trace.go:171","msg":"trace[816694548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"721.437162ms","start":"2026-08-16T12:47:32.377046Z","end":"2026-08-16T12:47:33.098483Z","steps":["trace[816694548] 'agreement among raft nodes before linearized reading' (duration: 721.301473ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:33.098527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:32.377029Z","time spent":"721.491662ms","remote":"127.0.0.1:49516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-16T12:47:33.098747Z","caller":"traceutil/trace.go:171","msg":"trace[1670478341] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"191.088388ms","start":"2026-08-16T12:47:32.907641Z","end":"2026-08-16T12:47:33.098729Z","steps":["trace[1670478341] 'process raft request' (duration: 190.55913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:33.098876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.635409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-x84cb\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-16T12:47:33.098895Z","caller":"traceutil/trace.go:171","msg":"trace[63583097] range","detail":"{range_begin:/registry/pods/kube-system/cilium-x84cb; range_end:; response_count:1; response_revision:592; }","duration":"462.697969ms","start":"2026-08-16T12:47:32.636192Z","end":"2026-08-16T12:47:33.09889Z","steps":["trace[63583097] 'agreement among raft nodes before linearized reading' (duration: 462.660049ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:33.098908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:32.636174Z","time spent":"462.730709ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-x84cb\" "} {"level":"warn","ts":"2026-08-16T12:47:33.09903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.345259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-16T12:47:33.099044Z","caller":"traceutil/trace.go:171","msg":"trace[2070551596] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:592; }","duration":"617.388649ms","start":"2026-08-16T12:47:32.481651Z","end":"2026-08-16T12:47:33.09904Z","steps":["trace[2070551596] 'agreement among raft nodes before linearized reading' (duration: 617.32582ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:33.099056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:32.481633Z","time spent":"617.420459ms","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-16T12:47:33.099145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.109202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-16T12:47:33.099161Z","caller":"traceutil/trace.go:171","msg":"trace[748378650] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"643.149952ms","start":"2026-08-16T12:47:32.456008Z","end":"2026-08-16T12:47:33.099158Z","steps":["trace[748378650] 'agreement among raft nodes before linearized reading' (duration: 643.103852ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:33.099174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:32.455991Z","time spent":"643.179921ms","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-16T12:47:33.099247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.966305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-16T12:47:33.099263Z","caller":"traceutil/trace.go:171","msg":"trace[432960195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:592; }","duration":"664.024305ms","start":"2026-08-16T12:47:32.435234Z","end":"2026-08-16T12:47:33.099259Z","steps":["trace[432960195] 'agreement among raft nodes before linearized reading' (duration: 663.983635ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:33.099275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:32.435223Z","time spent":"664.048595ms","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-16T12:47:33.860905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.539998ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:47:33.861Z","caller":"traceutil/trace.go:171","msg":"trace[1262502517] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"760.446901ms","start":"2026-08-16T12:47:33.100536Z","end":"2026-08-16T12:47:33.860983Z","steps":["trace[1262502517] 'read index received' (duration: 59.783234ms)","trace[1262502517] 'applied index is now lower than readState.Index' (duration: 700.662147ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:47:33.861059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.563011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:47:33.861089Z","caller":"traceutil/trace.go:171","msg":"trace[37439332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"760.602091ms","start":"2026-08-16T12:47:33.100479Z","end":"2026-08-16T12:47:33.861081Z","steps":["trace[37439332] 'agreement among raft nodes before linearized reading' (duration: 760.546971ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:33.861124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:33.100468Z","time spent":"760.649321ms","remote":"127.0.0.1:49500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-16T12:47:33.861198Z","caller":"traceutil/trace.go:171","msg":"trace[315435622] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"786.41748ms","start":"2026-08-16T12:47:33.074759Z","end":"2026-08-16T12:47:33.861176Z","steps":["trace[315435622] 'process raft request' (duration: 85.508083ms)","trace[315435622] 'compare' (duration: 700.333028ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:47:33.861321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:33.074741Z","time spent":"786.536329ms","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:47:33.890245Z","caller":"traceutil/trace.go:171","msg":"trace[1777092568] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"787.150969ms","start":"2026-08-16T12:47:33.103075Z","end":"2026-08-16T12:47:33.890226Z","steps":["trace[1777092568] 'process raft request' (duration: 787.070509ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:47:33.890264Z","caller":"traceutil/trace.go:171","msg":"trace[1598180646] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"787.237378ms","start":"2026-08-16T12:47:33.103003Z","end":"2026-08-16T12:47:33.89024Z","steps":["trace[1598180646] 'process raft request' (duration: 787.023048ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:33.890379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:33.103054Z","time spent":"787.249918ms","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:47:33.890411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:33.102989Z","time spent":"787.330957ms","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:47:33.890627Z","caller":"traceutil/trace.go:171","msg":"trace[292125392] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"781.392176ms","start":"2026-08-16T12:47:33.10922Z","end":"2026-08-16T12:47:33.890612Z","steps":["trace[292125392] 'process raft request' (duration: 781.200627ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:47:33.890632Z","caller":"traceutil/trace.go:171","msg":"trace[200253325] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"786.728569ms","start":"2026-08-16T12:47:33.103858Z","end":"2026-08-16T12:47:33.890586Z","steps":["trace[200253325] 'process raft request' (duration: 786.316251ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:33.890729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:33.109209Z","time spent":"781.470126ms","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:47:33.890749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:33.103849Z","time spent":"786.844109ms","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:47:33.890858Z","caller":"traceutil/trace.go:171","msg":"trace[81951458] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"765.963664ms","start":"2026-08-16T12:47:33.12488Z","end":"2026-08-16T12:47:33.890843Z","steps":["trace[81951458] 'process raft request' (duration: 765.653506ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:33.890937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:33.124868Z","time spent":"766.034044ms","remote":"127.0.0.1:50014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-16T12:47:36.418844Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555607884551426083,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:47:36.419511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.043049926s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:47:36.419584Z","caller":"traceutil/trace.go:171","msg":"trace[1756153792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"2.043158666s","start":"2026-08-16T12:47:34.376396Z","end":"2026-08-16T12:47:36.419555Z","steps":["trace[1756153792] 'range keys from in-memory index tree' (duration: 2.042968897s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:36.419648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:34.376377Z","time spent":"2.043246695s","remote":"127.0.0.1:49500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-16T12:47:36.419849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.001438ms","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-08-16T12:47:36.419946Z","caller":"traceutil/trace.go:171","msg":"trace[1916311269] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:598; }","duration":"701.110368ms","start":"2026-08-16T12:47:35.71882Z","end":"2026-08-16T12:47:36.419931Z","steps":["trace[1916311269] 'range keys from in-memory index tree' (duration: 700.977918ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:36.919775Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555607884551426083,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:47:37.420518Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555607884551426083,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:47:37.92062Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555607884551426083,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:47:38.382021Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.951748515s","expected-duration":"1s"} {"level":"info","ts":"2026-08-16T12:47:38.383242Z","caller":"traceutil/trace.go:171","msg":"trace[898699365] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"3.953771146s","start":"2026-08-16T12:47:34.429448Z","end":"2026-08-16T12:47:38.383219Z","steps":["trace[898699365] 'process raft request' (duration: 3.953134994s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:38.383381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:34.429424Z","time spent":"3.953884706s","remote":"127.0.0.1:49590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:47:38.424054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000373715s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-16T12:47:38.424119Z","caller":"traceutil/trace.go:171","msg":"trace[912255890] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000470825s","start":"2026-08-16T12:47:36.423632Z","end":"2026-08-16T12:47:38.424103Z","steps":["trace[912255890] 'agreement among raft nodes before linearized reading' (duration: 2.000392925s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:38.424153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:36.423621Z","time spent":"2.000525775s","remote":"127.0.0.1:49516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-16T12:47:38.648227Z","caller":"traceutil/trace.go:171","msg":"trace[1783572549] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:626; }","duration":"2.78117413s","start":"2026-08-16T12:47:35.867022Z","end":"2026-08-16T12:47:38.648196Z","steps":["trace[1783572549] 'read index received' (duration: 2.515291866s)","trace[1783572549] 'applied index is now lower than readState.Index' (duration: 265.880724ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:47:38.648253Z","caller":"traceutil/trace.go:171","msg":"trace[254809070] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"2.225880403s","start":"2026-08-16T12:47:36.422326Z","end":"2026-08-16T12:47:38.648207Z","steps":["trace[254809070] 'process raft request' (duration: 2.225674273s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:38.648517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.781470231s","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-08-16T12:47:38.648516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:36.422309Z","time spent":"2.226128164s","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-16T12:47:38.64867Z","caller":"traceutil/trace.go:171","msg":"trace[992112335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:600; }","duration":"2.781677691s","start":"2026-08-16T12:47:35.866973Z","end":"2026-08-16T12:47:38.64865Z","steps":["trace[992112335] 'agreement among raft nodes before linearized reading' (duration: 2.781367811s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:38.648741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:35.86695Z","time spent":"2.78177537s","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-16T12:47:38.652815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.757831738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-16T12:47:38.652846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.330498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:47:38.65273Z","caller":"traceutil/trace.go:171","msg":"trace[2084338809] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"213.759209ms","start":"2026-08-16T12:47:38.438948Z","end":"2026-08-16T12:47:38.652708Z","steps":["trace[2084338809] 'process raft request' (duration: 213.545719ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:47:38.652874Z","caller":"traceutil/trace.go:171","msg":"trace[2127402645] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:602; }","duration":"2.757945828s","start":"2026-08-16T12:47:35.894914Z","end":"2026-08-16T12:47:38.65286Z","steps":["trace[2127402645] 'agreement among raft nodes before linearized reading' (duration: 2.757771578s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:38.652913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:35.894888Z","time spent":"2.758016738s","remote":"127.0.0.1:49788","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-08-16T12:47:38.652955Z","caller":"traceutil/trace.go:171","msg":"trace[996965836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"228.407568ms","start":"2026-08-16T12:47:38.424466Z","end":"2026-08-16T12:47:38.652874Z","steps":["trace[996965836] 'agreement among raft nodes before linearized reading' (duration: 228.346478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:38.653014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.756109949s","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-08-16T12:47:38.653071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.757372089s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-16T12:47:38.653096Z","caller":"traceutil/trace.go:171","msg":"trace[964826382] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:602; }","duration":"2.757439759s","start":"2026-08-16T12:47:35.895648Z","end":"2026-08-16T12:47:38.653088Z","steps":["trace[964826382] 'agreement among raft nodes before linearized reading' (duration: 2.757361758s)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:47:38.653102Z","caller":"traceutil/trace.go:171","msg":"trace[797845752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:602; }","duration":"2.75623012s","start":"2026-08-16T12:47:35.896855Z","end":"2026-08-16T12:47:38.653085Z","steps":["trace[797845752] 'agreement among raft nodes before linearized reading' (duration: 2.756004929s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:38.653121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:35.895627Z","time spent":"2.757487779s","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-16T12:47:38.653153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:35.896845Z","time spent":"2.75629439s","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-16T12:47:38.653222Z","caller":"traceutil/trace.go:171","msg":"trace[734718881] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"213.789318ms","start":"2026-08-16T12:47:38.439302Z","end":"2026-08-16T12:47:38.653092Z","steps":["trace[734718881] 'process raft request' (duration: 213.347167ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:55.248405Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555607884551426409,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:47:55.748911Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555607884551426409,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:47:55.943376Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.259518963s","expected-duration":"1s"} {"level":"info","ts":"2026-08-16T12:47:55.943879Z","caller":"traceutil/trace.go:171","msg":"trace[1958159046] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"1.260086152s","start":"2026-08-16T12:47:54.683767Z","end":"2026-08-16T12:47:55.943853Z","steps":["trace[1958159046] 'process raft request' (duration: 1.259900159s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:55.944131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:54.683751Z","time spent":"1.260256106s","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:47:55.945341Z","caller":"traceutil/trace.go:171","msg":"trace[802760926] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"1.17532987s","start":"2026-08-16T12:47:54.769993Z","end":"2026-08-16T12:47:55.945323Z","steps":["trace[802760926] 'process raft request' (duration: 1.175056686s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:55.945469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:54.769967Z","time spent":"1.175433593s","remote":"127.0.0.1:49770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:47:55.949594Z","caller":"traceutil/trace.go:171","msg":"trace[1064859242] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:798; }","duration":"1.201828819s","start":"2026-08-16T12:47:54.747739Z","end":"2026-08-16T12:47:55.949568Z","steps":["trace[1064859242] 'read index received' (duration: 1.19595437s)","trace[1064859242] 'applied index is now lower than readState.Index' (duration: 5.872829ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:47:55.949988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.202209756s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-5t7hg\" ","response":"range_response_count:1 size:5050"} {"level":"warn","ts":"2026-08-16T12:47:55.950083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124939863s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T12:47:55.950123Z","caller":"traceutil/trace.go:171","msg":"trace[633528999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:766; }","duration":"1.125012573s","start":"2026-08-16T12:47:54.825101Z","end":"2026-08-16T12:47:55.950113Z","steps":["trace[633528999] 'agreement among raft nodes before linearized reading' (duration: 1.124652287s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:55.950147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:54.825082Z","time spent":"1.125061275s","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-16T12:47:55.950186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124973933s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-16T12:47:55.950248Z","caller":"traceutil/trace.go:171","msg":"trace[1250127023] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:766; }","duration":"1.125048035s","start":"2026-08-16T12:47:54.825185Z","end":"2026-08-16T12:47:55.950233Z","steps":["trace[1250127023] 'agreement among raft nodes before linearized reading' (duration: 1.124557316s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:55.950304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:54.825178Z","time spent":"1.125112156s","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-16T12:47:55.950403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127389773s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-16T12:47:55.95043Z","caller":"traceutil/trace.go:171","msg":"trace[402377730] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:766; }","duration":"1.127435123s","start":"2026-08-16T12:47:54.822988Z","end":"2026-08-16T12:47:55.950423Z","steps":["trace[402377730] 'agreement among raft nodes before linearized reading' (duration: 1.126743141s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:55.950454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:54.82298Z","time spent":"1.127469615s","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-16T12:47:55.950535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125209878s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-16T12:47:55.950555Z","caller":"traceutil/trace.go:171","msg":"trace[1624203064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:766; }","duration":"1.125244609s","start":"2026-08-16T12:47:54.825305Z","end":"2026-08-16T12:47:55.95055Z","steps":["trace[1624203064] 'agreement among raft nodes before linearized reading' (duration: 1.124461155s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:55.950572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:54.825298Z","time spent":"1.125269939s","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-16T12:47:55.950216Z","caller":"traceutil/trace.go:171","msg":"trace[1707653073] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-5t7hg; range_end:; response_count:1; response_revision:766; }","duration":"1.202352558s","start":"2026-08-16T12:47:54.747688Z","end":"2026-08-16T12:47:55.95004Z","steps":["trace[1707653073] 'agreement among raft nodes before linearized reading' (duration: 1.201959792s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:55.950997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:54.747668Z","time spent":"1.203307985s","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5073,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-5t7hg\" "} {"level":"info","ts":"2026-08-16T12:48:08.249686Z","caller":"traceutil/trace.go:171","msg":"trace[1583471245] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"122.293503ms","start":"2026-08-16T12:48:08.127369Z","end":"2026-08-16T12:48:08.249663Z","steps":["trace[1583471245] 'read index received' (duration: 122.261112ms)","trace[1583471245] 'applied index is now lower than readState.Index' (duration: 31.131µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:48:08.249747Z","caller":"traceutil/trace.go:171","msg":"trace[2045902782] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"122.659903ms","start":"2026-08-16T12:48:08.127062Z","end":"2026-08-16T12:48:08.249722Z","steps":["trace[2045902782] 'process raft request' (duration: 122.460377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:08.249948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.548279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-16T12:48:08.250035Z","caller":"traceutil/trace.go:171","msg":"trace[1250325347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:916; }","duration":"122.663103ms","start":"2026-08-16T12:48:08.127352Z","end":"2026-08-16T12:48:08.250015Z","steps":["trace[1250325347] 'agreement among raft nodes before linearized reading' (duration: 122.403026ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:48:08.258094Z","caller":"traceutil/trace.go:171","msg":"trace[1935134962] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"124.030877ms","start":"2026-08-16T12:48:08.134022Z","end":"2026-08-16T12:48:08.258053Z","steps":["trace[1935134962] 'process raft request' (duration: 123.934075ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:08.258227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.744495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-16T12:48:08.258238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.850733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-16T12:48:08.25827Z","caller":"traceutil/trace.go:171","msg":"trace[1641413177] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:917; }","duration":"129.807896ms","start":"2026-08-16T12:48:08.128451Z","end":"2026-08-16T12:48:08.258259Z","steps":["trace[1641413177] 'agreement among raft nodes before linearized reading' (duration: 129.608081ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:48:08.258272Z","caller":"traceutil/trace.go:171","msg":"trace[1713920255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:917; }","duration":"123.895443ms","start":"2026-08-16T12:48:08.134367Z","end":"2026-08-16T12:48:08.258263Z","steps":["trace[1713920255] 'agreement among raft nodes before linearized reading' (duration: 123.807001ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:08.258328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.768264ms","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-08-16T12:48:08.258398Z","caller":"traceutil/trace.go:171","msg":"trace[176085225] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:917; }","duration":"129.930429ms","start":"2026-08-16T12:48:08.12845Z","end":"2026-08-16T12:48:08.25838Z","steps":["trace[176085225] 'agreement among raft nodes before linearized reading' (duration: 129.732234ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:08.48518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.366637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:48:08.48534Z","caller":"traceutil/trace.go:171","msg":"trace[2045282718] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"232.205546ms","start":"2026-08-16T12:48:08.253109Z","end":"2026-08-16T12:48:08.485314Z","steps":["trace[2045282718] 'process raft request' (duration: 36.568702ms)","trace[2045282718] 'compare' (duration: 195.254334ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:48:08.545725Z","caller":"traceutil/trace.go:171","msg":"trace[190973290] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"282.335809ms","start":"2026-08-16T12:48:08.263361Z","end":"2026-08-16T12:48:08.545696Z","steps":["trace[190973290] 'process raft request' (duration: 282.203295ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:48:08.545742Z","caller":"traceutil/trace.go:171","msg":"trace[711398279] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"283.372385ms","start":"2026-08-16T12:48:08.26232Z","end":"2026-08-16T12:48:08.545692Z","steps":["trace[711398279] 'process raft request' (duration: 283.096218ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:48:08.545852Z","caller":"traceutil/trace.go:171","msg":"trace[1497369048] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"282.190385ms","start":"2026-08-16T12:48:08.263581Z","end":"2026-08-16T12:48:08.545771Z","steps":["trace[1497369048] 'process raft request' (duration: 282.02195ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:48:08.546182Z","caller":"traceutil/trace.go:171","msg":"trace[112820384] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:955; }","duration":"168.934335ms","start":"2026-08-16T12:48:08.377232Z","end":"2026-08-16T12:48:08.546166Z","steps":["trace[112820384] 'read index received' (duration: 168.165885ms)","trace[112820384] 'applied index is now lower than readState.Index' (duration: 766.98µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:48:08.546688Z","caller":"traceutil/trace.go:171","msg":"trace[1578188636] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"279.68586ms","start":"2026-08-16T12:48:08.266979Z","end":"2026-08-16T12:48:08.546665Z","steps":["trace[1578188636] 'process raft request' (duration: 278.655554ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:08.547006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.749205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:48:08.547076Z","caller":"traceutil/trace.go:171","msg":"trace[1866749607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"169.859218ms","start":"2026-08-16T12:48:08.377197Z","end":"2026-08-16T12:48:08.547056Z","steps":["trace[1866749607] 'agreement among raft nodes before linearized reading' (duration: 169.752025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:08.74623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.326532ms","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":"info","ts":"2026-08-16T12:48:08.746311Z","caller":"traceutil/trace.go:171","msg":"trace[255433257] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:922; }","duration":"108.481116ms","start":"2026-08-16T12:48:08.637811Z","end":"2026-08-16T12:48:08.746293Z","steps":["trace[255433257] 'count revisions from in-memory index tree' (duration: 108.214559ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:49:41.257041Z","caller":"traceutil/trace.go:171","msg":"trace[773712885] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"158.837694ms","start":"2026-08-16T12:49:41.098164Z","end":"2026-08-16T12:49:41.257002Z","steps":["trace[773712885] 'process raft request' (duration: 158.654534ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:49:41.257014Z","caller":"traceutil/trace.go:171","msg":"trace[1599664233] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1319; }","duration":"155.235291ms","start":"2026-08-16T12:49:41.101748Z","end":"2026-08-16T12:49:41.256983Z","steps":["trace[1599664233] 'read index received' (duration: 155.012058ms)","trace[1599664233] 'applied index is now lower than readState.Index' (duration: 221.573µs)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:49:41.257276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.491035ms","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-08-16T12:49:41.257729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.467304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b5e42ece-8648-4761-a5be-0805f0fbad33\" ","response":"range_response_count:1 size:3869"} {"level":"info","ts":"2026-08-16T12:49:41.257916Z","caller":"traceutil/trace.go:171","msg":"trace[1258795538] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b5e42ece-8648-4761-a5be-0805f0fbad33; range_end:; response_count:1; response_revision:1262; }","duration":"135.696826ms","start":"2026-08-16T12:49:41.122196Z","end":"2026-08-16T12:49:41.257893Z","steps":["trace[1258795538] 'agreement among raft nodes before linearized reading' (duration: 135.264442ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:49:41.257457Z","caller":"traceutil/trace.go:171","msg":"trace[2143197558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1262; }","duration":"155.716998ms","start":"2026-08-16T12:49:41.101711Z","end":"2026-08-16T12:49:41.257428Z","steps":["trace[2143197558] 'agreement among raft nodes before linearized reading' (duration: 155.387099ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:49:41.565855Z","caller":"traceutil/trace.go:171","msg":"trace[172562156] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"142.567912ms","start":"2026-08-16T12:49:41.423258Z","end":"2026-08-16T12:49:41.565825Z","steps":["trace[172562156] 'process raft request' (duration: 114.889252ms)","trace[172562156] 'compare' (duration: 27.512121ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:49:41.758518Z","caller":"traceutil/trace.go:171","msg":"trace[1831561644] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1325; }","duration":"188.554276ms","start":"2026-08-16T12:49:41.569941Z","end":"2026-08-16T12:49:41.758495Z","steps":["trace[1831561644] 'read index received' (duration: 135.423901ms)","trace[1831561644] 'applied index is now lower than readState.Index' (duration: 53.129485ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:49:41.758533Z","caller":"traceutil/trace.go:171","msg":"trace[48741596] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"188.690724ms","start":"2026-08-16T12:49:41.569813Z","end":"2026-08-16T12:49:41.758503Z","steps":["trace[48741596] 'process raft request' (duration: 135.538528ms)","trace[48741596] 'compare' (duration: 52.968415ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:49:41.758874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.915286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b5e42ece-8648-4761-a5be-0805f0fbad33\" ","response":"range_response_count:1 size:3884"} {"level":"info","ts":"2026-08-16T12:49:41.758921Z","caller":"traceutil/trace.go:171","msg":"trace[1304742249] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b5e42ece-8648-4761-a5be-0805f0fbad33; range_end:; response_count:1; response_revision:1269; }","duration":"189.002981ms","start":"2026-08-16T12:49:41.569906Z","end":"2026-08-16T12:49:41.758909Z","steps":["trace[1304742249] 'agreement among raft nodes before linearized reading' (duration: 188.695314ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:49:41.958108Z","caller":"traceutil/trace.go:171","msg":"trace[485986785] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"115.067122ms","start":"2026-08-16T12:49:41.843001Z","end":"2026-08-16T12:49:41.958068Z","steps":["trace[485986785] 'process raft request' (duration: 114.894962ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:49:42.028965Z","caller":"traceutil/trace.go:171","msg":"trace[1629893429] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"119.283601ms","start":"2026-08-16T12:49:41.908944Z","end":"2026-08-16T12:49:42.028228Z","steps":["trace[1629893429] 'process raft request' (duration: 94.941076ms)","trace[1629893429] 'compare' (duration: 23.959014ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:49:42.229138Z","caller":"traceutil/trace.go:171","msg":"trace[635283049] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"194.756111ms","start":"2026-08-16T12:49:42.034344Z","end":"2026-08-16T12:49:42.2291Z","steps":["trace[635283049] 'process raft request' (duration: 132.36982ms)","trace[635283049] 'compare' (duration: 62.097725ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:49:43.066649Z","caller":"traceutil/trace.go:171","msg":"trace[960509881] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"102.375476ms","start":"2026-08-16T12:49:42.964231Z","end":"2026-08-16T12:49:43.066607Z","steps":["trace[960509881] 'process raft request' (duration: 66.973224ms)","trace[960509881] 'compare' (duration: 35.202951ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:49:45.317075Z","caller":"traceutil/trace.go:171","msg":"trace[1593557966] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1346; }","duration":"165.987226ms","start":"2026-08-16T12:49:45.151052Z","end":"2026-08-16T12:49:45.317039Z","steps":["trace[1593557966] 'read index received' (duration: 87.140378ms)","trace[1593557966] 'applied index is now lower than readState.Index' (duration: 78.843587ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:49:45.31749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.403788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-16T12:49:45.317586Z","caller":"traceutil/trace.go:171","msg":"trace[230078870] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1289; }","duration":"166.540916ms","start":"2026-08-16T12:49:45.151035Z","end":"2026-08-16T12:49:45.317576Z","steps":["trace[230078870] 'agreement among raft nodes before linearized reading' (duration: 166.275041ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:49:45.483519Z","caller":"traceutil/trace.go:171","msg":"trace[663830328] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1348; }","duration":"160.735479ms","start":"2026-08-16T12:49:45.322758Z","end":"2026-08-16T12:49:45.483494Z","steps":["trace[663830328] 'read index received' (duration: 97.069159ms)","trace[663830328] 'applied index is now lower than readState.Index' (duration: 63.66499ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:49:45.483587Z","caller":"traceutil/trace.go:171","msg":"trace[1693034440] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"162.245924ms","start":"2026-08-16T12:49:45.321313Z","end":"2026-08-16T12:49:45.483559Z","steps":["trace[1693034440] 'process raft request' (duration: 98.494279ms)","trace[1693034440] 'compare' (duration: 63.562784ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:49:45.483843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.071565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:49:45.483957Z","caller":"traceutil/trace.go:171","msg":"trace[1151525533] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1290; }","duration":"107.235564ms","start":"2026-08-16T12:49:45.376704Z","end":"2026-08-16T12:49:45.48394Z","steps":["trace[1151525533] 'agreement among raft nodes before linearized reading' (duration: 107.071785ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:49:45.484664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.878503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T12:49:45.484965Z","caller":"traceutil/trace.go:171","msg":"trace[309567120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1290; }","duration":"162.215692ms","start":"2026-08-16T12:49:45.322726Z","end":"2026-08-16T12:49:45.484942Z","steps":["trace[309567120] 'agreement among raft nodes before linearized reading' (duration: 160.905899ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:49:52.516282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.811019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-16T12:49:52.516671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.545654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:49:52.516744Z","caller":"traceutil/trace.go:171","msg":"trace[711689667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1321; }","duration":"139.625249ms","start":"2026-08-16T12:49:52.377101Z","end":"2026-08-16T12:49:52.516726Z","steps":["trace[711689667] 'range keys from in-memory index tree' (duration: 139.505872ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:49:52.51644Z","caller":"traceutil/trace.go:171","msg":"trace[199031401] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1321; }","duration":"152.018001ms","start":"2026-08-16T12:49:52.364395Z","end":"2026-08-16T12:49:52.516413Z","steps":["trace[199031401] 'count revisions from in-memory index tree' (duration: 151.727884ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:49:54.035053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.604088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:49:54.035358Z","caller":"traceutil/trace.go:171","msg":"trace[1524666882] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"414.130638ms","start":"2026-08-16T12:49:53.621197Z","end":"2026-08-16T12:49:54.035328Z","steps":["trace[1524666882] 'process raft request' (duration: 33.160718ms)","trace[1524666882] 'compare' (duration: 380.425828ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:49:54.035519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:49:53.621179Z","time spent":"414.246894ms","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:49:54.035758Z","caller":"traceutil/trace.go:171","msg":"trace[228324125] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1382; }","duration":"413.598756ms","start":"2026-08-16T12:49:53.622125Z","end":"2026-08-16T12:49:54.035724Z","steps":["trace[228324125] 'read index received' (duration: 32.268876ms)","trace[228324125] 'applied index is now lower than readState.Index' (duration: 381.32646ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:49:54.035988Z","caller":"traceutil/trace.go:171","msg":"trace[497591266] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"219.013999ms","start":"2026-08-16T12:49:53.816958Z","end":"2026-08-16T12:49:54.035972Z","steps":["trace[497591266] 'process raft request' (duration: 218.184751ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:49:54.036166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.437129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-16T12:49:54.036228Z","caller":"traceutil/trace.go:171","msg":"trace[410758720] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1324; }","duration":"380.508393ms","start":"2026-08-16T12:49:53.655703Z","end":"2026-08-16T12:49:54.036212Z","steps":["trace[410758720] 'agreement among raft nodes before linearized reading' (duration: 380.29134ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:49:54.036222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.140587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-16T12:49:54.036173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.044943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T12:49:54.036326Z","caller":"traceutil/trace.go:171","msg":"trace[1561341589] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1324; }","duration":"407.275205ms","start":"2026-08-16T12:49:53.62903Z","end":"2026-08-16T12:49:54.036305Z","steps":["trace[1561341589] 'agreement among raft nodes before linearized reading' (duration: 406.938784ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:49:54.036359Z","caller":"traceutil/trace.go:171","msg":"trace[1985941856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1323; }","duration":"414.267696ms","start":"2026-08-16T12:49:53.622072Z","end":"2026-08-16T12:49:54.03634Z","steps":["trace[1985941856] 'agreement among raft nodes before linearized reading' (duration: 413.809858ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:49:54.036432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:49:53.629016Z","time spent":"407.397881ms","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-16T12:49:54.036269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:49:53.655646Z","time spent":"380.614769ms","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-16T12:49:54.036678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:49:53.622053Z","time spent":"414.435465ms","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-16T12:49:54.332866Z","caller":"traceutil/trace.go:171","msg":"trace[1788783661] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"292.572492ms","start":"2026-08-16T12:49:54.040271Z","end":"2026-08-16T12:49:54.332843Z","steps":["trace[1788783661] 'process raft request' (duration: 292.399512ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:49:54.339925Z","caller":"traceutil/trace.go:171","msg":"trace[1423122979] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"298.858786ms","start":"2026-08-16T12:49:54.041046Z","end":"2026-08-16T12:49:54.339904Z","steps":["trace[1423122979] 'process raft request' (duration: 298.835864ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:49:54.340164Z","caller":"traceutil/trace.go:171","msg":"trace[2004026637] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"299.790229ms","start":"2026-08-16T12:49:54.04034Z","end":"2026-08-16T12:49:54.34013Z","steps":["trace[2004026637] 'process raft request' (duration: 299.45741ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:50:16.290625Z","caller":"traceutil/trace.go:171","msg":"trace[215603399] linearizableReadLoop","detail":"{readStateIndex:1466; appliedIndex:1465; }","duration":"103.478041ms","start":"2026-08-16T12:50:16.187123Z","end":"2026-08-16T12:50:16.290601Z","steps":["trace[215603399] 'read index received' (duration: 40.711056ms)","trace[215603399] 'applied index is now lower than readState.Index' (duration: 62.765975ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:50:16.290728Z","caller":"traceutil/trace.go:171","msg":"trace[308412220] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"105.218895ms","start":"2026-08-16T12:50:16.185484Z","end":"2026-08-16T12:50:16.290703Z","steps":["trace[308412220] 'process raft request' (duration: 42.408078ms)","trace[308412220] 'compare' (duration: 62.579424ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:50:16.291224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.083669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/\" range_end:\"/registry/serviceaccounts/kube-system0\" limit:500 ","response":"range_response_count:37 size:8042"} {"level":"info","ts":"2026-08-16T12:50:16.291288Z","caller":"traceutil/trace.go:171","msg":"trace[930880737] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/; range_end:/registry/serviceaccounts/kube-system0; response_count:37; response_revision:1401; }","duration":"104.175544ms","start":"2026-08-16T12:50:16.187096Z","end":"2026-08-16T12:50:16.291272Z","steps":["trace[930880737] 'agreement among raft nodes before linearized reading' (duration: 103.651132ms)"],"step_count":1}