{"level":"warn","ts":"2026-05-09T12:49:54.233914Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T12:49:54.234024Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.244: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.244:2380","--initial-cluster=instance=https://199.204.45.244:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.244:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.244:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-09T12:49:54.234108Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T12:49:54.234126Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-05-09T12:49:54.234148Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-09T12:49:54.234823Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"]} {"level":"info","ts":"2026-05-09T12:49:54.234914Z","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.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.244:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-09T12:49:54.252912Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"17.773377ms"} {"level":"info","ts":"2026-05-09T12:49:54.323623Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1686e9b1ea717edf","cluster-id":"71c60fe001ca5b3c"} {"level":"info","ts":"2026-05-09T12:49:54.32373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T12:49:54.323764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 0"} {"level":"info","ts":"2026-05-09T12:49:54.323778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1686e9b1ea717edf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T12:49:54.323785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 1"} {"level":"info","ts":"2026-05-09T12:49:54.323816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"warn","ts":"2026-05-09T12:49:54.429847Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T12:49:54.434693Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T12:49:54.473151Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-09T12:49:54.510234Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1686e9b1ea717edf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T12:49:54.513723Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1686e9b1ea717edf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T12:49:54.51384Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T12:49:54.51397Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T12:49:54.514018Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T12:49:54.518471Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-09T12:49:54.518606Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-05-09T12:49:54.518667Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-05-09T12:49:54.519164Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T12:49:54.519071Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1686e9b1ea717edf","initial-advertise-peer-urls":["https://199.204.45.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T12:49:54.53435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"info","ts":"2026-05-09T12:49:54.534643Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","added-peer-id":"1686e9b1ea717edf","added-peer-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-05-09T12:49:55.024079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T12:49:55.024154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T12:49:55.02418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgPreVoteResp from 1686e9b1ea717edf at term 1"} {"level":"info","ts":"2026-05-09T12:49:55.024254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became candidate at term 2"} {"level":"info","ts":"2026-05-09T12:49:55.024266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgVoteResp from 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-05-09T12:49:55.024339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became leader at term 2"} {"level":"info","ts":"2026-05-09T12:49:55.024364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1686e9b1ea717edf elected leader 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-05-09T12:49:55.116002Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T12:49:55.117169Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1686e9b1ea717edf","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.244:2379]}","request-path":"/0/members/1686e9b1ea717edf/attributes","cluster-id":"71c60fe001ca5b3c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T12:49:55.117253Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T12:49:55.117418Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T12:49:55.119551Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T12:49:55.119664Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T12:49:55.119671Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T12:49:55.119701Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T12:49:55.119716Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T12:49:55.120367Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T12:49:55.124301Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.244:2379"} {"level":"warn","ts":"2026-05-09T12:49:59.385068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.378869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:49:59.385212Z","caller":"traceutil/trace.go:171","msg":"trace[125569900] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"154.246723ms","start":"2026-05-09T12:49:59.230943Z","end":"2026-05-09T12:49:59.38519Z","steps":["trace[125569900] 'process raft request' (duration: 20.237941ms)","trace[125569900] 'compare' (duration: 133.199145ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:50:00.174916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.72855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:50:00.175141Z","caller":"traceutil/trace.go:171","msg":"trace[409615252] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"781.916044ms","start":"2026-05-09T12:49:59.393202Z","end":"2026-05-09T12:50:00.175118Z","steps":["trace[409615252] 'process raft request' (duration: 288.825507ms)","trace[409615252] 'compare' (duration: 492.599637ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:50:00.175266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:49:59.393169Z","time spent":"782.054417ms","remote":"127.0.0.1:49016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11432,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T12:50:02.127986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.231855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-09T12:50:02.128076Z","caller":"traceutil/trace.go:171","msg":"trace[1653749505] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:315; }","duration":"194.387228ms","start":"2026-05-09T12:50:01.933669Z","end":"2026-05-09T12:50:02.128057Z","steps":["trace[1653749505] 'range keys from in-memory index tree' (duration: 194.132493ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:02.396752Z","caller":"traceutil/trace.go:171","msg":"trace[693717453] transaction","detail":"{read_only:false; number_of_response:0; response_revision:318; }","duration":"131.134729ms","start":"2026-05-09T12:50:02.265597Z","end":"2026-05-09T12:50:02.396731Z","steps":["trace[693717453] 'process raft request' (duration: 131.060318ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:02.544314Z","caller":"traceutil/trace.go:171","msg":"trace[2079124675] transaction","detail":"{read_only:false; number_of_response:0; response_revision:318; }","duration":"278.069098ms","start":"2026-05-09T12:50:02.26622Z","end":"2026-05-09T12:50:02.544289Z","steps":["trace[2079124675] 'process raft request' (duration: 277.992976ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:02.544351Z","caller":"traceutil/trace.go:171","msg":"trace[741096899] transaction","detail":"{read_only:false; number_of_response:0; response_revision:318; }","duration":"278.321014ms","start":"2026-05-09T12:50:02.265998Z","end":"2026-05-09T12:50:02.544319Z","steps":["trace[741096899] 'process raft request' (duration: 278.063117ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:02.544418Z","caller":"traceutil/trace.go:171","msg":"trace[1977301897] transaction","detail":"{read_only:false; number_of_response:0; response_revision:318; }","duration":"278.13524ms","start":"2026-05-09T12:50:02.266259Z","end":"2026-05-09T12:50:02.544394Z","steps":["trace[1977301897] 'process raft request' (duration: 277.988857ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:02.544527Z","caller":"traceutil/trace.go:171","msg":"trace[1899420827] transaction","detail":"{read_only:false; number_of_response:0; response_revision:318; }","duration":"278.354735ms","start":"2026-05-09T12:50:02.266162Z","end":"2026-05-09T12:50:02.544517Z","steps":["trace[1899420827] 'process raft request' (duration: 278.028207ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:02.544588Z","caller":"traceutil/trace.go:171","msg":"trace[1119202916] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"251.005425ms","start":"2026-05-09T12:50:02.293573Z","end":"2026-05-09T12:50:02.544579Z","steps":["trace[1119202916] 'process raft request' (duration: 250.789751ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:02.544591Z","caller":"traceutil/trace.go:171","msg":"trace[1390351938] linearizableReadLoop","detail":"{readStateIndex:333; appliedIndex:327; }","duration":"146.751425ms","start":"2026-05-09T12:50:02.397811Z","end":"2026-05-09T12:50:02.544563Z","steps":["trace[1390351938] 'read index received' (duration: 146.181252ms)","trace[1390351938] 'applied index is now lower than readState.Index' (duration: 569.253µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:50:02.544669Z","caller":"traceutil/trace.go:171","msg":"trace[1486400080] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"261.520754ms","start":"2026-05-09T12:50:02.28314Z","end":"2026-05-09T12:50:02.544661Z","steps":["trace[1486400080] 'process raft request' (duration: 261.124566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:02.544689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.871358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-05-09T12:50:02.544715Z","caller":"traceutil/trace.go:171","msg":"trace[980415289] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:320; }","duration":"146.98304ms","start":"2026-05-09T12:50:02.397724Z","end":"2026-05-09T12:50:02.544707Z","steps":["trace[980415289] 'agreement among raft nodes before linearized reading' (duration: 146.920028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:02.729064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.411205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-05-09T12:50:02.729183Z","caller":"traceutil/trace.go:171","msg":"trace[916793981] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:320; }","duration":"180.557789ms","start":"2026-05-09T12:50:02.548605Z","end":"2026-05-09T12:50:02.729163Z","steps":["trace[916793981] 'range keys from in-memory index tree' (duration: 178.959444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:02.72906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.912285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-09T12:50:02.729282Z","caller":"traceutil/trace.go:171","msg":"trace[2075101388] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:320; }","duration":"180.221632ms","start":"2026-05-09T12:50:02.549043Z","end":"2026-05-09T12:50:02.729265Z","steps":["trace[2075101388] 'range keys from in-memory index tree' (duration: 179.601348ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:02.751083Z","caller":"traceutil/trace.go:171","msg":"trace[1620836644] linearizableReadLoop","detail":"{readStateIndex:334; appliedIndex:333; }","duration":"201.532624ms","start":"2026-05-09T12:50:02.549527Z","end":"2026-05-09T12:50:02.75106Z","steps":["trace[1620836644] 'read index received' (duration: 201.381811ms)","trace[1620836644] 'applied index is now lower than readState.Index' (duration: 149.553µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:50:02.751118Z","caller":"traceutil/trace.go:171","msg":"trace[29741775] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"201.622857ms","start":"2026-05-09T12:50:02.549472Z","end":"2026-05-09T12:50:02.751095Z","steps":["trace[29741775] 'process raft request' (duration: 201.360932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:02.751384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.837542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6755"} {"level":"info","ts":"2026-05-09T12:50:02.751457Z","caller":"traceutil/trace.go:171","msg":"trace[539032536] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:321; }","duration":"201.952373ms","start":"2026-05-09T12:50:02.549477Z","end":"2026-05-09T12:50:02.751429Z","steps":["trace[539032536] 'agreement among raft nodes before linearized reading' (duration: 201.717718ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:02.761367Z","caller":"traceutil/trace.go:171","msg":"trace[1958966756] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"210.797137ms","start":"2026-05-09T12:50:02.550559Z","end":"2026-05-09T12:50:02.761356Z","steps":["trace[1958966756] 'process raft request' (duration: 210.724886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:02.761671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.102606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-05-09T12:50:02.761727Z","caller":"traceutil/trace.go:171","msg":"trace[2051999266] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:322; }","duration":"212.171757ms","start":"2026-05-09T12:50:02.549548Z","end":"2026-05-09T12:50:02.76172Z","steps":["trace[2051999266] 'agreement among raft nodes before linearized reading' (duration: 212.095495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:02.761874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.529123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"info","ts":"2026-05-09T12:50:02.761897Z","caller":"traceutil/trace.go:171","msg":"trace[1035808730] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:322; }","duration":"211.570163ms","start":"2026-05-09T12:50:02.550321Z","end":"2026-05-09T12:50:02.761892Z","steps":["trace[1035808730] 'agreement among raft nodes before linearized reading' (duration: 211.532122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:02.762078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.142075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-05-09T12:50:02.762149Z","caller":"traceutil/trace.go:171","msg":"trace[1743685062] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:322; }","duration":"212.244627ms","start":"2026-05-09T12:50:02.549889Z","end":"2026-05-09T12:50:02.762133Z","steps":["trace[1743685062] 'agreement among raft nodes before linearized reading' (duration: 212.102014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:03.134909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.776356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:50:03.135229Z","caller":"traceutil/trace.go:171","msg":"trace[39620946] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"367.18682ms","start":"2026-05-09T12:50:02.768018Z","end":"2026-05-09T12:50:03.135205Z","steps":["trace[39620946] 'process raft request' (duration: 79.907254ms)","trace[39620946] 'compare' (duration: 286.665604ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:50:03.135278Z","caller":"traceutil/trace.go:171","msg":"trace[1906349749] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"367.075498ms","start":"2026-05-09T12:50:02.768186Z","end":"2026-05-09T12:50:03.135261Z","steps":["trace[1906349749] 'process raft request' (duration: 366.814152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:03.135354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:02.768178Z","time spent":"367.14004ms","remote":"127.0.0.1:49148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T12:50:03.135428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:02.768007Z","time spent":"367.272672ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:50:03.276139Z","caller":"traceutil/trace.go:171","msg":"trace[239228681] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"104.263672ms","start":"2026-05-09T12:50:03.171848Z","end":"2026-05-09T12:50:03.276111Z","steps":["trace[239228681] 'process raft request' (duration: 66.133284ms)","trace[239228681] 'compare' (duration: 37.980635ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:50:03.412993Z","caller":"traceutil/trace.go:171","msg":"trace[2040799238] linearizableReadLoop","detail":"{readStateIndex:340; appliedIndex:339; }","duration":"133.358423ms","start":"2026-05-09T12:50:03.279614Z","end":"2026-05-09T12:50:03.412972Z","steps":["trace[2040799238] 'read index received' (duration: 127.166324ms)","trace[2040799238] 'applied index is now lower than readState.Index' (duration: 6.191079ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:50:03.413088Z","caller":"traceutil/trace.go:171","msg":"trace[265370673] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"235.288917ms","start":"2026-05-09T12:50:03.177777Z","end":"2026-05-09T12:50:03.413066Z","steps":["trace[265370673] 'process raft request' (duration: 229.080148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:03.413124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.488405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:50:03.413211Z","caller":"traceutil/trace.go:171","msg":"trace[1226974074] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:0; response_revision:327; }","duration":"133.628348ms","start":"2026-05-09T12:50:03.27957Z","end":"2026-05-09T12:50:03.413198Z","steps":["trace[1226974074] 'agreement among raft nodes before linearized reading' (duration: 133.489335ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:11.446538Z","caller":"traceutil/trace.go:171","msg":"trace[1545769411] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"194.36839ms","start":"2026-05-09T12:50:11.252125Z","end":"2026-05-09T12:50:11.446494Z","steps":["trace[1545769411] 'process raft request' (duration: 193.196338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:11.73783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.706314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4425"} {"level":"info","ts":"2026-05-09T12:50:11.737995Z","caller":"traceutil/trace.go:171","msg":"trace[1252268999] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:420; }","duration":"153.928958ms","start":"2026-05-09T12:50:11.584044Z","end":"2026-05-09T12:50:11.737973Z","steps":["trace[1252268999] 'range keys from in-memory index tree' (duration: 153.53508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:12.518077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.305354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4420"} {"level":"info","ts":"2026-05-09T12:50:12.518182Z","caller":"traceutil/trace.go:171","msg":"trace[1503140262] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:422; }","duration":"103.467906ms","start":"2026-05-09T12:50:12.41469Z","end":"2026-05-09T12:50:12.518157Z","steps":["trace[1503140262] 'range keys from in-memory index tree' (duration: 103.12516ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:13.545503Z","caller":"traceutil/trace.go:171","msg":"trace[580563813] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"101.873831ms","start":"2026-05-09T12:50:13.443586Z","end":"2026-05-09T12:50:13.54546Z","steps":["trace[580563813] 'process raft request' (duration: 82.218627ms)","trace[580563813] 'compare' (duration: 19.374448ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:50:13.807226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.143178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:50:13.807492Z","caller":"traceutil/trace.go:171","msg":"trace[414628405] linearizableReadLoop","detail":"{readStateIndex:450; appliedIndex:444; }","duration":"223.584019ms","start":"2026-05-09T12:50:13.583892Z","end":"2026-05-09T12:50:13.807476Z","steps":["trace[414628405] 'read index received' (duration: 7.509003ms)","trace[414628405] 'applied index is now lower than readState.Index' (duration: 216.074166ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:50:13.807579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.68005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:50:13.807546Z","caller":"traceutil/trace.go:171","msg":"trace[124728340] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"229.706006ms","start":"2026-05-09T12:50:13.577812Z","end":"2026-05-09T12:50:13.807518Z","steps":["trace[124728340] 'process raft request' (duration: 229.568083ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:13.807612Z","caller":"traceutil/trace.go:171","msg":"trace[654978315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:434; }","duration":"223.740022ms","start":"2026-05-09T12:50:13.583864Z","end":"2026-05-09T12:50:13.807604Z","steps":["trace[654978315] 'agreement among raft nodes before linearized reading' (duration: 223.668291ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:13.80768Z","caller":"traceutil/trace.go:171","msg":"trace[2029883594] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"230.758725ms","start":"2026-05-09T12:50:13.576857Z","end":"2026-05-09T12:50:13.807616Z","steps":["trace[2029883594] 'process raft request' (duration: 230.47881ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:13.807791Z","caller":"traceutil/trace.go:171","msg":"trace[992229132] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"227.846639ms","start":"2026-05-09T12:50:13.579877Z","end":"2026-05-09T12:50:13.807723Z","steps":["trace[992229132] 'process raft request' (duration: 227.560654ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:13.808006Z","caller":"traceutil/trace.go:171","msg":"trace[1008875984] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"231.883997ms","start":"2026-05-09T12:50:13.575569Z","end":"2026-05-09T12:50:13.807453Z","steps":["trace[1008875984] 'process raft request' (duration: 121.448604ms)","trace[1008875984] 'compare' (duration: 109.999745ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:50:14.087102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.315242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:50:14.087267Z","caller":"traceutil/trace.go:171","msg":"trace[1801847155] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:450; }","duration":"274.235621ms","start":"2026-05-09T12:50:13.813004Z","end":"2026-05-09T12:50:14.087239Z","steps":["trace[1801847155] 'read index received' (duration: 134.646835ms)","trace[1801847155] 'applied index is now lower than readState.Index' (duration: 139.586566ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:50:14.087425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.414685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-05-09T12:50:14.087395Z","caller":"traceutil/trace.go:171","msg":"trace[438341506] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"489.281337ms","start":"2026-05-09T12:50:13.598063Z","end":"2026-05-09T12:50:14.087345Z","steps":["trace[438341506] 'process raft request' (duration: 349.574319ms)","trace[438341506] 'compare' (duration: 139.126478ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:50:14.087471Z","caller":"traceutil/trace.go:171","msg":"trace[759870087] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:435; }","duration":"274.463545ms","start":"2026-05-09T12:50:13.812994Z","end":"2026-05-09T12:50:14.087458Z","steps":["trace[759870087] 'agreement among raft nodes before linearized reading' (duration: 274.353583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:14.087583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.695742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3810"} {"level":"info","ts":"2026-05-09T12:50:14.087634Z","caller":"traceutil/trace.go:171","msg":"trace[325004599] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:435; }","duration":"272.764082ms","start":"2026-05-09T12:50:13.814854Z","end":"2026-05-09T12:50:14.087618Z","steps":["trace[325004599] 'agreement among raft nodes before linearized reading' (duration: 272.666101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:14.087635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:13.598042Z","time spent":"489.455291ms","remote":"127.0.0.1:49396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:50:14.238027Z","caller":"traceutil/trace.go:171","msg":"trace[943769478] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"143.83445ms","start":"2026-05-09T12:50:14.094146Z","end":"2026-05-09T12:50:14.237981Z","steps":["trace[943769478] 'process raft request' (duration: 101.865646ms)","trace[943769478] 'compare' (duration: 41.653708ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:50:14.238166Z","caller":"traceutil/trace.go:171","msg":"trace[981252803] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"140.903564ms","start":"2026-05-09T12:50:14.097216Z","end":"2026-05-09T12:50:14.23812Z","steps":["trace[981252803] 'process raft request' (duration: 140.6915ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:14.238185Z","caller":"traceutil/trace.go:171","msg":"trace[375854304] linearizableReadLoop","detail":"{readStateIndex:453; appliedIndex:451; }","duration":"143.518423ms","start":"2026-05-09T12:50:14.094634Z","end":"2026-05-09T12:50:14.238153Z","steps":["trace[375854304] 'read index received' (duration: 58.075278ms)","trace[375854304] 'applied index is now lower than readState.Index' (duration: 85.441725ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:50:14.2382Z","caller":"traceutil/trace.go:171","msg":"trace[254145103] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"138.396086ms","start":"2026-05-09T12:50:14.09977Z","end":"2026-05-09T12:50:14.238166Z","steps":["trace[254145103] 'process raft request' (duration: 138.217633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:14.238843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.054423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-05-09T12:50:14.238958Z","caller":"traceutil/trace.go:171","msg":"trace[1804684741] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:439; }","duration":"144.326238ms","start":"2026-05-09T12:50:14.094615Z","end":"2026-05-09T12:50:14.238941Z","steps":["trace[1804684741] 'agreement among raft nodes before linearized reading' (duration: 143.637705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:14.457038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.889592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:50:14.457485Z","caller":"traceutil/trace.go:171","msg":"trace[190511931] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"168.712199ms","start":"2026-05-09T12:50:14.288752Z","end":"2026-05-09T12:50:14.457464Z","steps":["trace[190511931] 'process raft request' (duration: 168.615787ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:14.457504Z","caller":"traceutil/trace.go:171","msg":"trace[784180674] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"171.484771ms","start":"2026-05-09T12:50:14.28597Z","end":"2026-05-09T12:50:14.457455Z","steps":["trace[784180674] 'process raft request' (duration: 65.08023ms)","trace[784180674] 'compare' (duration: 105.753349ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:50:14.466914Z","caller":"traceutil/trace.go:171","msg":"trace[1485939824] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"155.52988ms","start":"2026-05-09T12:50:14.31137Z","end":"2026-05-09T12:50:14.4669Z","steps":["trace[1485939824] 'process raft request' (duration: 155.289665ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:14.466968Z","caller":"traceutil/trace.go:171","msg":"trace[945271957] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"152.86652ms","start":"2026-05-09T12:50:14.314074Z","end":"2026-05-09T12:50:14.466941Z","steps":["trace[945271957] 'process raft request' (duration: 152.701806ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:14.466845Z","caller":"traceutil/trace.go:171","msg":"trace[1455077774] linearizableReadLoop","detail":"{readStateIndex:462; appliedIndex:459; }","duration":"153.502842ms","start":"2026-05-09T12:50:14.313328Z","end":"2026-05-09T12:50:14.466831Z","steps":["trace[1455077774] 'read index received' (duration: 37.830255ms)","trace[1455077774] 'applied index is now lower than readState.Index' (duration: 115.671546ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:50:14.467348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.000531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3855"} {"level":"info","ts":"2026-05-09T12:50:14.46739Z","caller":"traceutil/trace.go:171","msg":"trace[1985712928] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:446; }","duration":"154.099403ms","start":"2026-05-09T12:50:14.313279Z","end":"2026-05-09T12:50:14.467378Z","steps":["trace[1985712928] 'agreement among raft nodes before linearized reading' (duration: 153.92668ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:14.651916Z","caller":"traceutil/trace.go:171","msg":"trace[377731235] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"118.088453ms","start":"2026-05-09T12:50:14.53381Z","end":"2026-05-09T12:50:14.651898Z","steps":["trace[377731235] 'process raft request' (duration: 118.009611ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:14.651906Z","caller":"traceutil/trace.go:171","msg":"trace[184457394] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"124.522524ms","start":"2026-05-09T12:50:14.527349Z","end":"2026-05-09T12:50:14.651871Z","steps":["trace[184457394] 'process raft request' (duration: 124.283839ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:14.652255Z","caller":"traceutil/trace.go:171","msg":"trace[1465022689] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"124.236469ms","start":"2026-05-09T12:50:14.528005Z","end":"2026-05-09T12:50:14.652242Z","steps":["trace[1465022689] 'process raft request' (duration: 123.771101ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:14.652283Z","caller":"traceutil/trace.go:171","msg":"trace[415378937] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"124.851331ms","start":"2026-05-09T12:50:14.527426Z","end":"2026-05-09T12:50:14.652277Z","steps":["trace[415378937] 'process raft request' (duration: 124.321361ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:14.889272Z","caller":"traceutil/trace.go:171","msg":"trace[1567841388] linearizableReadLoop","detail":"{readStateIndex:472; appliedIndex:471; }","duration":"193.14004ms","start":"2026-05-09T12:50:14.696105Z","end":"2026-05-09T12:50:14.889245Z","steps":["trace[1567841388] 'read index received' (duration: 116.641355ms)","trace[1567841388] 'applied index is now lower than readState.Index' (duration: 76.497385ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:50:14.889518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.399206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:50:14.889605Z","caller":"traceutil/trace.go:171","msg":"trace[1563579246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:455; }","duration":"193.541308ms","start":"2026-05-09T12:50:14.696044Z","end":"2026-05-09T12:50:14.889585Z","steps":["trace[1563579246] 'agreement among raft nodes before linearized reading' (duration: 193.327224ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:14.890001Z","caller":"traceutil/trace.go:171","msg":"trace[1513425414] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"230.515498ms","start":"2026-05-09T12:50:14.65947Z","end":"2026-05-09T12:50:14.889985Z","steps":["trace[1513425414] 'process raft request' (duration: 153.358129ms)","trace[1513425414] 'compare' (duration: 76.285482ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:50:16.134299Z","caller":"traceutil/trace.go:171","msg":"trace[957980342] linearizableReadLoop","detail":"{readStateIndex:476; appliedIndex:475; }","duration":"432.501945ms","start":"2026-05-09T12:50:15.70178Z","end":"2026-05-09T12:50:16.134282Z","steps":["trace[957980342] 'read index received' (duration: 432.305262ms)","trace[957980342] 'applied index is now lower than readState.Index' (duration: 196.213µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:50:16.134413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.613187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:50:16.134434Z","caller":"traceutil/trace.go:171","msg":"trace[1448239635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:459; }","duration":"432.690718ms","start":"2026-05-09T12:50:15.701734Z","end":"2026-05-09T12:50:16.134425Z","steps":["trace[1448239635] 'agreement among raft nodes before linearized reading' (duration: 432.614976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:16.134462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:15.701683Z","time spent":"432.771059ms","remote":"127.0.0.1:48892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T12:50:16.134443Z","caller":"traceutil/trace.go:171","msg":"trace[574004066] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"654.489409ms","start":"2026-05-09T12:50:15.47988Z","end":"2026-05-09T12:50:16.134369Z","steps":["trace[574004066] 'process raft request' (duration: 654.263855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:16.134707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:15.479871Z","time spent":"654.687672ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:50:17.201289Z","caller":"traceutil/trace.go:171","msg":"trace[822623808] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:478; }","duration":"224.999792ms","start":"2026-05-09T12:50:16.976256Z","end":"2026-05-09T12:50:17.201255Z","steps":["trace[822623808] 'read index received' (duration: 224.86431ms)","trace[822623808] 'applied index is now lower than readState.Index' (duration: 134.002µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:50:17.2014Z","caller":"traceutil/trace.go:171","msg":"trace[1444553783] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"276.876207ms","start":"2026-05-09T12:50:16.924491Z","end":"2026-05-09T12:50:17.201367Z","steps":["trace[1444553783] 'process raft request' (duration: 276.551631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:17.20147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.175085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T12:50:17.201517Z","caller":"traceutil/trace.go:171","msg":"trace[797808055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:462; }","duration":"225.306598ms","start":"2026-05-09T12:50:16.976195Z","end":"2026-05-09T12:50:17.201501Z","steps":["trace[797808055] 'agreement among raft nodes before linearized reading' (duration: 225.188095ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:20.794815Z","caller":"traceutil/trace.go:171","msg":"trace[665297733] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"127.85549ms","start":"2026-05-09T12:50:20.666916Z","end":"2026-05-09T12:50:20.794771Z","steps":["trace[665297733] 'process raft request' (duration: 127.531275ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:22.978619Z","caller":"traceutil/trace.go:171","msg":"trace[1751069175] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"176.191929ms","start":"2026-05-09T12:50:22.802386Z","end":"2026-05-09T12:50:22.978578Z","steps":["trace[1751069175] 'process raft request' (duration: 175.988515ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:27.384447Z","caller":"traceutil/trace.go:171","msg":"trace[719898355] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"265.247262ms","start":"2026-05-09T12:50:27.119176Z","end":"2026-05-09T12:50:27.384423Z","steps":["trace[719898355] 'process raft request' (duration: 265.09687ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:27.479666Z","caller":"traceutil/trace.go:171","msg":"trace[858213335] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"289.408038ms","start":"2026-05-09T12:50:27.190205Z","end":"2026-05-09T12:50:27.479613Z","steps":["trace[858213335] 'process raft request' (duration: 289.211135ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:27.480006Z","caller":"traceutil/trace.go:171","msg":"trace[461387491] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:537; }","duration":"228.46612ms","start":"2026-05-09T12:50:27.251527Z","end":"2026-05-09T12:50:27.479993Z","steps":["trace[461387491] 'read index received' (duration: 132.786174ms)","trace[461387491] 'applied index is now lower than readState.Index' (duration: 95.679196ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:50:27.480049Z","caller":"traceutil/trace.go:171","msg":"trace[1529769191] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"273.982523ms","start":"2026-05-09T12:50:27.206051Z","end":"2026-05-09T12:50:27.480034Z","steps":["trace[1529769191] 'process raft request' (duration: 273.580326ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:27.479783Z","caller":"traceutil/trace.go:171","msg":"trace[2105330301] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"283.376854ms","start":"2026-05-09T12:50:27.196384Z","end":"2026-05-09T12:50:27.479761Z","steps":["trace[2105330301] 'process raft request' (duration: 283.15634ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:27.480111Z","caller":"traceutil/trace.go:171","msg":"trace[923265266] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"271.577571ms","start":"2026-05-09T12:50:27.208529Z","end":"2026-05-09T12:50:27.480107Z","steps":["trace[923265266] 'process raft request' (duration: 271.241925ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:27.480091Z","caller":"traceutil/trace.go:171","msg":"trace[538870113] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"272.291462ms","start":"2026-05-09T12:50:27.20779Z","end":"2026-05-09T12:50:27.480082Z","steps":["trace[538870113] 'process raft request' (duration: 271.871765ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:27.480228Z","caller":"traceutil/trace.go:171","msg":"trace[1244113502] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"263.545233ms","start":"2026-05-09T12:50:27.216676Z","end":"2026-05-09T12:50:27.480222Z","steps":["trace[1244113502] 'process raft request' (duration: 263.137886ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:27.480202Z","caller":"traceutil/trace.go:171","msg":"trace[7669910] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"278.172255ms","start":"2026-05-09T12:50:27.201983Z","end":"2026-05-09T12:50:27.480155Z","steps":["trace[7669910] 'process raft request' (duration: 277.588015ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:27.480276Z","caller":"traceutil/trace.go:171","msg":"trace[572869978] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"259.557984ms","start":"2026-05-09T12:50:27.220711Z","end":"2026-05-09T12:50:27.480269Z","steps":["trace[572869978] 'process raft request' (duration: 259.159568ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:27.480283Z","caller":"traceutil/trace.go:171","msg":"trace[1604706846] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"229.398026ms","start":"2026-05-09T12:50:27.250875Z","end":"2026-05-09T12:50:27.480273Z","steps":["trace[1604706846] 'process raft request' (duration: 229.07675ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:27.480249Z","caller":"traceutil/trace.go:171","msg":"trace[1604206288] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"275.113832ms","start":"2026-05-09T12:50:27.2051Z","end":"2026-05-09T12:50:27.480214Z","steps":["trace[1604206288] 'process raft request' (duration: 274.503031ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:27.480321Z","caller":"traceutil/trace.go:171","msg":"trace[2133506194] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"286.978736ms","start":"2026-05-09T12:50:27.193268Z","end":"2026-05-09T12:50:27.480246Z","steps":["trace[2133506194] 'process raft request' (duration: 286.242324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:27.480517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.956448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-09T12:50:27.48062Z","caller":"traceutil/trace.go:171","msg":"trace[564726373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:530; }","duration":"229.09132ms","start":"2026-05-09T12:50:27.251506Z","end":"2026-05-09T12:50:27.480597Z","steps":["trace[564726373] 'agreement among raft nodes before linearized reading' (duration: 228.792645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:27.484109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.241964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T12:50:27.48419Z","caller":"traceutil/trace.go:171","msg":"trace[1822151936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:531; }","duration":"218.349185ms","start":"2026-05-09T12:50:27.265824Z","end":"2026-05-09T12:50:27.484173Z","steps":["trace[1822151936] 'agreement among raft nodes before linearized reading' (duration: 218.199533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:28.194657Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199546343233421,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T12:50:28.695232Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199546343233421,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T12:50:28.720842Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.116551789s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T12:50:28.742454Z","caller":"traceutil/trace.go:171","msg":"trace[1451525564] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"1.143061163s","start":"2026-05-09T12:50:27.59937Z","end":"2026-05-09T12:50:28.742431Z","steps":["trace[1451525564] 'process raft request' (duration: 1.142957981s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:28.742663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:27.599349Z","time spent":"1.143171624s","remote":"127.0.0.1:48962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:50:28.743177Z","caller":"traceutil/trace.go:171","msg":"trace[836201119] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.143770876s","start":"2026-05-09T12:50:27.599373Z","end":"2026-05-09T12:50:28.743144Z","steps":["trace[836201119] 'process raft request' (duration: 1.121903762s)","trace[836201119] 'compare' (duration: 20.609262ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:50:28.743322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:27.59936Z","time spent":"1.143894467s","remote":"127.0.0.1:49436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T12:50:28.745766Z","caller":"traceutil/trace.go:171","msg":"trace[18170047] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:577; }","duration":"1.05220032s","start":"2026-05-09T12:50:27.693537Z","end":"2026-05-09T12:50:28.745737Z","steps":["trace[18170047] 'read index received' (duration: 1.027710291s)","trace[18170047] 'applied index is now lower than readState.Index' (duration: 24.489219ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:50:28.745882Z","caller":"traceutil/trace.go:171","msg":"trace[1584612804] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"1.082973909s","start":"2026-05-09T12:50:27.662894Z","end":"2026-05-09T12:50:28.745868Z","steps":["trace[1584612804] 'process raft request' (duration: 1.082704865s)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:28.745862Z","caller":"traceutil/trace.go:171","msg":"trace[563890267] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"1.130037788s","start":"2026-05-09T12:50:27.615771Z","end":"2026-05-09T12:50:28.745809Z","steps":["trace[563890267] 'process raft request' (duration: 1.129543569s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:28.746018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:27.662849Z","time spent":"1.083065241s","remote":"127.0.0.1:48962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T12:50:28.746111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:27.615746Z","time spent":"1.130232262s","remote":"127.0.0.1:48962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:50:28.746339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.089064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-05-09T12:50:28.746368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.435283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-09T12:50:28.746397Z","caller":"traceutil/trace.go:171","msg":"trace[1230384201] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:557; }","duration":"544.183395ms","start":"2026-05-09T12:50:28.202198Z","end":"2026-05-09T12:50:28.746382Z","steps":["trace[1230384201] 'agreement among raft nodes before linearized reading' (duration: 543.766098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:28.746339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05279513s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T12:50:28.746436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:28.202176Z","time spent":"544.252017ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-09T12:50:28.746434Z","caller":"traceutil/trace.go:171","msg":"trace[1241065547] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:557; }","duration":"430.552644ms","start":"2026-05-09T12:50:28.315866Z","end":"2026-05-09T12:50:28.746418Z","steps":["trace[1241065547] 'agreement among raft nodes before linearized reading' (duration: 430.426902ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:28.746443Z","caller":"traceutil/trace.go:171","msg":"trace[1151086490] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:557; }","duration":"1.052945053s","start":"2026-05-09T12:50:27.693485Z","end":"2026-05-09T12:50:28.74643Z","steps":["trace[1151086490] 'agreement among raft nodes before linearized reading' (duration: 1.05279416s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:28.746477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:27.693473Z","time spent":"1.052995213s","remote":"127.0.0.1:48892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T12:50:28.746473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:28.315839Z","time spent":"430.625926ms","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.244\" "} {"level":"info","ts":"2026-05-09T12:50:33.144743Z","caller":"traceutil/trace.go:171","msg":"trace[1792940870] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"100.898717ms","start":"2026-05-09T12:50:33.043793Z","end":"2026-05-09T12:50:33.144692Z","steps":["trace[1792940870] 'read index received' (duration: 92.226784ms)","trace[1792940870] 'applied index is now lower than readState.Index' (duration: 8.671203ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:50:33.14487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.0655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-09T12:50:33.144903Z","caller":"traceutil/trace.go:171","msg":"trace[1267623410] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:615; }","duration":"101.171402ms","start":"2026-05-09T12:50:33.043721Z","end":"2026-05-09T12:50:33.144892Z","steps":["trace[1267623410] 'agreement among raft nodes before linearized reading' (duration: 101.08695ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:33.144866Z","caller":"traceutil/trace.go:171","msg":"trace[1874987139] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"195.970274ms","start":"2026-05-09T12:50:32.948839Z","end":"2026-05-09T12:50:33.144809Z","steps":["trace[1874987139] 'process raft request' (duration: 188.334238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:33.399917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.353981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:50:33.400083Z","caller":"traceutil/trace.go:171","msg":"trace[1610121114] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"250.415357ms","start":"2026-05-09T12:50:33.149638Z","end":"2026-05-09T12:50:33.400053Z","steps":["trace[1610121114] 'process raft request' (duration: 55.797522ms)","trace[1610121114] 'compare' (duration: 194.170588ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:50:33.636557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.176067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:50:33.636938Z","caller":"traceutil/trace.go:171","msg":"trace[958242230] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"419.180806ms","start":"2026-05-09T12:50:33.217638Z","end":"2026-05-09T12:50:33.636819Z","steps":["trace[958242230] 'process raft request' (duration: 287.646012ms)","trace[958242230] 'compare' (duration: 131.035405ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:50:33.637167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:33.217609Z","time spent":"419.43921ms","remote":"127.0.0.1:49250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T12:50:33.706525Z","caller":"traceutil/trace.go:171","msg":"trace[1798137945] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:641; }","duration":"164.891784ms","start":"2026-05-09T12:50:33.541597Z","end":"2026-05-09T12:50:33.706489Z","steps":["trace[1798137945] 'read index received' (duration: 34.303467ms)","trace[1798137945] 'applied index is now lower than readState.Index' (duration: 130.585977ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:50:33.706913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.294721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T12:50:33.707035Z","caller":"traceutil/trace.go:171","msg":"trace[586627096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:617; }","duration":"165.477944ms","start":"2026-05-09T12:50:33.541538Z","end":"2026-05-09T12:50:33.707016Z","steps":["trace[586627096] 'agreement among raft nodes before linearized reading' (duration: 165.054427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:33.706967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.155736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T12:50:33.707138Z","caller":"traceutil/trace.go:171","msg":"trace[591132809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:617; }","duration":"157.397901ms","start":"2026-05-09T12:50:33.549725Z","end":"2026-05-09T12:50:33.707123Z","steps":["trace[591132809] 'agreement among raft nodes before linearized reading' (duration: 157.048175ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:33.829471Z","caller":"traceutil/trace.go:171","msg":"trace[699789932] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"115.919034ms","start":"2026-05-09T12:50:33.713524Z","end":"2026-05-09T12:50:33.829443Z","steps":["trace[699789932] 'process raft request' (duration: 115.781912ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:34.259752Z","caller":"traceutil/trace.go:171","msg":"trace[1050672399] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"123.721411ms","start":"2026-05-09T12:50:34.135999Z","end":"2026-05-09T12:50:34.259721Z","steps":["trace[1050672399] 'process raft request' (duration: 122.002942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:36.874274Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199546343233642,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T12:50:37.37547Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199546343233642,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T12:50:37.527771Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.202798893s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T12:50:37.53019Z","caller":"traceutil/trace.go:171","msg":"trace[1919831704] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"1.156757696s","start":"2026-05-09T12:50:36.373409Z","end":"2026-05-09T12:50:37.530166Z","steps":["trace[1919831704] 'read index received' (duration: 1.15448733s)","trace[1919831704] 'applied index is now lower than readState.Index' (duration: 2.269366ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:50:37.530304Z","caller":"traceutil/trace.go:171","msg":"trace[1606922457] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.206266008s","start":"2026-05-09T12:50:36.323977Z","end":"2026-05-09T12:50:37.530243Z","steps":["trace[1606922457] 'process raft request' (duration: 1.204339138s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:37.530469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157043191s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-c5kkr\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-09T12:50:37.530566Z","caller":"traceutil/trace.go:171","msg":"trace[1552346344] range","detail":"{range_begin:/registry/pods/kube-system/cilium-c5kkr; range_end:; response_count:1; response_revision:635; }","duration":"1.157183163s","start":"2026-05-09T12:50:36.373371Z","end":"2026-05-09T12:50:37.530554Z","steps":["trace[1552346344] 'agreement among raft nodes before linearized reading' (duration: 1.156934558s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:37.530668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:36.323944Z","time spent":"1.206506582s","remote":"127.0.0.1:49016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:50:37.530666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.459512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-09T12:50:37.530773Z","caller":"traceutil/trace.go:171","msg":"trace[908519785] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"676.630034ms","start":"2026-05-09T12:50:36.854121Z","end":"2026-05-09T12:50:37.530751Z","steps":["trace[908519785] 'agreement among raft nodes before linearized reading' (duration: 676.326289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:37.530695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:36.373321Z","time spent":"1.157354076s","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-c5kkr\" "} {"level":"warn","ts":"2026-05-09T12:50:37.530827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:36.854099Z","time spent":"676.715776ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T12:50:37.530595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.314106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-09T12:50:37.531045Z","caller":"traceutil/trace.go:171","msg":"trace[1786793465] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"118.805074ms","start":"2026-05-09T12:50:37.412224Z","end":"2026-05-09T12:50:37.531029Z","steps":["trace[1786793465] 'agreement among raft nodes before linearized reading' (duration: 118.279056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:38.196339Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199546343233645,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T12:50:38.401506Z","caller":"traceutil/trace.go:171","msg":"trace[1428761374] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"856.269412ms","start":"2026-05-09T12:50:37.54521Z","end":"2026-05-09T12:50:38.40148Z","steps":["trace[1428761374] 'process raft request' (duration: 856.032158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:38.401677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:37.545183Z","time spent":"856.416864ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:50:39.696451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00028769s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-09T12:50:39.696594Z","caller":"traceutil/trace.go:171","msg":"trace[1110460023] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000505282s","start":"2026-05-09T12:50:37.696061Z","end":"2026-05-09T12:50:39.696566Z","steps":["trace[1110460023] 'agreement among raft nodes before linearized reading' (duration: 2.000322969s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:39.696649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:37.696038Z","time spent":"2.000599414s","remote":"127.0.0.1:48892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T12:50:40.070085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.522082132s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:50:40.07033Z","caller":"traceutil/trace.go:171","msg":"trace[1267844653] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"2.500407583s","start":"2026-05-09T12:50:37.569893Z","end":"2026-05-09T12:50:40.070301Z","steps":["trace[1267844653] 'process raft request' (duration: 973.77819ms)","trace[1267844653] 'compare' (duration: 1.521725456s)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:50:40.070382Z","caller":"traceutil/trace.go:171","msg":"trace[2087531329] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"2.499440708s","start":"2026-05-09T12:50:37.570903Z","end":"2026-05-09T12:50:40.070344Z","steps":["trace[2087531329] 'process raft request' (duration: 2.499301186s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:40.070633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:37.570894Z","time spent":"2.499700242s","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:50:40.070551Z","caller":"traceutil/trace.go:171","msg":"trace[1727366062] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"2.481614412s","start":"2026-05-09T12:50:37.588903Z","end":"2026-05-09T12:50:40.070517Z","steps":["trace[1727366062] 'process raft request' (duration: 2.481370688s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:40.070757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:37.588873Z","time spent":"2.481853335s","remote":"127.0.0.1:49172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T12:50:40.070723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:37.569866Z","time spent":"2.500653197s","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:50:40.070833Z","caller":"traceutil/trace.go:171","msg":"trace[1697317039] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"2.294630719s","start":"2026-05-09T12:50:37.776119Z","end":"2026-05-09T12:50:40.070749Z","steps":["trace[1697317039] 'process raft request' (duration: 2.294189572s)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:40.070977Z","caller":"traceutil/trace.go:171","msg":"trace[1536548012] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:661; }","duration":"2.37484414s","start":"2026-05-09T12:50:37.696115Z","end":"2026-05-09T12:50:40.070959Z","steps":["trace[1536548012] 'read index received' (duration: 705.199248ms)","trace[1536548012] 'applied index is now lower than readState.Index' (duration: 1.669643412s)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:50:40.071108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:37.776067Z","time spent":"2.294844062s","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:50:40.071189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.291889355s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T12:50:40.07123Z","caller":"traceutil/trace.go:171","msg":"trace[355386105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"2.291974406s","start":"2026-05-09T12:50:37.779244Z","end":"2026-05-09T12:50:40.071218Z","steps":["trace[355386105] 'agreement among raft nodes before linearized reading' (duration: 2.291854125s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:40.071247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.287672058s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T12:50:40.071328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.748658428s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-09T12:50:40.071332Z","caller":"traceutil/trace.go:171","msg":"trace[1856523171] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"1.287756371s","start":"2026-05-09T12:50:38.783533Z","end":"2026-05-09T12:50:40.071289Z","steps":["trace[1856523171] 'agreement among raft nodes before linearized reading' (duration: 1.287641968s)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:40.07136Z","caller":"traceutil/trace.go:171","msg":"trace[854737470] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:640; }","duration":"1.748727619s","start":"2026-05-09T12:50:38.322624Z","end":"2026-05-09T12:50:40.071352Z","steps":["trace[854737470] 'agreement among raft nodes before linearized reading' (duration: 1.748653737s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:40.071389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:38.322598Z","time spent":"1.74878451s","remote":"127.0.0.1:48938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.244\" "} {"level":"warn","ts":"2026-05-09T12:50:40.071405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:38.783513Z","time spent":"1.287855992s","remote":"127.0.0.1:48892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T12:50:40.07126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:37.779231Z","time spent":"2.292022368s","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T12:50:40.071741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.943635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:50:40.071934Z","caller":"traceutil/trace.go:171","msg":"trace[1243495714] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"373.175099ms","start":"2026-05-09T12:50:39.698741Z","end":"2026-05-09T12:50:40.071917Z","steps":["trace[1243495714] 'agreement among raft nodes before linearized reading' (duration: 372.915395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:40.072012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:39.69872Z","time spent":"373.277621ms","remote":"127.0.0.1:48894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T12:50:40.088018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.240119046s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T12:50:40.088116Z","caller":"traceutil/trace.go:171","msg":"trace[1239578064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"2.240275199s","start":"2026-05-09T12:50:37.847822Z","end":"2026-05-09T12:50:40.088097Z","steps":["trace[1239578064] 'agreement among raft nodes before linearized reading' (duration: 2.224294375s)","trace[1239578064] 'range keys from bolt db' (duration: 15.782429ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:50:40.088202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:37.847748Z","time spent":"2.240442282s","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T12:50:40.631878Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199546343233652,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T12:50:41.057733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:40.124447Z","time spent":"933.280161ms","remote":"127.0.0.1:48938","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-09T12:50:41.058332Z","caller":"traceutil/trace.go:171","msg":"trace[860387310] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"928.557186ms","start":"2026-05-09T12:50:40.12974Z","end":"2026-05-09T12:50:41.058297Z","steps":["trace[860387310] 'process raft request' (duration: 927.796434ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:41.0586Z","caller":"traceutil/trace.go:171","msg":"trace[948711905] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"927.5735ms","start":"2026-05-09T12:50:40.130989Z","end":"2026-05-09T12:50:41.058563Z","steps":["trace[948711905] 'process raft request' (duration: 927.125323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:41.058714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:40.129718Z","time spent":"928.887941ms","remote":"127.0.0.1:48992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T12:50:41.058841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:40.130979Z","time spent":"927.745462ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:50:41.059223Z","caller":"traceutil/trace.go:171","msg":"trace[1581274261] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:666; }","duration":"928.2264ms","start":"2026-05-09T12:50:40.130967Z","end":"2026-05-09T12:50:41.059194Z","steps":["trace[1581274261] 'read index received' (duration: 850.210931ms)","trace[1581274261] 'applied index is now lower than readState.Index' (duration: 78.014229ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:50:41.059348Z","caller":"traceutil/trace.go:171","msg":"trace[777410408] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"926.600145ms","start":"2026-05-09T12:50:40.132734Z","end":"2026-05-09T12:50:41.059335Z","steps":["trace[777410408] 'process raft request' (duration: 925.470187ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:41.059426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:40.132709Z","time spent":"926.679076ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:50:41.05962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.625756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-09T12:50:41.059654Z","caller":"traceutil/trace.go:171","msg":"trace[95606195] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:643; }","duration":"928.687946ms","start":"2026-05-09T12:50:40.130958Z","end":"2026-05-09T12:50:41.059646Z","steps":["trace[95606195] 'agreement among raft nodes before linearized reading' (duration: 928.416282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:41.05968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:40.130863Z","time spent":"928.810229ms","remote":"127.0.0.1:49148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-05-09T12:50:41.059693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.012424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:50:41.05976Z","caller":"traceutil/trace.go:171","msg":"trace[1037524559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"924.118135ms","start":"2026-05-09T12:50:40.135623Z","end":"2026-05-09T12:50:41.059741Z","steps":["trace[1037524559] 'agreement among raft nodes before linearized reading' (duration: 923.866721ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:41.059804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:40.135609Z","time spent":"924.184857ms","remote":"127.0.0.1:48892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T12:50:41.568922Z","caller":"traceutil/trace.go:171","msg":"trace[1195905830] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"155.614525ms","start":"2026-05-09T12:50:41.413284Z","end":"2026-05-09T12:50:41.568898Z","steps":["trace[1195905830] 'process raft request' (duration: 155.458272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:42.871093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.626553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T12:50:42.871093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.82041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:50:42.871169Z","caller":"traceutil/trace.go:171","msg":"trace[1055614100] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:663; }","duration":"335.708724ms","start":"2026-05-09T12:50:42.535441Z","end":"2026-05-09T12:50:42.87115Z","steps":["trace[1055614100] 'range keys from in-memory index tree' (duration: 335.573492ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:42.871181Z","caller":"traceutil/trace.go:171","msg":"trace[120748076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"173.011702ms","start":"2026-05-09T12:50:42.698152Z","end":"2026-05-09T12:50:42.871163Z","steps":["trace[120748076] 'range keys from in-memory index tree' (duration: 172.565586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:42.871203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:42.535405Z","time spent":"335.790526ms","remote":"127.0.0.1:49002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-05-09T12:50:45.886966Z","caller":"traceutil/trace.go:171","msg":"trace[723244957] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"199.212548ms","start":"2026-05-09T12:50:45.687725Z","end":"2026-05-09T12:50:45.886938Z","steps":["trace[723244957] 'process raft request' (duration: 198.947893ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:56.674094Z","caller":"traceutil/trace.go:171","msg":"trace[200221558] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"465.77585ms","start":"2026-05-09T12:50:56.208293Z","end":"2026-05-09T12:50:56.674068Z","steps":["trace[200221558] 'process raft request' (duration: 465.549517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:56.674303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:56.208273Z","time spent":"465.912493ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:50:57.737019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.031873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:50:57.737128Z","caller":"traceutil/trace.go:171","msg":"trace[1461025320] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:835; }","duration":"1.237260005s","start":"2026-05-09T12:50:56.499849Z","end":"2026-05-09T12:50:57.737109Z","steps":["trace[1461025320] 'read index received' (duration: 174.015574ms)","trace[1461025320] 'applied index is now lower than readState.Index' (duration: 1.063242951s)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:50:57.737205Z","caller":"traceutil/trace.go:171","msg":"trace[1869862697] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"1.526332041s","start":"2026-05-09T12:50:56.21084Z","end":"2026-05-09T12:50:57.737172Z","steps":["trace[1869862697] 'process raft request' (duration: 1.185051935s)","trace[1869862697] 'compare' (duration: 340.83421ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:50:57.737332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.237481358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4srtr\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-09T12:50:57.737376Z","caller":"traceutil/trace.go:171","msg":"trace[1806455076] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4srtr; range_end:; response_count:1; response_revision:805; }","duration":"1.237518838s","start":"2026-05-09T12:50:56.499842Z","end":"2026-05-09T12:50:57.737361Z","steps":["trace[1806455076] 'agreement among raft nodes before linearized reading' (duration: 1.237363696s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:57.737397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:56.210815Z","time spent":"1.526475544s","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:50:57.737416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:56.499798Z","time spent":"1.23761007s","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4srtr\" "} {"level":"warn","ts":"2026-05-09T12:50:57.73775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.189297801s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:50:57.737788Z","caller":"traceutil/trace.go:171","msg":"trace[2104952239] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:805; }","duration":"1.189390323s","start":"2026-05-09T12:50:56.548389Z","end":"2026-05-09T12:50:57.737779Z","steps":["trace[2104952239] 'agreement among raft nodes before linearized reading' (duration: 1.189295022s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:57.737811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:56.548357Z","time spent":"1.189449263s","remote":"127.0.0.1:49002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-05-09T12:50:57.73796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.159103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T12:50:57.738008Z","caller":"traceutil/trace.go:171","msg":"trace[59137903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:805; }","duration":"576.244305ms","start":"2026-05-09T12:50:57.161751Z","end":"2026-05-09T12:50:57.737995Z","steps":["trace[59137903] 'agreement among raft nodes before linearized reading' (duration: 576.129153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:57.738028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.709471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-09T12:50:57.738051Z","caller":"traceutil/trace.go:171","msg":"trace[1087821425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:805; }","duration":"576.777052ms","start":"2026-05-09T12:50:57.161268Z","end":"2026-05-09T12:50:57.738045Z","steps":["trace[1087821425] 'agreement among raft nodes before linearized reading' (duration: 576.64821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:57.738051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:57.161688Z","time spent":"576.348807ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T12:50:57.73807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:57.161246Z","time spent":"576.820393ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T12:50:57.738167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.544104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-09T12:50:57.738187Z","caller":"traceutil/trace.go:171","msg":"trace[1242608983] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:805; }","duration":"726.604336ms","start":"2026-05-09T12:50:57.011578Z","end":"2026-05-09T12:50:57.738182Z","steps":["trace[1242608983] 'agreement among raft nodes before linearized reading' (duration: 726.547235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:57.738204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:57.011549Z","time spent":"726.650756ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T12:51:08.146175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.847499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:51:08.146455Z","caller":"traceutil/trace.go:171","msg":"trace[2090087308] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"294.82434ms","start":"2026-05-09T12:51:07.851613Z","end":"2026-05-09T12:51:08.146438Z","steps":["trace[2090087308] 'process raft request' (duration: 294.784079ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:51:08.146641Z","caller":"traceutil/trace.go:171","msg":"trace[489581249] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"295.097573ms","start":"2026-05-09T12:51:07.851525Z","end":"2026-05-09T12:51:08.146622Z","steps":["trace[489581249] 'process raft request' (duration: 294.779968ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:51:08.146639Z","caller":"traceutil/trace.go:171","msg":"trace[1958113744] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"295.126664ms","start":"2026-05-09T12:51:07.851467Z","end":"2026-05-09T12:51:08.146594Z","steps":["trace[1958113744] 'process raft request' (duration: 167.746197ms)","trace[1958113744] 'compare' (duration: 126.575655ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:51:59.226302Z","caller":"traceutil/trace.go:171","msg":"trace[1869854374] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"148.812087ms","start":"2026-05-09T12:51:59.077456Z","end":"2026-05-09T12:51:59.226268Z","steps":["trace[1869854374] 'process raft request' (duration: 148.637465ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:52:01.336909Z","caller":"traceutil/trace.go:171","msg":"trace[1801335207] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"104.861486ms","start":"2026-05-09T12:52:01.231996Z","end":"2026-05-09T12:52:01.336858Z","steps":["trace[1801335207] 'process raft request' (duration: 104.710494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:52:18.670668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.320003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-09T12:52:18.670776Z","caller":"traceutil/trace.go:171","msg":"trace[1769702703] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1201; }","duration":"148.480975ms","start":"2026-05-09T12:52:18.522278Z","end":"2026-05-09T12:52:18.670759Z","steps":["trace[1769702703] 'range keys from in-memory index tree' (duration: 148.13753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:52:18.927312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.02767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:52:18.9275Z","caller":"traceutil/trace.go:171","msg":"trace[564533107] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"182.852063ms","start":"2026-05-09T12:52:18.744617Z","end":"2026-05-09T12:52:18.927469Z","steps":["trace[564533107] 'process raft request' (duration: 34.54216ms)","trace[564533107] 'compare' (duration: 147.810758ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:52:18.956247Z","caller":"traceutil/trace.go:171","msg":"trace[373143666] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"211.093034ms","start":"2026-05-09T12:52:18.745119Z","end":"2026-05-09T12:52:18.956212Z","steps":["trace[373143666] 'process raft request' (duration: 210.850261ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:52:18.956276Z","caller":"traceutil/trace.go:171","msg":"trace[1838337090] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"210.272014ms","start":"2026-05-09T12:52:18.74598Z","end":"2026-05-09T12:52:18.956252Z","steps":["trace[1838337090] 'process raft request' (duration: 210.158463ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:52:47.232942Z","caller":"traceutil/trace.go:171","msg":"trace[23613476] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"114.025291ms","start":"2026-05-09T12:52:47.118877Z","end":"2026-05-09T12:52:47.232902Z","steps":["trace[23613476] 'process raft request' (duration: 106.525631ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:52:47.843935Z","caller":"traceutil/trace.go:171","msg":"trace[1473629111] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"107.442172ms","start":"2026-05-09T12:52:47.736468Z","end":"2026-05-09T12:52:47.843911Z","steps":["trace[1473629111] 'process raft request' (duration: 107.279491ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:52:49.455596Z","caller":"traceutil/trace.go:171","msg":"trace[2100072195] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"150.022187ms","start":"2026-05-09T12:52:49.305549Z","end":"2026-05-09T12:52:49.455571Z","steps":["trace[2100072195] 'process raft request' (duration: 107.170007ms)","trace[2100072195] 'compare' (duration: 42.690868ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:52:51.620338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.000432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:52:51.620582Z","caller":"traceutil/trace.go:171","msg":"trace[511784491] linearizableReadLoop","detail":"{readStateIndex:1398; appliedIndex:1397; }","duration":"161.481393ms","start":"2026-05-09T12:52:51.45905Z","end":"2026-05-09T12:52:51.620532Z","steps":["trace[511784491] 'read index received' (duration: 42.691µs)","trace[511784491] 'applied index is now lower than readState.Index' (duration: 161.437102ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:52:51.620636Z","caller":"traceutil/trace.go:171","msg":"trace[1703252063] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"228.843169ms","start":"2026-05-09T12:52:51.391761Z","end":"2026-05-09T12:52:51.620604Z","steps":["trace[1703252063] 'process raft request' (duration: 57.465673ms)","trace[1703252063] 'compare' (duration: 170.869901ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:52:51.620817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.755076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-09T12:52:51.620895Z","caller":"traceutil/trace.go:171","msg":"trace[1392998540] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1340; }","duration":"161.882026ms","start":"2026-05-09T12:52:51.458992Z","end":"2026-05-09T12:52:51.620874Z","steps":["trace[1392998540] 'agreement among raft nodes before linearized reading' (duration: 161.638454ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:52:51.753803Z","caller":"traceutil/trace.go:171","msg":"trace[620879526] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"128.697999ms","start":"2026-05-09T12:52:51.625083Z","end":"2026-05-09T12:52:51.753781Z","steps":["trace[620879526] 'process raft request' (duration: 108.118823ms)","trace[620879526] 'compare' (duration: 20.298213ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:52:51.753981Z","caller":"traceutil/trace.go:171","msg":"trace[2122883217] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"128.469436ms","start":"2026-05-09T12:52:51.625501Z","end":"2026-05-09T12:52:51.753971Z","steps":["trace[2122883217] 'process raft request' (duration: 128.224273ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:52:53.414387Z","caller":"traceutil/trace.go:171","msg":"trace[1587951422] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"356.400556ms","start":"2026-05-09T12:52:53.057963Z","end":"2026-05-09T12:52:53.414363Z","steps":["trace[1587951422] 'process raft request' (duration: 356.254254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:52:53.414587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:52:53.057935Z","time spent":"356.525297ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:52:53.733596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.287808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:52:53.733724Z","caller":"traceutil/trace.go:171","msg":"trace[342380248] linearizableReadLoop","detail":"{readStateIndex:1403; appliedIndex:1401; }","duration":"342.033266ms","start":"2026-05-09T12:52:53.391647Z","end":"2026-05-09T12:52:53.73368Z","steps":["trace[342380248] 'read index received' (duration: 22.572066ms)","trace[342380248] 'applied index is now lower than readState.Index' (duration: 319.45957ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:52:53.733774Z","caller":"traceutil/trace.go:171","msg":"trace[359535106] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"632.369882ms","start":"2026-05-09T12:52:53.101372Z","end":"2026-05-09T12:52:53.733742Z","steps":["trace[359535106] 'process raft request' (duration: 488.849221ms)","trace[359535106] 'compare' (duration: 143.062426ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:52:53.733891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.249538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T12:52:53.733927Z","caller":"traceutil/trace.go:171","msg":"trace[1420204077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1345; }","duration":"342.321039ms","start":"2026-05-09T12:52:53.391597Z","end":"2026-05-09T12:52:53.733918Z","steps":["trace[1420204077] 'agreement among raft nodes before linearized reading' (duration: 342.192847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:52:53.733931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:52:53.101334Z","time spent":"632.517003ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:52:53.733958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:52:53.391575Z","time spent":"342.375899ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T12:52:54.111947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.419852ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-09T12:52:54.112061Z","caller":"traceutil/trace.go:171","msg":"trace[432889163] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1403; }","duration":"378.284574ms","start":"2026-05-09T12:52:53.733756Z","end":"2026-05-09T12:52:54.11204Z","steps":["trace[432889163] 'read index received' (duration: 71.754661ms)","trace[432889163] 'applied index is now lower than readState.Index' (duration: 306.528593ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:52:54.112168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.089929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:52:54.112203Z","caller":"traceutil/trace.go:171","msg":"trace[587511041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1345; }","duration":"416.15751ms","start":"2026-05-09T12:52:53.696036Z","end":"2026-05-09T12:52:54.112193Z","steps":["trace[587511041] 'agreement among raft nodes before linearized reading' (duration: 416.086259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:52:54.112237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:52:53.696022Z","time spent":"416.20766ms","remote":"127.0.0.1:48892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T12:52:54.112595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.329194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T12:52:54.112735Z","caller":"traceutil/trace.go:171","msg":"trace[1256107176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1345; }","duration":"487.535457ms","start":"2026-05-09T12:52:53.625135Z","end":"2026-05-09T12:52:54.112671Z","steps":["trace[1256107176] 'agreement among raft nodes before linearized reading' (duration: 487.000751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:52:54.112987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:52:53.625115Z","time spent":"487.854991ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T12:52:54.612829Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142199546343235271,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T12:52:54.66662Z","caller":"traceutil/trace.go:171","msg":"trace[574926935] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"928.358058ms","start":"2026-05-09T12:52:53.738236Z","end":"2026-05-09T12:52:54.666595Z","steps":["trace[574926935] 'process raft request' (duration: 928.191916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:52:54.666859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:52:53.738209Z","time spent":"928.559699ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:52:54.706861Z","caller":"traceutil/trace.go:171","msg":"trace[1643070978] linearizableReadLoop","detail":"{readStateIndex:1408; appliedIndex:1404; }","duration":"594.75002ms","start":"2026-05-09T12:52:54.112087Z","end":"2026-05-09T12:52:54.706837Z","steps":["trace[1643070978] 'read index received' (duration: 554.290698ms)","trace[1643070978] 'applied index is now lower than readState.Index' (duration: 40.458322ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:52:54.706925Z","caller":"traceutil/trace.go:171","msg":"trace[2044406865] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"380.571804ms","start":"2026-05-09T12:52:54.326328Z","end":"2026-05-09T12:52:54.7069Z","steps":["trace[2044406865] 'process raft request' (duration: 380.456093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:52:54.707077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.239234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T12:52:54.707143Z","caller":"traceutil/trace.go:171","msg":"trace[21680828] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1349; }","duration":"656.354304ms","start":"2026-05-09T12:52:54.050769Z","end":"2026-05-09T12:52:54.707124Z","steps":["trace[21680828] 'agreement among raft nodes before linearized reading' (duration: 656.240313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:52:54.7072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:52:54.050746Z","time spent":"656.436516ms","remote":"127.0.0.1:36418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-05-09T12:52:54.707212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:52:54.326304Z","time spent":"380.769957ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:52:54.707307Z","caller":"traceutil/trace.go:171","msg":"trace[1352429722] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"590.942271ms","start":"2026-05-09T12:52:54.116328Z","end":"2026-05-09T12:52:54.70727Z","steps":["trace[1352429722] 'process raft request' (duration: 590.379285ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:52:54.707332Z","caller":"traceutil/trace.go:171","msg":"trace[1367362530] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"737.515723ms","start":"2026-05-09T12:52:53.96977Z","end":"2026-05-09T12:52:54.707285Z","steps":["trace[1367362530] 'process raft request' (duration: 736.816625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:52:54.707416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:52:54.116318Z","time spent":"591.045802ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:52:54.707523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:52:53.96975Z","time spent":"737.665004ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:52:54.707629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.386036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T12:52:54.70768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.877422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-09T12:52:54.707765Z","caller":"traceutil/trace.go:171","msg":"trace[2144423444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1349; }","duration":"949.000614ms","start":"2026-05-09T12:52:53.758751Z","end":"2026-05-09T12:52:54.707752Z","steps":["trace[2144423444] 'agreement among raft nodes before linearized reading' (duration: 948.542889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:52:54.707856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:52:53.758735Z","time spent":"949.085364ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-09T12:52:54.707686Z","caller":"traceutil/trace.go:171","msg":"trace[634306875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1349; }","duration":"594.464497ms","start":"2026-05-09T12:52:54.113207Z","end":"2026-05-09T12:52:54.707672Z","steps":["trace[634306875] 'agreement among raft nodes before linearized reading' (duration: 594.012222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:52:54.707976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:52:54.113196Z","time spent":"594.77044ms","remote":"127.0.0.1:48894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T12:52:54.708101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.379047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-09T12:52:54.708252Z","caller":"traceutil/trace.go:171","msg":"trace[2068468948] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1349; }","duration":"950.599769ms","start":"2026-05-09T12:52:53.757622Z","end":"2026-05-09T12:52:54.708222Z","steps":["trace[2068468948] 'agreement among raft nodes before linearized reading' (duration: 950.140485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:52:54.708365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:52:53.757601Z","time spent":"950.70644ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-09T12:52:54.839304Z","caller":"traceutil/trace.go:171","msg":"trace[330638325] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"126.282739ms","start":"2026-05-09T12:52:54.712985Z","end":"2026-05-09T12:52:54.839267Z","steps":["trace[330638325] 'process raft request' (duration: 46.340014ms)","trace[330638325] 'compare' (duration: 79.798684ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:52:54.839474Z","caller":"traceutil/trace.go:171","msg":"trace[1678659646] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1408; }","duration":"123.819953ms","start":"2026-05-09T12:52:54.715631Z","end":"2026-05-09T12:52:54.839451Z","steps":["trace[1678659646] 'read index received' (duration: 43.815437ms)","trace[1678659646] 'applied index is now lower than readState.Index' (duration: 80.003516ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:52:54.839599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.942424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:52:54.839611Z","caller":"traceutil/trace.go:171","msg":"trace[610846515] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"126.44673ms","start":"2026-05-09T12:52:54.713153Z","end":"2026-05-09T12:52:54.839599Z","steps":["trace[610846515] 'process raft request' (duration: 126.170028ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:52:54.839628Z","caller":"traceutil/trace.go:171","msg":"trace[1159190309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1351; }","duration":"124.039346ms","start":"2026-05-09T12:52:54.715581Z","end":"2026-05-09T12:52:54.83962Z","steps":["trace[1159190309] 'agreement among raft nodes before linearized reading' (duration: 123.965995ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:52:55.647113Z","caller":"traceutil/trace.go:171","msg":"trace[559248146] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"224.480902ms","start":"2026-05-09T12:52:55.422606Z","end":"2026-05-09T12:52:55.647087Z","steps":["trace[559248146] 'process raft request' (duration: 224.33349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:52:56.000976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.147611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:52:56.001076Z","caller":"traceutil/trace.go:171","msg":"trace[2043915075] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1352; }","duration":"159.321492ms","start":"2026-05-09T12:52:55.841726Z","end":"2026-05-09T12:52:56.001048Z","steps":["trace[2043915075] 'count revisions from in-memory index tree' (duration: 159.055059ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:52:56.904427Z","caller":"traceutil/trace.go:171","msg":"trace[1420955064] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"190.626756ms","start":"2026-05-09T12:52:56.713761Z","end":"2026-05-09T12:52:56.904387Z","steps":["trace[1420955064] 'process raft request' (duration: 113.852487ms)","trace[1420955064] 'compare' (duration: 76.521357ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:52:56.904889Z","caller":"traceutil/trace.go:171","msg":"trace[2128737095] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"189.530614ms","start":"2026-05-09T12:52:56.715316Z","end":"2026-05-09T12:52:56.904847Z","steps":["trace[2128737095] 'process raft request' (duration: 189.015639ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:53:03.153898Z","caller":"traceutil/trace.go:171","msg":"trace[589465750] linearizableReadLoop","detail":"{readStateIndex:1439; appliedIndex:1438; }","duration":"193.194409ms","start":"2026-05-09T12:53:02.960677Z","end":"2026-05-09T12:53:03.153871Z","steps":["trace[589465750] 'read index received' (duration: 149.696409ms)","trace[589465750] 'applied index is now lower than readState.Index' (duration: 43.49701ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:53:03.15404Z","caller":"traceutil/trace.go:171","msg":"trace[785235112] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"199.613185ms","start":"2026-05-09T12:53:02.954413Z","end":"2026-05-09T12:53:03.154026Z","steps":["trace[785235112] 'process raft request' (duration: 156.048775ms)","trace[785235112] 'compare' (duration: 43.225597ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:53:03.154233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.842525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T12:53:03.154309Z","caller":"traceutil/trace.go:171","msg":"trace[429353260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1379; }","duration":"161.986346ms","start":"2026-05-09T12:53:02.992305Z","end":"2026-05-09T12:53:03.154291Z","steps":["trace[429353260] 'agreement among raft nodes before linearized reading' (duration: 161.738984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:53:03.154335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.648494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-09T12:53:03.154424Z","caller":"traceutil/trace.go:171","msg":"trace[1384315931] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1379; }","duration":"193.801985ms","start":"2026-05-09T12:53:02.960601Z","end":"2026-05-09T12:53:03.154403Z","steps":["trace[1384315931] 'agreement among raft nodes before linearized reading' (duration: 193.577903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:53:03.15462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.982694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-09T12:53:03.154661Z","caller":"traceutil/trace.go:171","msg":"trace[159385767] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1379; }","duration":"158.070785ms","start":"2026-05-09T12:53:02.996579Z","end":"2026-05-09T12:53:03.15465Z","steps":["trace[159385767] 'agreement among raft nodes before linearized reading' (duration: 157.910593ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:53:03.495292Z","caller":"traceutil/trace.go:171","msg":"trace[1292362466] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"336.704083ms","start":"2026-05-09T12:53:03.15856Z","end":"2026-05-09T12:53:03.495264Z","steps":["trace[1292362466] 'process raft request' (duration: 296.209353ms)","trace[1292362466] 'compare' (duration: 40.337148ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:53:03.495447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:53:03.158531Z","time spent":"336.840004ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:53:03.528687Z","caller":"traceutil/trace.go:171","msg":"trace[127367439] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"370.069868ms","start":"2026-05-09T12:53:03.158592Z","end":"2026-05-09T12:53:03.528662Z","steps":["trace[127367439] 'process raft request' (duration: 369.887387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:53:03.528851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:53:03.158567Z","time spent":"370.195709ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:53:03.529014Z","caller":"traceutil/trace.go:171","msg":"trace[1927696749] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"369.601794ms","start":"2026-05-09T12:53:03.159376Z","end":"2026-05-09T12:53:03.528978Z","steps":["trace[1927696749] 'process raft request' (duration: 369.24138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:53:03.529196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:53:03.159364Z","time spent":"369.727085ms","remote":"127.0.0.1:49190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:53:04.298925Z","caller":"traceutil/trace.go:171","msg":"trace[1630903412] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"177.590636ms","start":"2026-05-09T12:53:04.121307Z","end":"2026-05-09T12:53:04.298897Z","steps":["trace[1630903412] 'process raft request' (duration: 177.350093ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:53:04.990048Z","caller":"traceutil/trace.go:171","msg":"trace[179603918] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"224.175956ms","start":"2026-05-09T12:53:04.76584Z","end":"2026-05-09T12:53:04.990016Z","steps":["trace[179603918] 'process raft request' (duration: 223.749462ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:53:05.06389Z","caller":"traceutil/trace.go:171","msg":"trace[2060330407] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"234.304652ms","start":"2026-05-09T12:53:04.829555Z","end":"2026-05-09T12:53:05.06386Z","steps":["trace[2060330407] 'process raft request' (duration: 234.070969ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:53:05.064234Z","caller":"traceutil/trace.go:171","msg":"trace[1499235081] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"179.168921ms","start":"2026-05-09T12:53:04.885041Z","end":"2026-05-09T12:53:05.06421Z","steps":["trace[1499235081] 'process raft request' (duration: 178.725677ms)"],"step_count":1}