{"level":"warn","ts":"2026-05-24T07:15:00.886114Z","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-24T07:15:00.886225Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-24T07:15:00.886282Z","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-24T07:15:00.88633Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-05-24T07:15:00.886346Z","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-24T07:15:00.886938Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-05-24T07:15:00.887021Z","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.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-24T07:15:00.889007Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.801352ms"} {"level":"info","ts":"2026-05-24T07:15:00.89684Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-05-24T07:15:00.896906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-05-24T07:15:00.896933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-05-24T07:15:00.896947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-24T07:15:00.897024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-05-24T07:15:00.897067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-05-24T07:15:00.904384Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-24T07:15:00.906755Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-24T07:15:00.90919Z","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-24T07:15:00.912557Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-24T07:15:00.913503Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-24T07:15:00.913782Z","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-24T07:15:00.913903Z","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-24T07:15:00.913922Z","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-24T07:15:00.914638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-05-24T07:15:00.914909Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-05-24T07:15:00.917602Z","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-24T07:15:00.917825Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-24T07:15:00.917894Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-24T07:15:00.918559Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-05-24T07:15:00.918605Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-05-24T07:15:01.697863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-05-24T07:15:01.697932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-05-24T07:15:01.69799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-05-24T07:15:01.698015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-05-24T07:15:01.698026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-05-24T07:15:01.698042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-05-24T07:15:01.698062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-05-24T07:15:01.951179Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-05-24T07:15:01.951439Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-24T07:15:01.951564Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-24T07:15:01.951709Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-24T07:15:01.951798Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-24T07:15:01.951725Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-24T07:15:01.954667Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-05-24T07:15:01.955272Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-24T07:15:02.163195Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-05-24T07:15:02.163337Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-24T07:15:02.163378Z","caller":"traceutil/trace.go:171","msg":"trace[89176756] linearizableReadLoop","detail":"{readStateIndex:4; appliedIndex:3; }","duration":"168.908035ms","start":"2026-05-24T07:15:01.994456Z","end":"2026-05-24T07:15:02.163364Z","steps":["trace[89176756] 'read index received' (duration: 168.712811ms)","trace[89176756] 'applied index is now lower than readState.Index' (duration: 194.224µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:15:02.163438Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-24T07:15:02.163542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.059689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-24T07:15:02.16357Z","caller":"traceutil/trace.go:171","msg":"trace[1915595066] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"169.10968ms","start":"2026-05-24T07:15:01.994451Z","end":"2026-05-24T07:15:02.16356Z","steps":["trace[1915595066] 'agreement among raft nodes before linearized reading' (duration: 168.999977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:15:02.16386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.341555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-24T07:15:02.163881Z","caller":"traceutil/trace.go:171","msg":"trace[1845749428] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"169.382856ms","start":"2026-05-24T07:15:01.994492Z","end":"2026-05-24T07:15:02.163875Z","steps":["trace[1845749428] 'agreement among raft nodes before linearized reading' (duration: 169.349665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:15:02.164466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.342274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-24T07:15:02.164486Z","caller":"traceutil/trace.go:171","msg":"trace[406869557] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"108.382214ms","start":"2026-05-24T07:15:02.056098Z","end":"2026-05-24T07:15:02.164481Z","steps":["trace[406869557] 'agreement among raft nodes before linearized reading' (duration: 108.346763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:15:02.164577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.580379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-24T07:15:02.164594Z","caller":"traceutil/trace.go:171","msg":"trace[1214175233] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"108.61219ms","start":"2026-05-24T07:15:02.055977Z","end":"2026-05-24T07:15:02.164589Z","steps":["trace[1214175233] 'agreement among raft nodes before linearized reading' (duration: 108.582979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:15:02.164676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.649851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-24T07:15:02.164692Z","caller":"traceutil/trace.go:171","msg":"trace[222440555] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"117.679491ms","start":"2026-05-24T07:15:02.047008Z","end":"2026-05-24T07:15:02.164687Z","steps":["trace[222440555] 'agreement among raft nodes before linearized reading' (duration: 117.65ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:15:02.164796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.803385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-24T07:15:02.164813Z","caller":"traceutil/trace.go:171","msg":"trace[1977402111] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"117.849405ms","start":"2026-05-24T07:15:02.046959Z","end":"2026-05-24T07:15:02.164808Z","steps":["trace[1977402111] 'agreement among raft nodes before linearized reading' (duration: 117.802464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:15:02.164892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.555541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-24T07:15:02.164908Z","caller":"traceutil/trace.go:171","msg":"trace[1894610295] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"130.601542ms","start":"2026-05-24T07:15:02.034302Z","end":"2026-05-24T07:15:02.164903Z","steps":["trace[1894610295] 'agreement among raft nodes before linearized reading' (duration: 130.575752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:15:36.212456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.832044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:15:36.212644Z","caller":"traceutil/trace.go:171","msg":"trace[627066386] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:509; }","duration":"268.063606ms","start":"2026-05-24T07:15:35.944562Z","end":"2026-05-24T07:15:36.212626Z","steps":["trace[627066386] 'read index received' (duration: 183.162861ms)","trace[627066386] 'applied index is now lower than readState.Index' (duration: 84.899515ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:15:36.212765Z","caller":"traceutil/trace.go:171","msg":"trace[1224600203] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"478.372066ms","start":"2026-05-24T07:15:35.73436Z","end":"2026-05-24T07:15:36.212732Z","steps":["trace[1224600203] 'process raft request' (duration: 188.641101ms)","trace[1224600203] 'compare' (duration: 288.66619ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:15:36.212802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.23031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-24T07:15:36.212869Z","caller":"traceutil/trace.go:171","msg":"trace[1989513613] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:493; }","duration":"268.333932ms","start":"2026-05-24T07:15:35.944524Z","end":"2026-05-24T07:15:36.212858Z","steps":["trace[1989513613] 'agreement among raft nodes before linearized reading' (duration: 268.157088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:15:36.212906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:15:35.734341Z","time spent":"478.503669ms","remote":"127.0.0.1:37632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2364,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-24T07:15:36.212891Z","caller":"traceutil/trace.go:171","msg":"trace[482007475] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"475.047857ms","start":"2026-05-24T07:15:35.737777Z","end":"2026-05-24T07:15:36.212825Z","steps":["trace[482007475] 'process raft request' (duration: 474.795462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:15:36.213076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:15:35.737759Z","time spent":"475.246423ms","remote":"127.0.0.1:37632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1639,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-24T07:15:38.24111Z","caller":"traceutil/trace.go:171","msg":"trace[500806205] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"298.235486ms","start":"2026-05-24T07:15:37.942853Z","end":"2026-05-24T07:15:38.241089Z","steps":["trace[500806205] 'process raft request' (duration: 298.123583ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:38.246788Z","caller":"traceutil/trace.go:171","msg":"trace[745875228] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"303.015217ms","start":"2026-05-24T07:15:37.943749Z","end":"2026-05-24T07:15:38.246764Z","steps":["trace[745875228] 'process raft request' (duration: 302.878854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:15:38.247011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:15:37.943711Z","time spent":"303.242512ms","remote":"127.0.0.1:37348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-24T07:15:38.24712Z","caller":"traceutil/trace.go:171","msg":"trace[1998962854] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"279.29243ms","start":"2026-05-24T07:15:37.967809Z","end":"2026-05-24T07:15:38.247101Z","steps":["trace[1998962854] 'process raft request' (duration: 279.101615ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:38.247165Z","caller":"traceutil/trace.go:171","msg":"trace[1055663205] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"302.180298ms","start":"2026-05-24T07:15:37.944979Z","end":"2026-05-24T07:15:38.24716Z","steps":["trace[1055663205] 'process raft request' (duration: 301.732798ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:38.247144Z","caller":"traceutil/trace.go:171","msg":"trace[1491461627] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"291.435236ms","start":"2026-05-24T07:15:37.955669Z","end":"2026-05-24T07:15:38.247105Z","steps":["trace[1491461627] 'process raft request' (duration: 291.148389ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:38.247181Z","caller":"traceutil/trace.go:171","msg":"trace[1900216570] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"291.439936ms","start":"2026-05-24T07:15:37.955701Z","end":"2026-05-24T07:15:38.247141Z","steps":["trace[1900216570] 'process raft request' (duration: 291.143229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:15:38.24721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:15:37.944961Z","time spent":"302.223859ms","remote":"127.0.0.1:37348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-24T07:15:38.247134Z","caller":"traceutil/trace.go:171","msg":"trace[1986846568] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"291.808194ms","start":"2026-05-24T07:15:37.955304Z","end":"2026-05-24T07:15:38.247113Z","steps":["trace[1986846568] 'process raft request' (duration: 291.436735ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:38.247238Z","caller":"traceutil/trace.go:171","msg":"trace[846719940] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"282.735411ms","start":"2026-05-24T07:15:37.964464Z","end":"2026-05-24T07:15:38.247199Z","steps":["trace[846719940] 'process raft request' (duration: 282.406813ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:38.247271Z","caller":"traceutil/trace.go:171","msg":"trace[1114290074] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"249.551062ms","start":"2026-05-24T07:15:37.997713Z","end":"2026-05-24T07:15:38.247264Z","steps":["trace[1114290074] 'process raft request' (duration: 249.230384ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:38.247281Z","caller":"traceutil/trace.go:171","msg":"trace[606310377] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"133.173308ms","start":"2026-05-24T07:15:38.114094Z","end":"2026-05-24T07:15:38.247268Z","steps":["trace[606310377] 'process raft request' (duration: 132.890791ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:38.247313Z","caller":"traceutil/trace.go:171","msg":"trace[1033536463] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"291.940738ms","start":"2026-05-24T07:15:37.95536Z","end":"2026-05-24T07:15:38.247301Z","steps":["trace[1033536463] 'process raft request' (duration: 291.410575ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:38.247366Z","caller":"traceutil/trace.go:171","msg":"trace[728790284] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"291.886106ms","start":"2026-05-24T07:15:37.955427Z","end":"2026-05-24T07:15:38.247313Z","steps":["trace[728790284] 'process raft request' (duration: 291.366634ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:38.53943Z","caller":"traceutil/trace.go:171","msg":"trace[1334116708] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"229.712796ms","start":"2026-05-24T07:15:38.309694Z","end":"2026-05-24T07:15:38.539407Z","steps":["trace[1334116708] 'process raft request' (duration: 229.665175ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:38.53982Z","caller":"traceutil/trace.go:171","msg":"trace[1542472565] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"253.159077ms","start":"2026-05-24T07:15:38.286641Z","end":"2026-05-24T07:15:38.5398Z","steps":["trace[1542472565] 'process raft request' (duration: 160.975412ms)","trace[1542472565] 'compare' (duration: 91.53691ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:15:38.540072Z","caller":"traceutil/trace.go:171","msg":"trace[831712234] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"166.830549ms","start":"2026-05-24T07:15:38.373228Z","end":"2026-05-24T07:15:38.540058Z","steps":["trace[831712234] 'process raft request' (duration: 166.593443ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:38.540187Z","caller":"traceutil/trace.go:171","msg":"trace[1192792964] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"155.921403ms","start":"2026-05-24T07:15:38.384254Z","end":"2026-05-24T07:15:38.540176Z","steps":["trace[1192792964] 'process raft request' (duration: 155.706988ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:38.540283Z","caller":"traceutil/trace.go:171","msg":"trace[1951688548] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"155.907242ms","start":"2026-05-24T07:15:38.384367Z","end":"2026-05-24T07:15:38.540274Z","steps":["trace[1951688548] 'process raft request' (duration: 155.696147ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:38.540392Z","caller":"traceutil/trace.go:171","msg":"trace[1013766514] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"111.807496ms","start":"2026-05-24T07:15:38.428567Z","end":"2026-05-24T07:15:38.540374Z","steps":["trace[1013766514] 'process raft request' (duration: 111.700974ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:38.540461Z","caller":"traceutil/trace.go:171","msg":"trace[71611418] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"150.925875ms","start":"2026-05-24T07:15:38.389444Z","end":"2026-05-24T07:15:38.54037Z","steps":["trace[71611418] 'process raft request' (duration: 150.735571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:15:39.012309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.538092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:15:39.012427Z","caller":"traceutil/trace.go:171","msg":"trace[1481895759] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"245.218659ms","start":"2026-05-24T07:15:38.76719Z","end":"2026-05-24T07:15:39.012409Z","steps":["trace[1481895759] 'process raft request' (duration: 101.359191ms)","trace[1481895759] 'compare' (duration: 143.418048ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:15:39.035925Z","caller":"traceutil/trace.go:171","msg":"trace[2038972690] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"260.675682ms","start":"2026-05-24T07:15:38.775228Z","end":"2026-05-24T07:15:39.035904Z","steps":["trace[2038972690] 'process raft request' (duration: 260.418126ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:39.03625Z","caller":"traceutil/trace.go:171","msg":"trace[2046909538] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"244.007681ms","start":"2026-05-24T07:15:38.79222Z","end":"2026-05-24T07:15:39.036227Z","steps":["trace[2046909538] 'process raft request' (duration: 243.631722ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:39.036216Z","caller":"traceutil/trace.go:171","msg":"trace[1435609491] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:588; }","duration":"194.779335ms","start":"2026-05-24T07:15:38.841405Z","end":"2026-05-24T07:15:39.036184Z","steps":["trace[1435609491] 'read index received' (duration: 27.161578ms)","trace[1435609491] 'applied index is now lower than readState.Index' (duration: 167.615367ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:15:39.037545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.126746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-24T07:15:39.037658Z","caller":"traceutil/trace.go:171","msg":"trace[1928968065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:572; }","duration":"196.247539ms","start":"2026-05-24T07:15:38.841394Z","end":"2026-05-24T07:15:39.037641Z","steps":["trace[1928968065] 'agreement among raft nodes before linearized reading' (duration: 194.888877ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:39.261283Z","caller":"traceutil/trace.go:171","msg":"trace[529285530] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"220.457568ms","start":"2026-05-24T07:15:39.040798Z","end":"2026-05-24T07:15:39.261256Z","steps":["trace[529285530] 'process raft request' (duration: 148.98293ms)","trace[529285530] 'compare' (duration: 71.200202ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:15:39.261729Z","caller":"traceutil/trace.go:171","msg":"trace[1843935936] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"192.069641ms","start":"2026-05-24T07:15:39.069645Z","end":"2026-05-24T07:15:39.261715Z","steps":["trace[1843935936] 'process raft request' (duration: 191.861476ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:39.261713Z","caller":"traceutil/trace.go:171","msg":"trace[908478762] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"167.451693ms","start":"2026-05-24T07:15:39.094234Z","end":"2026-05-24T07:15:39.261685Z","steps":["trace[908478762] 'process raft request' (duration: 167.369841ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:39.261821Z","caller":"traceutil/trace.go:171","msg":"trace[1623254328] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"197.510758ms","start":"2026-05-24T07:15:39.064302Z","end":"2026-05-24T07:15:39.261813Z","steps":["trace[1623254328] 'process raft request' (duration: 196.858633ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:39.261808Z","caller":"traceutil/trace.go:171","msg":"trace[507216898] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:592; }","duration":"172.90164ms","start":"2026-05-24T07:15:39.088873Z","end":"2026-05-24T07:15:39.261775Z","steps":["trace[507216898] 'read index received' (duration: 100.92016ms)","trace[507216898] 'applied index is now lower than readState.Index' (duration: 71.97519ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:15:39.262083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.181978ms","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-24T07:15:39.262124Z","caller":"traceutil/trace.go:171","msg":"trace[790153746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:577; }","duration":"173.244859ms","start":"2026-05-24T07:15:39.088869Z","end":"2026-05-24T07:15:39.262113Z","steps":["trace[790153746] 'agreement among raft nodes before linearized reading' (duration: 173.024494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:15:39.599944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.498063ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:15:39.602331Z","caller":"traceutil/trace.go:171","msg":"trace[1634573786] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"281.84517ms","start":"2026-05-24T07:15:39.320447Z","end":"2026-05-24T07:15:39.602293Z","steps":["trace[1634573786] 'process raft request' (duration: 120.904069ms)","trace[1634573786] 'compare' (duration: 157.986412ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:15:39.60482Z","caller":"traceutil/trace.go:171","msg":"trace[1730905006] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"282.899525ms","start":"2026-05-24T07:15:39.321854Z","end":"2026-05-24T07:15:39.604754Z","steps":["trace[1730905006] 'process raft request' (duration: 282.645219ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:39.805958Z","caller":"traceutil/trace.go:171","msg":"trace[1441303681] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"211.092748ms","start":"2026-05-24T07:15:39.594845Z","end":"2026-05-24T07:15:39.805937Z","steps":["trace[1441303681] 'read index received' (duration: 10.589739ms)","trace[1441303681] 'applied index is now lower than readState.Index' (duration: 200.499789ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:15:39.805975Z","caller":"traceutil/trace.go:171","msg":"trace[729226064] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"272.943671ms","start":"2026-05-24T07:15:39.532962Z","end":"2026-05-24T07:15:39.805905Z","steps":["trace[729226064] 'process raft request' (duration: 215.282787ms)","trace[729226064] 'compare' (duration: 57.033309ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:15:39.805998Z","caller":"traceutil/trace.go:171","msg":"trace[1391766913] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"137.700175ms","start":"2026-05-24T07:15:39.668278Z","end":"2026-05-24T07:15:39.805978Z","steps":["trace[1391766913] 'process raft request' (duration: 137.374187ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:39.805943Z","caller":"traceutil/trace.go:171","msg":"trace[1289497433] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"116.366453ms","start":"2026-05-24T07:15:39.689547Z","end":"2026-05-24T07:15:39.805914Z","steps":["trace[1289497433] 'process raft request' (duration: 116.25155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:15:39.806131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.277542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-24T07:15:39.806157Z","caller":"traceutil/trace.go:171","msg":"trace[2100710100] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:583; }","duration":"211.344794ms","start":"2026-05-24T07:15:39.594804Z","end":"2026-05-24T07:15:39.806149Z","steps":["trace[2100710100] 'agreement among raft nodes before linearized reading' (duration: 211.20973ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:44.959939Z","caller":"traceutil/trace.go:171","msg":"trace[964838423] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"106.8465ms","start":"2026-05-24T07:15:44.853066Z","end":"2026-05-24T07:15:44.959913Z","steps":["trace[964838423] 'read index received' (duration: 104.452804ms)","trace[964838423] 'applied index is now lower than readState.Index' (duration: 2.392646ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:15:44.960175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.080135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-24T07:15:44.960239Z","caller":"traceutil/trace.go:171","msg":"trace[418967802] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:623; }","duration":"107.203518ms","start":"2026-05-24T07:15:44.85302Z","end":"2026-05-24T07:15:44.960223Z","steps":["trace[418967802] 'agreement among raft nodes before linearized reading' (duration: 107.063355ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:44.960064Z","caller":"traceutil/trace.go:171","msg":"trace[19914593] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"108.295964ms","start":"2026-05-24T07:15:44.851702Z","end":"2026-05-24T07:15:44.959998Z","steps":["trace[19914593] 'process raft request' (duration: 106.658575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:15:45.337179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.27259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:15:45.337556Z","caller":"traceutil/trace.go:171","msg":"trace[37028895] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"360.417276ms","start":"2026-05-24T07:15:44.977121Z","end":"2026-05-24T07:15:45.337538Z","steps":["trace[37028895] 'process raft request' (duration: 360.272002ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:45.337645Z","caller":"traceutil/trace.go:171","msg":"trace[2009045108] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"369.99004ms","start":"2026-05-24T07:15:44.967604Z","end":"2026-05-24T07:15:45.337594Z","steps":["trace[2009045108] 'process raft request' (duration: 44.225359ms)","trace[2009045108] 'compare' (duration: 325.139036ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:15:45.337708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:15:44.977098Z","time spent":"360.56843ms","remote":"127.0.0.1:37490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:15:45.33783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:15:44.96759Z","time spent":"370.146164ms","remote":"127.0.0.1:37334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:15:45.344558Z","caller":"traceutil/trace.go:171","msg":"trace[765438148] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"327.118273ms","start":"2026-05-24T07:15:45.017418Z","end":"2026-05-24T07:15:45.344536Z","steps":["trace[765438148] 'process raft request' (duration: 326.97612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:15:45.344857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:15:45.017394Z","time spent":"327.289078ms","remote":"127.0.0.1:37516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-24T07:15:46.338134Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899449932195434,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-24T07:15:46.839133Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899449932195434,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-24T07:15:47.040485Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.290386689s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-24T07:15:47.057768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.511735908s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:15:47.057829Z","caller":"traceutil/trace.go:171","msg":"trace[1599752741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"1.51183961s","start":"2026-05-24T07:15:45.545971Z","end":"2026-05-24T07:15:47.057811Z","steps":["trace[1599752741] 'range keys from in-memory index tree' (duration: 1.511623785s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:15:47.057866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:15:45.545951Z","time spent":"1.511907012s","remote":"127.0.0.1:37316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-24T07:15:47.058438Z","caller":"traceutil/trace.go:171","msg":"trace[483380226] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"1.220981679s","start":"2026-05-24T07:15:45.837437Z","end":"2026-05-24T07:15:47.058419Z","steps":["trace[483380226] 'read index received' (duration: 1.203178321s)","trace[483380226] 'applied index is now lower than readState.Index' (duration: 17.802168ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:15:47.058566Z","caller":"traceutil/trace.go:171","msg":"trace[1657306764] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"1.309266362s","start":"2026-05-24T07:15:45.749284Z","end":"2026-05-24T07:15:47.058551Z","steps":["trace[1657306764] 'process raft request' (duration: 1.291577377s)","trace[1657306764] 'compare' (duration: 16.709942ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:15:47.05867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:15:45.749267Z","time spent":"1.309332874s","remote":"127.0.0.1:37390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:15:47.058872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.428319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-24T07:15:47.058935Z","caller":"traceutil/trace.go:171","msg":"trace[441534175] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"682.527892ms","start":"2026-05-24T07:15:46.376387Z","end":"2026-05-24T07:15:47.058915Z","steps":["trace[441534175] 'agreement among raft nodes before linearized reading' (duration: 682.379898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:15:47.058929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.221482611s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-24T07:15:47.059088Z","caller":"traceutil/trace.go:171","msg":"trace[1464990361] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"1.221675515s","start":"2026-05-24T07:15:45.837384Z","end":"2026-05-24T07:15:47.05906Z","steps":["trace[1464990361] 'agreement among raft nodes before linearized reading' (duration: 1.221395649s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:15:47.059152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:15:45.837365Z","time spent":"1.221769047s","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-24T07:15:47.058965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:15:46.376369Z","time spent":"682.590493ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-24T07:15:47.668135Z","caller":"traceutil/trace.go:171","msg":"trace[1406704471] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"117.1594ms","start":"2026-05-24T07:15:47.550948Z","end":"2026-05-24T07:15:47.668107Z","steps":["trace[1406704471] 'process raft request' (duration: 116.491165ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:47.71939Z","caller":"traceutil/trace.go:171","msg":"trace[805608825] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"130.413893ms","start":"2026-05-24T07:15:47.588945Z","end":"2026-05-24T07:15:47.719359Z","steps":["trace[805608825] 'process raft request' (duration: 127.637808ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:15:48.46835Z","caller":"traceutil/trace.go:171","msg":"trace[1823485763] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"198.773259ms","start":"2026-05-24T07:15:48.269551Z","end":"2026-05-24T07:15:48.468324Z","steps":["trace[1823485763] 'process raft request' (duration: 198.528883ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:16:04.274416Z","caller":"traceutil/trace.go:171","msg":"trace[243147510] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"383.699732ms","start":"2026-05-24T07:16:03.890691Z","end":"2026-05-24T07:16:04.274391Z","steps":["trace[243147510] 'process raft request' (duration: 383.559549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:16:04.274805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:16:03.890675Z","time spent":"383.826415ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:16:04.318746Z","caller":"traceutil/trace.go:171","msg":"trace[55913828] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"287.923893ms","start":"2026-05-24T07:16:04.030793Z","end":"2026-05-24T07:16:04.318716Z","steps":["trace[55913828] 'process raft request' (duration: 287.612946ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:16:04.319061Z","caller":"traceutil/trace.go:171","msg":"trace[831301528] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"186.965631ms","start":"2026-05-24T07:16:04.132022Z","end":"2026-05-24T07:16:04.318988Z","steps":["trace[831301528] 'process raft request' (duration: 186.640714ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:16:15.799511Z","caller":"traceutil/trace.go:171","msg":"trace[9003298] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"171.591131ms","start":"2026-05-24T07:16:15.627897Z","end":"2026-05-24T07:16:15.799488Z","steps":["trace[9003298] 'process raft request' (duration: 171.461098ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:16:17.882006Z","caller":"traceutil/trace.go:171","msg":"trace[1401684447] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"146.763497ms","start":"2026-05-24T07:16:17.735206Z","end":"2026-05-24T07:16:17.881969Z","steps":["trace[1401684447] 'process raft request' (duration: 146.557362ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:16:17.995942Z","caller":"traceutil/trace.go:171","msg":"trace[809882540] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"109.574264ms","start":"2026-05-24T07:16:17.886338Z","end":"2026-05-24T07:16:17.995913Z","steps":["trace[809882540] 'process raft request' (duration: 52.445422ms)","trace[809882540] 'compare' (duration: 56.807724ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:16:18.526872Z","caller":"traceutil/trace.go:171","msg":"trace[169442510] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"296.522945ms","start":"2026-05-24T07:16:18.230319Z","end":"2026-05-24T07:16:18.526841Z","steps":["trace[169442510] 'process raft request' (duration: 296.374531ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:16:18.535262Z","caller":"traceutil/trace.go:171","msg":"trace[403663746] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"118.680108ms","start":"2026-05-24T07:16:18.416562Z","end":"2026-05-24T07:16:18.535242Z","steps":["trace[403663746] 'read index received' (duration: 110.26114ms)","trace[403663746] 'applied index is now lower than readState.Index' (duration: 8.417798ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:16:18.535441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.865452ms","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-24T07:16:18.535474Z","caller":"traceutil/trace.go:171","msg":"trace[1683160713] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:940; }","duration":"118.958734ms","start":"2026-05-24T07:16:18.416506Z","end":"2026-05-24T07:16:18.535465Z","steps":["trace[1683160713] 'agreement among raft nodes before linearized reading' (duration: 118.821711ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:16:18.535533Z","caller":"traceutil/trace.go:171","msg":"trace[1571866990] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"226.018789ms","start":"2026-05-24T07:16:18.309425Z","end":"2026-05-24T07:16:18.535444Z","steps":["trace[1571866990] 'process raft request' (duration: 225.693071ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:16:33.616538Z","caller":"traceutil/trace.go:171","msg":"trace[1617940033] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"225.809925ms","start":"2026-05-24T07:16:33.390698Z","end":"2026-05-24T07:16:33.616508Z","steps":["trace[1617940033] 'process raft request' (duration: 225.60154ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:16:33.624178Z","caller":"traceutil/trace.go:171","msg":"trace[394534465] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"232.614835ms","start":"2026-05-24T07:16:33.391537Z","end":"2026-05-24T07:16:33.624151Z","steps":["trace[394534465] 'process raft request' (duration: 232.39408ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:16:35.736637Z","caller":"traceutil/trace.go:171","msg":"trace[1972317339] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"108.793581ms","start":"2026-05-24T07:16:35.62782Z","end":"2026-05-24T07:16:35.736614Z","steps":["trace[1972317339] 'read index received' (duration: 108.655568ms)","trace[1972317339] 'applied index is now lower than readState.Index' (duration: 137.013µs)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:16:35.736832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.998316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-24T07:16:35.736801Z","caller":"traceutil/trace.go:171","msg":"trace[857320456] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"112.246312ms","start":"2026-05-24T07:16:35.624505Z","end":"2026-05-24T07:16:35.736751Z","steps":["trace[857320456] 'process raft request' (duration: 111.967626ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:16:35.736878Z","caller":"traceutil/trace.go:171","msg":"trace[566281610] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1004; }","duration":"109.057177ms","start":"2026-05-24T07:16:35.627808Z","end":"2026-05-24T07:16:35.736865Z","steps":["trace[566281610] 'agreement among raft nodes before linearized reading' (duration: 108.898253ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:16:35.75588Z","caller":"traceutil/trace.go:171","msg":"trace[671630770] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"126.643081ms","start":"2026-05-24T07:16:35.629218Z","end":"2026-05-24T07:16:35.755861Z","steps":["trace[671630770] 'process raft request' (duration: 126.541929ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:16:36.748213Z","caller":"traceutil/trace.go:171","msg":"trace[598848992] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"116.062142ms","start":"2026-05-24T07:16:36.632128Z","end":"2026-05-24T07:16:36.74819Z","steps":["trace[598848992] 'process raft request' (duration: 115.922879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:16:39.297066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.436879ms","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-24T07:16:39.297161Z","caller":"traceutil/trace.go:171","msg":"trace[308151856] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1019; }","duration":"545.568462ms","start":"2026-05-24T07:16:38.751571Z","end":"2026-05-24T07:16:39.29714Z","steps":["trace[308151856] 'range keys from in-memory index tree' (duration: 545.230754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:16:39.297213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:16:38.751556Z","time spent":"545.644654ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-24T07:16:39.593736Z","caller":"traceutil/trace.go:171","msg":"trace[953796232] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"292.589607ms","start":"2026-05-24T07:16:39.301091Z","end":"2026-05-24T07:16:39.593681Z","steps":["trace[953796232] 'process raft request' (duration: 292.320761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:16:39.997192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.441701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:16:39.99732Z","caller":"traceutil/trace.go:171","msg":"trace[693563149] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1020; }","duration":"393.595075ms","start":"2026-05-24T07:16:39.603686Z","end":"2026-05-24T07:16:39.997281Z","steps":["trace[693563149] 'count revisions from in-memory index tree' (duration: 393.310058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:16:39.997365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:16:39.603667Z","time spent":"393.677996ms","remote":"127.0.0.1:37442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-05-24T07:16:39.997348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.471745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-24T07:16:39.997415Z","caller":"traceutil/trace.go:171","msg":"trace[439934259] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1020; }","duration":"247.591188ms","start":"2026-05-24T07:16:39.749808Z","end":"2026-05-24T07:16:39.997399Z","steps":["trace[439934259] 'range keys from in-memory index tree' (duration: 247.048575ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:16:39.999322Z","caller":"traceutil/trace.go:171","msg":"trace[971852752] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1054; }","duration":"227.979177ms","start":"2026-05-24T07:16:39.771331Z","end":"2026-05-24T07:16:39.99931Z","steps":["trace[971852752] 'read index received' (duration: 227.879444ms)","trace[971852752] 'applied index is now lower than readState.Index' (duration: 98.823µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:16:39.999393Z","caller":"traceutil/trace.go:171","msg":"trace[1867268013] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"232.632625ms","start":"2026-05-24T07:16:39.766753Z","end":"2026-05-24T07:16:39.999386Z","steps":["trace[1867268013] 'process raft request' (duration: 232.465171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:16:39.999499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.165931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-24T07:16:39.999523Z","caller":"traceutil/trace.go:171","msg":"trace[357349567] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1021; }","duration":"228.214531ms","start":"2026-05-24T07:16:39.771304Z","end":"2026-05-24T07:16:39.999518Z","steps":["trace[357349567] 'agreement among raft nodes before linearized reading' (duration: 228.15016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:16:39.999596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.788192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-24T07:16:39.999619Z","caller":"traceutil/trace.go:171","msg":"trace[855528485] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1021; }","duration":"131.841484ms","start":"2026-05-24T07:16:39.867772Z","end":"2026-05-24T07:16:39.999613Z","steps":["trace[855528485] 'agreement among raft nodes before linearized reading' (duration: 131.799973ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:02.236609Z","caller":"traceutil/trace.go:171","msg":"trace[612587052] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"131.417534ms","start":"2026-05-24T07:17:02.105166Z","end":"2026-05-24T07:17:02.236583Z","steps":["trace[612587052] 'process raft request' (duration: 131.356012ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:02.236639Z","caller":"traceutil/trace.go:171","msg":"trace[732224953] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"133.283517ms","start":"2026-05-24T07:17:02.103328Z","end":"2026-05-24T07:17:02.236612Z","steps":["trace[732224953] 'process raft request' (duration: 133.039181ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:04.313427Z","caller":"traceutil/trace.go:171","msg":"trace[1763848452] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"112.5726ms","start":"2026-05-24T07:17:04.200805Z","end":"2026-05-24T07:17:04.313377Z","steps":["trace[1763848452] 'process raft request' (duration: 112.300853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:06.642365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.042346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-24T07:17:06.642453Z","caller":"traceutil/trace.go:171","msg":"trace[1107887736] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1111; }","duration":"140.17996ms","start":"2026-05-24T07:17:06.502257Z","end":"2026-05-24T07:17:06.642437Z","steps":["trace[1107887736] 'count revisions from in-memory index tree' (duration: 139.962375ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:08.433469Z","caller":"traceutil/trace.go:171","msg":"trace[1781168722] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"601.039547ms","start":"2026-05-24T07:17:07.832397Z","end":"2026-05-24T07:17:08.433437Z","steps":["trace[1781168722] 'process raft request' (duration: 600.517535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:08.43369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:07.832367Z","time spent":"601.193601ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:17:08.636531Z","caller":"traceutil/trace.go:171","msg":"trace[1206984366] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1151; }","duration":"306.440493ms","start":"2026-05-24T07:17:08.330061Z","end":"2026-05-24T07:17:08.636501Z","steps":["trace[1206984366] 'read index received' (duration: 103.306652ms)","trace[1206984366] 'applied index is now lower than readState.Index' (duration: 203.131971ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:17:08.63668Z","caller":"traceutil/trace.go:171","msg":"trace[814236631] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"511.51647ms","start":"2026-05-24T07:17:08.125146Z","end":"2026-05-24T07:17:08.636662Z","steps":["trace[814236631] 'process raft request' (duration: 443.161041ms)","trace[814236631] 'compare' (duration: 67.895318ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:17:08.636772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.758959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-24T07:17:08.63683Z","caller":"traceutil/trace.go:171","msg":"trace[1771609036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1113; }","duration":"300.853682ms","start":"2026-05-24T07:17:08.335963Z","end":"2026-05-24T07:17:08.636817Z","steps":["trace[1771609036] 'agreement among raft nodes before linearized reading' (duration: 300.708058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:08.636824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:08.125051Z","time spent":"511.681175ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:17:08.63686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:08.33595Z","time spent":"300.904103ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-24T07:17:08.637066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.005517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-24T07:17:08.637086Z","caller":"traceutil/trace.go:171","msg":"trace[330944172] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1113; }","duration":"307.062118ms","start":"2026-05-24T07:17:08.330018Z","end":"2026-05-24T07:17:08.63708Z","steps":["trace[330944172] 'agreement among raft nodes before linearized reading' (duration: 306.986816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:08.637104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:08.33Z","time spent":"307.099798ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-24T07:17:08.637365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.821891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-24T07:17:08.637439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.187216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-24T07:17:08.637464Z","caller":"traceutil/trace.go:171","msg":"trace[2082173751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1113; }","duration":"300.954974ms","start":"2026-05-24T07:17:08.336487Z","end":"2026-05-24T07:17:08.637441Z","steps":["trace[2082173751] 'agreement among raft nodes before linearized reading' (duration: 300.686498ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:08.637492Z","caller":"traceutil/trace.go:171","msg":"trace[202038928] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1113; }","duration":"300.295038ms","start":"2026-05-24T07:17:08.337182Z","end":"2026-05-24T07:17:08.637477Z","steps":["trace[202038928] 'agreement among raft nodes before linearized reading' (duration: 300.159945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:08.637522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:08.336475Z","time spent":"301.033936ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-24T07:17:08.637541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:08.337173Z","time spent":"300.35765ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-24T07:17:09.028271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.91564ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:17:09.028501Z","caller":"traceutil/trace.go:171","msg":"trace[382123252] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"386.210021ms","start":"2026-05-24T07:17:08.642269Z","end":"2026-05-24T07:17:09.028479Z","steps":["trace[382123252] 'process raft request' (duration: 386.127669ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:09.028558Z","caller":"traceutil/trace.go:171","msg":"trace[334411603] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"386.244742ms","start":"2026-05-24T07:17:08.642271Z","end":"2026-05-24T07:17:09.028516Z","steps":["trace[334411603] 'process raft request' (duration: 229.978793ms)","trace[334411603] 'compare' (duration: 155.749927ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:17:09.028635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:08.642247Z","time spent":"386.342063ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:17:09.028704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:08.642259Z","time spent":"386.365934ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:17:09.040521Z","caller":"traceutil/trace.go:171","msg":"trace[2075169732] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"398.233274ms","start":"2026-05-24T07:17:08.642269Z","end":"2026-05-24T07:17:09.040502Z","steps":["trace[2075169732] 'process raft request' (duration: 398.07031ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:09.040635Z","caller":"traceutil/trace.go:171","msg":"trace[2133663544] linearizableReadLoop","detail":"{readStateIndex:1157; appliedIndex:1155; }","duration":"166.292234ms","start":"2026-05-24T07:17:08.874329Z","end":"2026-05-24T07:17:09.040621Z","steps":["trace[2133663544] 'read index received' (duration: 166.004638ms)","trace[2133663544] 'applied index is now lower than readState.Index' (duration: 286.246µs)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:17:09.04066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:08.642256Z","time spent":"398.328136ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:17:09.040758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.428987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:17:09.040772Z","caller":"traceutil/trace.go:171","msg":"trace[579769326] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"398.353697ms","start":"2026-05-24T07:17:08.642411Z","end":"2026-05-24T07:17:09.040765Z","steps":["trace[579769326] 'process raft request' (duration: 398.0559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:09.040806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:08.642402Z","time spent":"398.385517ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:17:09.042549Z","caller":"traceutil/trace.go:171","msg":"trace[2143310249] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1117; }","duration":"166.503229ms","start":"2026-05-24T07:17:08.874276Z","end":"2026-05-24T07:17:09.040779Z","steps":["trace[2143310249] 'agreement among raft nodes before linearized reading' (duration: 166.393757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:10.439136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.668543ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-05-24T07:17:11.375376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.384963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:17:11.375481Z","caller":"traceutil/trace.go:171","msg":"trace[1595402717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1119; }","duration":"829.528116ms","start":"2026-05-24T07:17:10.54593Z","end":"2026-05-24T07:17:11.375458Z","steps":["trace[1595402717] 'range keys from in-memory index tree' (duration: 829.302911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:11.375536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:10.545913Z","time spent":"829.610948ms","remote":"127.0.0.1:37322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-24T07:17:11.375375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.968414ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:17:11.37567Z","caller":"traceutil/trace.go:171","msg":"trace[1828314085] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1119; }","duration":"640.264921ms","start":"2026-05-24T07:17:10.735382Z","end":"2026-05-24T07:17:11.375647Z","steps":["trace[1828314085] 'range keys from in-memory index tree' (duration: 639.953354ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:11.37643Z","caller":"traceutil/trace.go:171","msg":"trace[1919281775] linearizableReadLoop","detail":"{readStateIndex:1161; appliedIndex:1160; }","duration":"343.71174ms","start":"2026-05-24T07:17:11.032698Z","end":"2026-05-24T07:17:11.37641Z","steps":["trace[1919281775] 'read index received' (duration: 284.296451ms)","trace[1919281775] 'applied index is now lower than readState.Index' (duration: 59.413959ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:17:11.376443Z","caller":"traceutil/trace.go:171","msg":"trace[851427732] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"732.753778ms","start":"2026-05-24T07:17:10.643665Z","end":"2026-05-24T07:17:11.376419Z","steps":["trace[851427732] 'process raft request' (duration: 673.34906ms)","trace[851427732] 'compare' (duration: 57.926113ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:17:11.376563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:10.643646Z","time spent":"732.84794ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:17:11.376793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.840364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-24T07:17:11.376824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.354198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:17:11.376847Z","caller":"traceutil/trace.go:171","msg":"trace[1520970742] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1120; }","duration":"343.934316ms","start":"2026-05-24T07:17:11.032899Z","end":"2026-05-24T07:17:11.376833Z","steps":["trace[1520970742] 'agreement among raft nodes before linearized reading' (duration: 343.786602ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:11.376866Z","caller":"traceutil/trace.go:171","msg":"trace[862969280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1120; }","duration":"288.48585ms","start":"2026-05-24T07:17:11.088369Z","end":"2026-05-24T07:17:11.376855Z","steps":["trace[862969280] 'agreement among raft nodes before linearized reading' (duration: 288.353517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:11.376886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:11.032878Z","time spent":"343.997287ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-24T07:17:11.376936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.303694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-24T07:17:11.376954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.67729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-24T07:17:11.377068Z","caller":"traceutil/trace.go:171","msg":"trace[1187588152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1120; }","duration":"327.466598ms","start":"2026-05-24T07:17:11.049553Z","end":"2026-05-24T07:17:11.37702Z","steps":["trace[1187588152] 'agreement among raft nodes before linearized reading' (duration: 327.241053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:11.377243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:11.049541Z","time spent":"327.651902ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-24T07:17:11.377083Z","caller":"traceutil/trace.go:171","msg":"trace[2047150519] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1120; }","duration":"326.901645ms","start":"2026-05-24T07:17:11.050154Z","end":"2026-05-24T07:17:11.377056Z","steps":["trace[2047150519] 'agreement among raft nodes before linearized reading' (duration: 326.547607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:11.377328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:11.05014Z","time spent":"327.170481ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-24T07:17:11.377309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.616172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-24T07:17:11.377541Z","caller":"traceutil/trace.go:171","msg":"trace[1534355412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1120; }","duration":"344.884848ms","start":"2026-05-24T07:17:11.032635Z","end":"2026-05-24T07:17:11.37752Z","steps":["trace[1534355412] 'agreement among raft nodes before linearized reading' (duration: 343.889954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:11.377676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:11.032614Z","time spent":"345.00658ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-24T07:17:13.568536Z","caller":"traceutil/trace.go:171","msg":"trace[328114793] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"100.059714ms","start":"2026-05-24T07:17:13.46844Z","end":"2026-05-24T07:17:13.5685Z","steps":["trace[328114793] 'process raft request' (duration: 38.325181ms)","trace[328114793] 'compare' (duration: 61.563249ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:17:13.601661Z","caller":"traceutil/trace.go:171","msg":"trace[1198459364] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"132.331454ms","start":"2026-05-24T07:17:13.469305Z","end":"2026-05-24T07:17:13.601637Z","steps":["trace[1198459364] 'process raft request' (duration: 132.282043ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:13.60179Z","caller":"traceutil/trace.go:171","msg":"trace[1974659600] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"132.920338ms","start":"2026-05-24T07:17:13.468836Z","end":"2026-05-24T07:17:13.601756Z","steps":["trace[1974659600] 'process raft request' (duration: 132.622861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:14.441515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.365922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-24T07:17:14.441603Z","caller":"traceutil/trace.go:171","msg":"trace[1261370896] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1130; }","duration":"275.509334ms","start":"2026-05-24T07:17:14.166074Z","end":"2026-05-24T07:17:14.441584Z","steps":["trace[1261370896] 'count revisions from in-memory index tree' (duration: 275.201977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:14.967847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.352474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-24T07:17:14.967908Z","caller":"traceutil/trace.go:171","msg":"trace[645313491] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:1131; }","duration":"109.462667ms","start":"2026-05-24T07:17:14.85843Z","end":"2026-05-24T07:17:14.967893Z","steps":["trace[645313491] 'range keys from in-memory index tree' (duration: 109.116219ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:17.562304Z","caller":"traceutil/trace.go:171","msg":"trace[837565147] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"100.569597ms","start":"2026-05-24T07:17:17.461706Z","end":"2026-05-24T07:17:17.562276Z","steps":["trace[837565147] 'process raft request' (duration: 92.408725ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:26.861505Z","caller":"traceutil/trace.go:171","msg":"trace[610742070] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"161.771704ms","start":"2026-05-24T07:17:26.699707Z","end":"2026-05-24T07:17:26.861478Z","steps":["trace[610742070] 'process raft request' (duration: 161.614411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:28.277826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.613353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-05-24T07:17:28.278Z","caller":"traceutil/trace.go:171","msg":"trace[1874945745] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1177; }","duration":"320.285223ms","start":"2026-05-24T07:17:27.957694Z","end":"2026-05-24T07:17:28.277979Z","steps":["trace[1874945745] 'process raft request' (duration: 72.440244ms)","trace[1874945745] 'compare' (duration: 247.546411ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:17:28.278068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:27.957677Z","time spent":"320.367094ms","remote":"127.0.0.1:37404","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-24T07:17:28.401963Z","caller":"traceutil/trace.go:171","msg":"trace[1709910168] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"101.868405ms","start":"2026-05-24T07:17:28.30006Z","end":"2026-05-24T07:17:28.401929Z","steps":["trace[1709910168] 'process raft request' (duration: 101.65821ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:28.627041Z","caller":"traceutil/trace.go:171","msg":"trace[758867880] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"100.705498ms","start":"2026-05-24T07:17:28.526306Z","end":"2026-05-24T07:17:28.627011Z","steps":["trace[758867880] 'process raft request' (duration: 100.422512ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:28.627223Z","caller":"traceutil/trace.go:171","msg":"trace[633897795] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"100.76466ms","start":"2026-05-24T07:17:28.526311Z","end":"2026-05-24T07:17:28.627076Z","steps":["trace[633897795] 'process raft request' (duration: 100.566595ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:28.807934Z","caller":"traceutil/trace.go:171","msg":"trace[1629045735] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"102.024839ms","start":"2026-05-24T07:17:28.705875Z","end":"2026-05-24T07:17:28.807899Z","steps":["trace[1629045735] 'process raft request' (duration: 16.232442ms)","trace[1629045735] 'compare' (duration: 85.583402ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:17:28.861234Z","caller":"traceutil/trace.go:171","msg":"trace[982137281] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"155.319533ms","start":"2026-05-24T07:17:28.705891Z","end":"2026-05-24T07:17:28.861211Z","steps":["trace[982137281] 'process raft request' (duration: 155.21581ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:29.942553Z","caller":"traceutil/trace.go:171","msg":"trace[906007548] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"120.860262ms","start":"2026-05-24T07:17:29.821678Z","end":"2026-05-24T07:17:29.942538Z","steps":["trace[906007548] 'process raft request' (duration: 120.76136ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:29.944923Z","caller":"traceutil/trace.go:171","msg":"trace[716403437] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"122.835869ms","start":"2026-05-24T07:17:29.821895Z","end":"2026-05-24T07:17:29.944731Z","steps":["trace[716403437] 'process raft request' (duration: 122.570053ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:31.206804Z","caller":"traceutil/trace.go:171","msg":"trace[688698954] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1282; }","duration":"227.743456ms","start":"2026-05-24T07:17:30.979035Z","end":"2026-05-24T07:17:31.206778Z","steps":["trace[688698954] 'read index received' (duration: 227.523061ms)","trace[688698954] 'applied index is now lower than readState.Index' (duration: 219.465µs)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:17:31.206953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.892249ms","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-24T07:17:31.206998Z","caller":"traceutil/trace.go:171","msg":"trace[58741066] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1236; }","duration":"227.991372ms","start":"2026-05-24T07:17:30.978993Z","end":"2026-05-24T07:17:31.206984Z","steps":["trace[58741066] 'agreement among raft nodes before linearized reading' (duration: 227.884489ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:31.207388Z","caller":"traceutil/trace.go:171","msg":"trace[420270784] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"280.899916ms","start":"2026-05-24T07:17:30.926475Z","end":"2026-05-24T07:17:31.207375Z","steps":["trace[420270784] 'process raft request' (duration: 280.162608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:34.45625Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899449932196789,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-24T07:17:34.956618Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899449932196789,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-24T07:17:35.456797Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899449932196789,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-24T07:17:35.660169Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.869094153s","expected-duration":"1s"} {"level":"info","ts":"2026-05-24T07:17:35.660603Z","caller":"traceutil/trace.go:171","msg":"trace[2036238920] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1290; }","duration":"1.704932003s","start":"2026-05-24T07:17:33.955646Z","end":"2026-05-24T07:17:35.660578Z","steps":["trace[2036238920] 'read index received' (duration: 1.704749568s)","trace[2036238920] 'applied index is now lower than readState.Index' (duration: 181.475µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:17:35.660704Z","caller":"traceutil/trace.go:171","msg":"trace[33753254] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"1.869680137s","start":"2026-05-24T07:17:33.790997Z","end":"2026-05-24T07:17:35.660677Z","steps":["trace[33753254] 'process raft request' (duration: 1.869407591s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:35.660824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:33.790978Z","time spent":"1.869783369s","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:17:35.660829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.705162358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-24T07:17:35.660868Z","caller":"traceutil/trace.go:171","msg":"trace[1108790108] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1244; }","duration":"1.70525877s","start":"2026-05-24T07:17:33.955596Z","end":"2026-05-24T07:17:35.660855Z","steps":["trace[1108790108] 'agreement among raft nodes before linearized reading' (duration: 1.705099396s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:35.660909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:33.955573Z","time spent":"1.705327742s","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-24T07:17:36.546129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000497843s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-24T07:17:36.546219Z","caller":"traceutil/trace.go:171","msg":"trace[783916687] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000710778s","start":"2026-05-24T07:17:34.545484Z","end":"2026-05-24T07:17:36.546195Z","steps":["trace[783916687] 'agreement among raft nodes before linearized reading' (duration: 2.000599936s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:36.546269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:34.545466Z","time spent":"2.00079253s","remote":"127.0.0.1:37316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/24 07:17:36 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-24T07:17:36.563235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.918944ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:17:36.563456Z","caller":"traceutil/trace.go:171","msg":"trace[1074895399] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"862.498973ms","start":"2026-05-24T07:17:35.700943Z","end":"2026-05-24T07:17:36.563442Z","steps":["trace[1074895399] 'process raft request' (duration: 862.458792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:36.563527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:35.700915Z","time spent":"862.581615ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:17:36.563643Z","caller":"traceutil/trace.go:171","msg":"trace[1720029514] linearizableReadLoop","detail":"{readStateIndex:1292; appliedIndex:1291; }","duration":"902.953394ms","start":"2026-05-24T07:17:35.66067Z","end":"2026-05-24T07:17:36.563624Z","steps":["trace[1720029514] 'read index received' (duration: 289.58819ms)","trace[1720029514] 'applied index is now lower than readState.Index' (duration: 613.362114ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:17:36.563901Z","caller":"traceutil/trace.go:171","msg":"trace[871192196] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"1.043473239s","start":"2026-05-24T07:17:35.520338Z","end":"2026-05-24T07:17:36.563811Z","steps":["trace[871192196] 'process raft request' (duration: 429.944281ms)","trace[871192196] 'compare' (duration: 612.635877ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:17:36.563927Z","caller":"traceutil/trace.go:171","msg":"trace[794186251] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"898.687304ms","start":"2026-05-24T07:17:35.665227Z","end":"2026-05-24T07:17:36.563914Z","steps":["trace[794186251] 'process raft request' (duration: 898.093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:36.56399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:35.665206Z","time spent":"898.752555ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:17:36.564157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:35.520316Z","time spent":"1.043637712s","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:17:36.56439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.567478827s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-24T07:17:36.564432Z","caller":"traceutil/trace.go:171","msg":"trace[1559728386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1247; }","duration":"2.567557889s","start":"2026-05-24T07:17:33.996864Z","end":"2026-05-24T07:17:36.564421Z","steps":["trace[1559728386] 'agreement among raft nodes before linearized reading' (duration: 2.567432516s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:36.564462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:33.996843Z","time spent":"2.56761242s","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-24T07:17:36.564495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.60847479s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-24T07:17:36.56456Z","caller":"traceutil/trace.go:171","msg":"trace[1028530530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1247; }","duration":"2.608559713s","start":"2026-05-24T07:17:33.955986Z","end":"2026-05-24T07:17:36.564546Z","steps":["trace[1028530530] 'agreement among raft nodes before linearized reading' (duration: 2.60842414s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:36.5646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:33.955978Z","time spent":"2.608610814s","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-24T07:17:36.564616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.345801138s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-24T07:17:36.564642Z","caller":"traceutil/trace.go:171","msg":"trace[1132182576] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1247; }","duration":"1.34586562s","start":"2026-05-24T07:17:35.218768Z","end":"2026-05-24T07:17:36.564634Z","steps":["trace[1132182576] 'agreement among raft nodes before linearized reading' (duration: 1.345792848s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:36.564667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:35.218738Z","time spent":"1.345923051s","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-24T07:17:36.564728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.704571454s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-24T07:17:36.564801Z","caller":"traceutil/trace.go:171","msg":"trace[747498384] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:1247; }","duration":"1.704689857s","start":"2026-05-24T07:17:34.860092Z","end":"2026-05-24T07:17:36.564782Z","steps":["trace[747498384] 'agreement among raft nodes before linearized reading' (duration: 1.704472292s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:36.564852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:34.860075Z","time spent":"1.704769429s","remote":"127.0.0.1:37334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.53\" "} {"level":"info","ts":"2026-05-24T07:17:42.318208Z","caller":"traceutil/trace.go:171","msg":"trace[1224975744] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1314; }","duration":"460.58462ms","start":"2026-05-24T07:17:41.857594Z","end":"2026-05-24T07:17:42.318179Z","steps":["trace[1224975744] 'read index received' (duration: 460.422786ms)","trace[1224975744] 'applied index is now lower than readState.Index' (duration: 160.094µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:17:42.31824Z","caller":"traceutil/trace.go:171","msg":"trace[1970899659] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"633.194319ms","start":"2026-05-24T07:17:41.685013Z","end":"2026-05-24T07:17:42.318207Z","steps":["trace[1970899659] 'process raft request' (duration: 633.010195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:42.318431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.777139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-24T07:17:42.318421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.793494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-24T07:17:42.318456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:41.684993Z","time spent":"633.365793ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:17:42.318471Z","caller":"traceutil/trace.go:171","msg":"trace[5936756] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1266; }","duration":"137.847231ms","start":"2026-05-24T07:17:42.180613Z","end":"2026-05-24T07:17:42.31846Z","steps":["trace[5936756] 'agreement among raft nodes before linearized reading' (duration: 137.78777ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:42.318483Z","caller":"traceutil/trace.go:171","msg":"trace[1722076527] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1266; }","duration":"460.906838ms","start":"2026-05-24T07:17:41.857562Z","end":"2026-05-24T07:17:42.318469Z","steps":["trace[1722076527] 'agreement among raft nodes before linearized reading' (duration: 460.750104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:42.318538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:41.857546Z","time spent":"460.980169ms","remote":"127.0.0.1:42380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-05-24T07:17:42.758539Z","caller":"traceutil/trace.go:171","msg":"trace[1559876111] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1316; }","duration":"120.694309ms","start":"2026-05-24T07:17:42.63782Z","end":"2026-05-24T07:17:42.758515Z","steps":["trace[1559876111] 'read index received' (duration: 53.953889ms)","trace[1559876111] 'applied index is now lower than readState.Index' (duration: 66.73945ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:17:42.758598Z","caller":"traceutil/trace.go:171","msg":"trace[32221428] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"121.484267ms","start":"2026-05-24T07:17:42.637065Z","end":"2026-05-24T07:17:42.758549Z","steps":["trace[32221428] 'process raft request' (duration: 54.756598ms)","trace[32221428] 'compare' (duration: 66.593216ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:17:42.758793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.956825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-24T07:17:42.758852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.76173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-24T07:17:42.758866Z","caller":"traceutil/trace.go:171","msg":"trace[1474597451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1268; }","duration":"121.075538ms","start":"2026-05-24T07:17:42.637772Z","end":"2026-05-24T07:17:42.758847Z","steps":["trace[1474597451] 'agreement among raft nodes before linearized reading' (duration: 120.824552ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:42.758909Z","caller":"traceutil/trace.go:171","msg":"trace[1762793301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1268; }","duration":"120.863303ms","start":"2026-05-24T07:17:42.638032Z","end":"2026-05-24T07:17:42.758895Z","steps":["trace[1762793301] 'agreement among raft nodes before linearized reading' (duration: 120.72544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:42.759052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.32574ms","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-24T07:17:42.759069Z","caller":"traceutil/trace.go:171","msg":"trace[165863018] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1268; }","duration":"120.360411ms","start":"2026-05-24T07:17:42.638705Z","end":"2026-05-24T07:17:42.759065Z","steps":["trace[165863018] 'agreement among raft nodes before linearized reading' (duration: 120.31664ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:42.976294Z","caller":"traceutil/trace.go:171","msg":"trace[1389735869] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"210.48719ms","start":"2026-05-24T07:17:42.765782Z","end":"2026-05-24T07:17:42.976269Z","steps":["trace[1389735869] 'process raft request' (duration: 112.872764ms)","trace[1389735869] 'compare' (duration: 97.412981ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:17:42.976307Z","caller":"traceutil/trace.go:171","msg":"trace[592824242] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"210.348437ms","start":"2026-05-24T07:17:42.765928Z","end":"2026-05-24T07:17:42.976276Z","steps":["trace[592824242] 'process raft request' (duration: 210.263185ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:46.986841Z","caller":"traceutil/trace.go:171","msg":"trace[116554878] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1332; }","duration":"109.398173ms","start":"2026-05-24T07:17:46.877425Z","end":"2026-05-24T07:17:46.986823Z","steps":["trace[116554878] 'read index received' (duration: 66.638418ms)","trace[116554878] 'applied index is now lower than readState.Index' (duration: 42.758825ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:17:46.987032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.587157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-24T07:17:46.987087Z","caller":"traceutil/trace.go:171","msg":"trace[2013879481] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1283; }","duration":"109.69656ms","start":"2026-05-24T07:17:46.877378Z","end":"2026-05-24T07:17:46.987074Z","steps":["trace[2013879481] 'agreement among raft nodes before linearized reading' (duration: 109.542266ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:46.987306Z","caller":"traceutil/trace.go:171","msg":"trace[1998142085] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"142.714276ms","start":"2026-05-24T07:17:46.844564Z","end":"2026-05-24T07:17:46.987278Z","steps":["trace[1998142085] 'process raft request' (duration: 99.543471ms)","trace[1998142085] 'compare' (duration: 42.632762ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:17:51.791042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.865917ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-24T07:17:58.897379Z","caller":"traceutil/trace.go:171","msg":"trace[1321145366] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1404; }","duration":"351.374623ms","start":"2026-05-24T07:17:58.545985Z","end":"2026-05-24T07:17:58.897359Z","steps":["trace[1321145366] 'read index received' (duration: 351.26886ms)","trace[1321145366] 'applied index is now lower than readState.Index' (duration: 104.933µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:17:58.897425Z","caller":"traceutil/trace.go:171","msg":"trace[739530826] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"415.973703ms","start":"2026-05-24T07:17:58.481428Z","end":"2026-05-24T07:17:58.897402Z","steps":["trace[739530826] 'process raft request' (duration: 415.817499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:58.897494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.492756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:17:58.897516Z","caller":"traceutil/trace.go:171","msg":"trace[101197822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1353; }","duration":"351.569807ms","start":"2026-05-24T07:17:58.54594Z","end":"2026-05-24T07:17:58.89751Z","steps":["trace[101197822] 'agreement among raft nodes before linearized reading' (duration: 351.513426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:58.89754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:58.545918Z","time spent":"351.616329ms","remote":"127.0.0.1:37316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-24T07:17:58.897542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:58.481407Z","time spent":"416.066364ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:17:59.085396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.412284ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:17:59.085559Z","caller":"traceutil/trace.go:171","msg":"trace[832567843] linearizableReadLoop","detail":"{readStateIndex:1406; appliedIndex:1405; }","duration":"188.054193ms","start":"2026-05-24T07:17:58.897433Z","end":"2026-05-24T07:17:59.085488Z","steps":["trace[832567843] 'read index received' (duration: 52.389412ms)","trace[832567843] 'applied index is now lower than readState.Index' (duration: 135.663511ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:17:59.085598Z","caller":"traceutil/trace.go:171","msg":"trace[1735688683] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"310.555573ms","start":"2026-05-24T07:17:58.775005Z","end":"2026-05-24T07:17:59.085561Z","steps":["trace[1735688683] 'process raft request' (duration: 174.894993ms)","trace[1735688683] 'compare' (duration: 135.298212ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:17:59.085748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:58.774981Z","time spent":"310.692376ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:17:59.086012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.16974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-24T07:17:59.086013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.410448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:17:59.086065Z","caller":"traceutil/trace.go:171","msg":"trace[179197850] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1354; }","duration":"161.263512ms","start":"2026-05-24T07:17:58.924788Z","end":"2026-05-24T07:17:59.086051Z","steps":["trace[179197850] 'agreement among raft nodes before linearized reading' (duration: 161.114038ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:59.086114Z","caller":"traceutil/trace.go:171","msg":"trace[489660470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1354; }","duration":"187.506049ms","start":"2026-05-24T07:17:58.898563Z","end":"2026-05-24T07:17:59.086069Z","steps":["trace[489660470] 'agreement among raft nodes before linearized reading' (duration: 187.333545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:59.086563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.916745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-24T07:17:59.086601Z","caller":"traceutil/trace.go:171","msg":"trace[1228024917] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1354; }","duration":"289.001997ms","start":"2026-05-24T07:17:58.797589Z","end":"2026-05-24T07:17:59.086591Z","steps":["trace[1228024917] 'agreement among raft nodes before linearized reading' (duration: 288.086685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:59.674878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.943553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:17:59.674978Z","caller":"traceutil/trace.go:171","msg":"trace[1152491262] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1406; }","duration":"561.530225ms","start":"2026-05-24T07:17:59.113432Z","end":"2026-05-24T07:17:59.674962Z","steps":["trace[1152491262] 'read index received' (duration: 122.391898ms)","trace[1152491262] 'applied index is now lower than readState.Index' (duration: 439.137167ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:17:59.675163Z","caller":"traceutil/trace.go:171","msg":"trace[1263103241] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"584.777472ms","start":"2026-05-24T07:17:59.090354Z","end":"2026-05-24T07:17:59.675132Z","steps":["trace[1263103241] 'process raft request' (duration: 145.493012ms)","trace[1263103241] 'compare' (duration: 438.798408ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:17:59.675224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.162418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-24T07:17:59.675236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.786781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-24T07:17:59.675265Z","caller":"traceutil/trace.go:171","msg":"trace[289825648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1355; }","duration":"550.24791ms","start":"2026-05-24T07:17:59.125003Z","end":"2026-05-24T07:17:59.675251Z","steps":["trace[289825648] 'agreement among raft nodes before linearized reading' (duration: 550.110376ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:17:59.675307Z","caller":"traceutil/trace.go:171","msg":"trace[1155144630] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1355; }","duration":"561.899233ms","start":"2026-05-24T07:17:59.11339Z","end":"2026-05-24T07:17:59.675289Z","steps":["trace[1155144630] 'agreement among raft nodes before linearized reading' (duration: 561.683288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:59.675311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:59.124975Z","time spent":"550.326982ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-24T07:17:59.675303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:59.090323Z","time spent":"584.904065ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:17:59.675353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:59.113375Z","time spent":"561.970036ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-24T07:17:59.675354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.66251ms","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-24T07:17:59.675479Z","caller":"traceutil/trace.go:171","msg":"trace[1371282607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1355; }","duration":"536.771153ms","start":"2026-05-24T07:17:59.138666Z","end":"2026-05-24T07:17:59.675437Z","steps":["trace[1371282607] 'agreement among raft nodes before linearized reading' (duration: 536.466816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:17:59.67556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:59.13863Z","time spent":"536.892126ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-24T07:18:00.353077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.898774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:18:00.353176Z","caller":"traceutil/trace.go:171","msg":"trace[1106048351] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"674.186343ms","start":"2026-05-24T07:17:59.67897Z","end":"2026-05-24T07:18:00.353157Z","steps":["trace[1106048351] 'process raft request' (duration: 422.058454ms)","trace[1106048351] 'compare' (duration: 251.808822ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:18:00.35324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:59.678954Z","time spent":"674.254105ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:18:00.644155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.202545ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:18:00.644272Z","caller":"traceutil/trace.go:171","msg":"trace[1198135312] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"964.003289ms","start":"2026-05-24T07:17:59.680258Z","end":"2026-05-24T07:18:00.644261Z","steps":["trace[1198135312] 'process raft request' (duration: 963.966178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:00.644313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:59.680248Z","time spent":"964.04518ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:18:00.644329Z","caller":"traceutil/trace.go:171","msg":"trace[847101782] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"964.810008ms","start":"2026-05-24T07:17:59.679454Z","end":"2026-05-24T07:18:00.644264Z","steps":["trace[847101782] 'process raft request' (duration: 779.444899ms)","trace[847101782] 'compare' (duration: 185.106313ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:18:00.644464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:17:59.679436Z","time spent":"964.956481ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:18:00.857577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.924323ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-24T07:18:00.857756Z","caller":"traceutil/trace.go:171","msg":"trace[668217924] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1408; }","duration":"535.300468ms","start":"2026-05-24T07:18:00.322436Z","end":"2026-05-24T07:18:00.857736Z","steps":["trace[668217924] 'read index received' (duration: 136.653494ms)","trace[668217924] 'applied index is now lower than readState.Index' (duration: 398.645224ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:18:00.857927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.956723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:18:00.858024Z","caller":"traceutil/trace.go:171","msg":"trace[1862275775] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1358; }","duration":"314.095026ms","start":"2026-05-24T07:18:00.54391Z","end":"2026-05-24T07:18:00.858005Z","steps":["trace[1862275775] 'agreement among raft nodes before linearized reading' (duration: 313.965263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:00.85803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.562094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-05-24T07:18:00.858144Z","caller":"traceutil/trace.go:171","msg":"trace[1079489085] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1358; }","duration":"535.694227ms","start":"2026-05-24T07:18:00.32243Z","end":"2026-05-24T07:18:00.858124Z","steps":["trace[1079489085] 'agreement among raft nodes before linearized reading' (duration: 535.410881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:00.85786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.378881ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-24T07:18:00.858203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:00.322386Z","time spent":"535.80736ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-24T07:18:00.858177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:00.543889Z","time spent":"314.215909ms","remote":"127.0.0.1:37316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-24T07:18:00.858256Z","caller":"traceutil/trace.go:171","msg":"trace[1307151643] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1358; }","duration":"123.781751ms","start":"2026-05-24T07:18:00.734448Z","end":"2026-05-24T07:18:00.858229Z","steps":["trace[1307151643] 'range keys from in-memory index tree' (duration: 123.353531ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:18:01.060789Z","caller":"traceutil/trace.go:171","msg":"trace[103713088] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1411; }","duration":"160.360031ms","start":"2026-05-24T07:18:00.900407Z","end":"2026-05-24T07:18:01.060767Z","steps":["trace[103713088] 'read index received' (duration: 160.32961ms)","trace[103713088] 'applied index is now lower than readState.Index' (duration: 29.501µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:18:01.060873Z","caller":"traceutil/trace.go:171","msg":"trace[100231281] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"196.768138ms","start":"2026-05-24T07:18:00.864082Z","end":"2026-05-24T07:18:01.06085Z","steps":["trace[100231281] 'process raft request' (duration: 196.566413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:01.061202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.77146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-24T07:18:01.061288Z","caller":"traceutil/trace.go:171","msg":"trace[1200091146] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1359; }","duration":"160.899164ms","start":"2026-05-24T07:18:00.900369Z","end":"2026-05-24T07:18:01.061269Z","steps":["trace[1200091146] 'agreement among raft nodes before linearized reading' (duration: 160.493454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:01.395206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.036009ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:18:01.395756Z","caller":"traceutil/trace.go:171","msg":"trace[75195483] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"491.739514ms","start":"2026-05-24T07:18:00.903962Z","end":"2026-05-24T07:18:01.395701Z","steps":["trace[75195483] 'process raft request' (duration: 352.121961ms)","trace[75195483] 'compare' (duration: 138.757723ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:18:01.395823Z","caller":"traceutil/trace.go:171","msg":"trace[559929040] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"330.112803ms","start":"2026-05-24T07:18:01.065674Z","end":"2026-05-24T07:18:01.395786Z","steps":["trace[559929040] 'process raft request' (duration: 329.939439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:01.395874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.09713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-24T07:18:01.39592Z","caller":"traceutil/trace.go:171","msg":"trace[432541523] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1362; }","duration":"349.190121ms","start":"2026-05-24T07:18:01.046719Z","end":"2026-05-24T07:18:01.395909Z","steps":["trace[432541523] 'agreement among raft nodes before linearized reading' (duration: 349.088069ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:18:01.395781Z","caller":"traceutil/trace.go:171","msg":"trace[739166117] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"305.73404ms","start":"2026-05-24T07:18:01.090028Z","end":"2026-05-24T07:18:01.395763Z","steps":["trace[739166117] 'process raft request' (duration: 305.670298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:01.396122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:01.046706Z","time spent":"349.395017ms","remote":"127.0.0.1:37798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":31,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-24T07:18:01.395757Z","caller":"traceutil/trace.go:171","msg":"trace[1425183437] linearizableReadLoop","detail":"{readStateIndex:1413; appliedIndex:1412; }","duration":"334.851154ms","start":"2026-05-24T07:18:01.060855Z","end":"2026-05-24T07:18:01.395706Z","steps":["trace[1425183437] 'read index received' (duration: 195.322943ms)","trace[1425183437] 'applied index is now lower than readState.Index' (duration: 139.525201ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:18:01.395911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:00.903939Z","time spent":"491.890407ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:18:01.396002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:01.065655Z","time spent":"330.256556ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:18:01.396509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:01.090001Z","time spent":"306.356385ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:18:01.797285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.861959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:18:01.797377Z","caller":"traceutil/trace.go:171","msg":"trace[656738115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1362; }","duration":"251.005452ms","start":"2026-05-24T07:18:01.546346Z","end":"2026-05-24T07:18:01.797352Z","steps":["trace[656738115] 'range keys from in-memory index tree' (duration: 250.674905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:01.797479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.387694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-24T07:18:01.797777Z","caller":"traceutil/trace.go:171","msg":"trace[1296872398] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1362; }","duration":"118.735692ms","start":"2026-05-24T07:18:01.679015Z","end":"2026-05-24T07:18:01.797751Z","steps":["trace[1296872398] 'range keys from in-memory index tree' (duration: 118.136799ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:18:02.329886Z","caller":"traceutil/trace.go:171","msg":"trace[522611457] linearizableReadLoop","detail":"{readStateIndex:1416; appliedIndex:1415; }","duration":"460.289654ms","start":"2026-05-24T07:18:01.869574Z","end":"2026-05-24T07:18:02.329863Z","steps":["trace[522611457] 'read index received' (duration: 460.082179ms)","trace[522611457] 'applied index is now lower than readState.Index' (duration: 206.285µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:18:02.329953Z","caller":"traceutil/trace.go:171","msg":"trace[1558243440] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"527.411023ms","start":"2026-05-24T07:18:01.802495Z","end":"2026-05-24T07:18:02.329906Z","steps":["trace[1558243440] 'process raft request' (duration: 527.184598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:02.330116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.53758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-24T07:18:02.330154Z","caller":"traceutil/trace.go:171","msg":"trace[1924050713] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1363; }","duration":"460.664393ms","start":"2026-05-24T07:18:01.869477Z","end":"2026-05-24T07:18:02.330141Z","steps":["trace[1924050713] 'agreement among raft nodes before linearized reading' (duration: 460.522689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:02.330189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:01.869458Z","time spent":"460.722974ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-24T07:18:02.330242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.622807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-24T07:18:02.330248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:01.80247Z","time spent":"527.555006ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:18:02.330294Z","caller":"traceutil/trace.go:171","msg":"trace[1219962489] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1363; }","duration":"362.72245ms","start":"2026-05-24T07:18:01.967558Z","end":"2026-05-24T07:18:02.330281Z","steps":["trace[1219962489] 'agreement among raft nodes before linearized reading' (duration: 362.632938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:02.330329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:01.96754Z","time spent":"362.780061ms","remote":"127.0.0.1:44154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-24T07:18:02.888638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.766073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:18:02.888759Z","caller":"traceutil/trace.go:171","msg":"trace[408357095] linearizableReadLoop","detail":"{readStateIndex:1417; appliedIndex:1416; }","duration":"530.662849ms","start":"2026-05-24T07:18:02.358078Z","end":"2026-05-24T07:18:02.888741Z","steps":["trace[408357095] 'read index received' (duration: 215.59769ms)","trace[408357095] 'applied index is now lower than readState.Index' (duration: 315.063509ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:18:02.889119Z","caller":"traceutil/trace.go:171","msg":"trace[906654115] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"554.03532ms","start":"2026-05-24T07:18:02.335027Z","end":"2026-05-24T07:18:02.889062Z","steps":["trace[906654115] 'process raft request' (duration: 238.773885ms)","trace[906654115] 'compare' (duration: 314.472686ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:18:02.889216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:02.335007Z","time spent":"554.159812ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:18:02.88933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.240573ms","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-24T07:18:02.889405Z","caller":"traceutil/trace.go:171","msg":"trace[1144879077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1364; }","duration":"531.326355ms","start":"2026-05-24T07:18:02.358061Z","end":"2026-05-24T07:18:02.889388Z","steps":["trace[1144879077] 'agreement among raft nodes before linearized reading' (duration: 531.1166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:02.889368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.092573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-24T07:18:02.889451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:02.358001Z","time spent":"531.441957ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-24T07:18:02.889523Z","caller":"traceutil/trace.go:171","msg":"trace[975648904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1364; }","duration":"242.248337ms","start":"2026-05-24T07:18:02.647233Z","end":"2026-05-24T07:18:02.889481Z","steps":["trace[975648904] 'agreement among raft nodes before linearized reading' (duration: 241.976051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:02.889134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.404772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:18:02.889703Z","caller":"traceutil/trace.go:171","msg":"trace[1243896251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1364; }","duration":"343.008916ms","start":"2026-05-24T07:18:02.546685Z","end":"2026-05-24T07:18:02.889693Z","steps":["trace[1243896251] 'agreement among raft nodes before linearized reading' (duration: 342.418292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:02.889743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:02.546666Z","time spent":"343.068478ms","remote":"127.0.0.1:37316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-24T07:18:02.890086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.437915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-24T07:18:02.890214Z","caller":"traceutil/trace.go:171","msg":"trace[700050128] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1364; }","duration":"284.004789ms","start":"2026-05-24T07:18:02.606174Z","end":"2026-05-24T07:18:02.890179Z","steps":["trace[700050128] 'agreement among raft nodes before linearized reading' (duration: 283.164259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:02.890705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.847371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-24T07:18:02.890749Z","caller":"traceutil/trace.go:171","msg":"trace[597032628] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1364; }","duration":"242.920693ms","start":"2026-05-24T07:18:02.647818Z","end":"2026-05-24T07:18:02.890739Z","steps":["trace[597032628] 'agreement among raft nodes before linearized reading' (duration: 241.342686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:03.495443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.154732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:18:03.495636Z","caller":"traceutil/trace.go:171","msg":"trace[1278797466] linearizableReadLoop","detail":"{readStateIndex:1420; appliedIndex:1417; }","duration":"308.095555ms","start":"2026-05-24T07:18:03.187525Z","end":"2026-05-24T07:18:03.495621Z","steps":["trace[1278797466] 'read index received' (duration: 174.635817ms)","trace[1278797466] 'applied index is now lower than readState.Index' (duration: 133.458808ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:18:03.496023Z","caller":"traceutil/trace.go:171","msg":"trace[507401581] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"602.476608ms","start":"2026-05-24T07:18:02.893533Z","end":"2026-05-24T07:18:03.496009Z","steps":["trace[507401581] 'process raft request' (duration: 468.660222ms)","trace[507401581] 'compare' (duration: 132.851834ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:18:03.4961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:02.893512Z","time spent":"602.55223ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:18:03.496256Z","caller":"traceutil/trace.go:171","msg":"trace[1864872500] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"602.325454ms","start":"2026-05-24T07:18:02.893921Z","end":"2026-05-24T07:18:03.496247Z","steps":["trace[1864872500] 'process raft request' (duration: 601.613707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:03.496311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:02.893905Z","time spent":"602.379896ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:18:03.496539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.990877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-24T07:18:03.496627Z","caller":"traceutil/trace.go:171","msg":"trace[158124724] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1367; }","duration":"309.15161ms","start":"2026-05-24T07:18:03.187456Z","end":"2026-05-24T07:18:03.496607Z","steps":["trace[158124724] 'agreement among raft nodes before linearized reading' (duration: 308.936365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:03.496672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:03.187431Z","time spent":"309.233422ms","remote":"127.0.0.1:37352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":142,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-05-24T07:18:03.496726Z","caller":"traceutil/trace.go:171","msg":"trace[1562917489] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"602.399257ms","start":"2026-05-24T07:18:02.8943Z","end":"2026-05-24T07:18:03.496699Z","steps":["trace[1562917489] 'process raft request' (duration: 601.28776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:03.496969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:02.894292Z","time spent":"602.56422ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:18:03.567447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.72377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129159"} {"level":"info","ts":"2026-05-24T07:18:03.567501Z","caller":"traceutil/trace.go:171","msg":"trace[1785560223] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"165.090462ms","start":"2026-05-24T07:18:03.402384Z","end":"2026-05-24T07:18:03.567475Z","steps":["trace[1785560223] 'process raft request' (duration: 164.757414ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:18:03.567523Z","caller":"traceutil/trace.go:171","msg":"trace[1287559815] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1368; }","duration":"379.821492ms","start":"2026-05-24T07:18:03.187685Z","end":"2026-05-24T07:18:03.567507Z","steps":["trace[1287559815] 'agreement among raft nodes before linearized reading' (duration: 379.592966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:03.56787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:03.187676Z","time spent":"380.17774ms","remote":"127.0.0.1:37350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":129183,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-05-24T07:18:03.567448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.011558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-24T07:18:03.568045Z","caller":"traceutil/trace.go:171","msg":"trace[45608763] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1368; }","duration":"167.657692ms","start":"2026-05-24T07:18:03.400373Z","end":"2026-05-24T07:18:03.568031Z","steps":["trace[45608763] 'agreement among raft nodes before linearized reading' (duration: 166.909314ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:18:03.965948Z","caller":"traceutil/trace.go:171","msg":"trace[605045519] linearizableReadLoop","detail":"{readStateIndex:1422; appliedIndex:1421; }","duration":"392.830527ms","start":"2026-05-24T07:18:03.573093Z","end":"2026-05-24T07:18:03.965924Z","steps":["trace[605045519] 'read index received' (duration: 392.485679ms)","trace[605045519] 'applied index is now lower than readState.Index' (duration: 343.448µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:18:03.965967Z","caller":"traceutil/trace.go:171","msg":"trace[663282747] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"393.83265ms","start":"2026-05-24T07:18:03.572105Z","end":"2026-05-24T07:18:03.965938Z","steps":["trace[663282747] 'process raft request' (duration: 393.515123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:03.966167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:03.57209Z","time spent":"393.959674ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:18:03.966356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.076802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129159"} {"level":"info","ts":"2026-05-24T07:18:03.966406Z","caller":"traceutil/trace.go:171","msg":"trace[853698217] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1369; }","duration":"393.338229ms","start":"2026-05-24T07:18:03.573058Z","end":"2026-05-24T07:18:03.966397Z","steps":["trace[853698217] 'agreement among raft nodes before linearized reading' (duration: 392.9581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:03.966495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:03.573046Z","time spent":"393.38424ms","remote":"127.0.0.1:37350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":129183,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-05-24T07:18:04.63022Z","caller":"traceutil/trace.go:171","msg":"trace[1587763495] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"291.578727ms","start":"2026-05-24T07:18:04.338613Z","end":"2026-05-24T07:18:04.630191Z","steps":["trace[1587763495] 'process raft request' (duration: 290.786309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:05.074237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.030574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-24T07:18:05.074325Z","caller":"traceutil/trace.go:171","msg":"trace[569353032] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1371; }","duration":"149.188318ms","start":"2026-05-24T07:18:04.925118Z","end":"2026-05-24T07:18:05.074306Z","steps":["trace[569353032] 'count revisions from in-memory index tree' (duration: 148.919822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:05.074395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.916747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-24T07:18:05.074439Z","caller":"traceutil/trace.go:171","msg":"trace[1476532752] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:1371; }","duration":"213.003269ms","start":"2026-05-24T07:18:04.861425Z","end":"2026-05-24T07:18:05.074429Z","steps":["trace[1476532752] 'range keys from in-memory index tree' (duration: 212.776994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:06.358168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.068537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:18:06.358321Z","caller":"traceutil/trace.go:171","msg":"trace[1229015445] linearizableReadLoop","detail":"{readStateIndex:1429; appliedIndex:1426; }","duration":"812.061687ms","start":"2026-05-24T07:18:05.546244Z","end":"2026-05-24T07:18:06.358306Z","steps":["trace[1229015445] 'read index received' (duration: 7.476526ms)","trace[1229015445] 'applied index is now lower than readState.Index' (duration: 804.584241ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:18:06.358505Z","caller":"traceutil/trace.go:171","msg":"trace[2092402972] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"853.374388ms","start":"2026-05-24T07:18:05.505117Z","end":"2026-05-24T07:18:06.358491Z","steps":["trace[2092402972] 'process raft request' (duration: 569.887872ms)","trace[2092402972] 'compare' (duration: 282.895653ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:18:06.358571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:05.505092Z","time spent":"853.447821ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:18:06.358731Z","caller":"traceutil/trace.go:171","msg":"trace[2079152912] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"853.486681ms","start":"2026-05-24T07:18:05.505234Z","end":"2026-05-24T07:18:06.358721Z","steps":["trace[2079152912] 'process raft request' (duration: 853.02273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:06.358789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:05.505203Z","time spent":"853.557853ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:18:06.358986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.714962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:18:06.359077Z","caller":"traceutil/trace.go:171","msg":"trace[169719483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1375; }","duration":"812.858126ms","start":"2026-05-24T07:18:05.546202Z","end":"2026-05-24T07:18:06.35906Z","steps":["trace[169719483] 'agreement among raft nodes before linearized reading' (duration: 812.683902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:06.359121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:05.546183Z","time spent":"812.929707ms","remote":"127.0.0.1:37316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-24T07:18:06.363633Z","caller":"traceutil/trace.go:171","msg":"trace[1845298153] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"788.604696ms","start":"2026-05-24T07:18:05.574962Z","end":"2026-05-24T07:18:06.363566Z","steps":["trace[1845298153] 'process raft request' (duration: 788.3476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:06.363672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.581416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-24T07:18:06.36368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.747426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:18:06.363718Z","caller":"traceutil/trace.go:171","msg":"trace[794714795] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1376; }","duration":"393.666488ms","start":"2026-05-24T07:18:05.970041Z","end":"2026-05-24T07:18:06.363708Z","steps":["trace[794714795] 'agreement among raft nodes before linearized reading' (duration: 393.487944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:06.363754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:05.970024Z","time spent":"393.725059ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-24T07:18:06.363723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.992868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-24T07:18:06.363764Z","caller":"traceutil/trace.go:171","msg":"trace[633209834] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1376; }","duration":"716.872458ms","start":"2026-05-24T07:18:05.646877Z","end":"2026-05-24T07:18:06.363749Z","steps":["trace[633209834] 'agreement among raft nodes before linearized reading' (duration: 716.759085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:06.363799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:05.646858Z","time spent":"716.9335ms","remote":"127.0.0.1:37616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-05-24T07:18:06.363796Z","caller":"traceutil/trace.go:171","msg":"trace[434750797] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1376; }","duration":"298.09809ms","start":"2026-05-24T07:18:06.065686Z","end":"2026-05-24T07:18:06.363784Z","steps":["trace[434750797] 'agreement among raft nodes before linearized reading' (duration: 297.902355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:06.363989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:05.574944Z","time spent":"788.771069ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:18:07.424378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:07.017062Z","time spent":"407.307387ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-24T07:18:07.425059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.524858ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:18:07.425237Z","caller":"traceutil/trace.go:171","msg":"trace[801377628] linearizableReadLoop","detail":"{readStateIndex:1432; appliedIndex:1430; }","duration":"878.496418ms","start":"2026-05-24T07:18:06.546725Z","end":"2026-05-24T07:18:07.425222Z","steps":["trace[801377628] 'read index received' (duration: 366.920459ms)","trace[801377628] 'applied index is now lower than readState.Index' (duration: 511.575099ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:18:07.42522Z","caller":"traceutil/trace.go:171","msg":"trace[1940973200] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"1.058336547s","start":"2026-05-24T07:18:06.366853Z","end":"2026-05-24T07:18:07.425189Z","steps":["trace[1940973200] 'process raft request' (duration: 546.609494ms)","trace[1940973200] 'compare' (duration: 511.430066ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:18:07.425313Z","caller":"traceutil/trace.go:171","msg":"trace[929383489] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"537.725244ms","start":"2026-05-24T07:18:06.887578Z","end":"2026-05-24T07:18:07.425303Z","steps":["trace[929383489] 'process raft request' (duration: 537.603041ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:18:07.425295Z","caller":"traceutil/trace.go:171","msg":"trace[450868258] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"1.05761252s","start":"2026-05-24T07:18:06.367659Z","end":"2026-05-24T07:18:07.425272Z","steps":["trace[450868258] 'process raft request' (duration: 1.057474937s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:07.42536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:06.887559Z","time spent":"537.776756ms","remote":"127.0.0.1:37364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-24T07:18:07.425387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:06.366839Z","time spent":"1.05846183s","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:18:07.425402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.663302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-24T07:18:07.425395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:06.36764Z","time spent":"1.057708132s","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:18:07.425463Z","caller":"traceutil/trace.go:171","msg":"trace[1982092439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1379; }","duration":"878.754864ms","start":"2026-05-24T07:18:06.546688Z","end":"2026-05-24T07:18:07.425443Z","steps":["trace[1982092439] 'agreement among raft nodes before linearized reading' (duration: 878.656982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:07.425569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:06.546667Z","time spent":"878.872127ms","remote":"127.0.0.1:37316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-24T07:18:07.742309Z","caller":"traceutil/trace.go:171","msg":"trace[637531604] linearizableReadLoop","detail":"{readStateIndex:1434; appliedIndex:1433; }","duration":"317.019775ms","start":"2026-05-24T07:18:07.425272Z","end":"2026-05-24T07:18:07.742292Z","steps":["trace[637531604] 'read index received' (duration: 316.860921ms)","trace[637531604] 'applied index is now lower than readState.Index' (duration: 158.184µs)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:18:07.74243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.785214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:18:07.742457Z","caller":"traceutil/trace.go:171","msg":"trace[1203608261] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1380; }","duration":"730.858386ms","start":"2026-05-24T07:18:07.011591Z","end":"2026-05-24T07:18:07.74245Z","steps":["trace[1203608261] 'agreement among raft nodes before linearized reading' (duration: 730.784974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:07.742485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:07.011574Z","time spent":"730.905067ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-24T07:18:07.742617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107653707s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-24T07:18:07.742637Z","caller":"traceutil/trace.go:171","msg":"trace[463412078] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1380; }","duration":"1.107708598s","start":"2026-05-24T07:18:06.634924Z","end":"2026-05-24T07:18:07.742632Z","steps":["trace[463412078] 'agreement among raft nodes before linearized reading' (duration: 1.107637167s)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:07.742653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:06.634903Z","time spent":"1.107746569s","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-24T07:18:07.742711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.762615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:18:07.742731Z","caller":"traceutil/trace.go:171","msg":"trace[1859094509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1380; }","duration":"315.834137ms","start":"2026-05-24T07:18:07.426892Z","end":"2026-05-24T07:18:07.742726Z","steps":["trace[1859094509] 'agreement among raft nodes before linearized reading' (duration: 315.806787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:07.742748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:07.426818Z","time spent":"315.926019ms","remote":"127.0.0.1:37322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-24T07:18:07.742957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.754238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-24T07:18:07.742981Z","caller":"traceutil/trace.go:171","msg":"trace[735041423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1380; }","duration":"185.805039ms","start":"2026-05-24T07:18:07.55717Z","end":"2026-05-24T07:18:07.742975Z","steps":["trace[735041423] 'agreement among raft nodes before linearized reading' (duration: 185.745718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:07.743048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.352133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:18:07.743068Z","caller":"traceutil/trace.go:171","msg":"trace[890878325] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1380; }","duration":"260.415443ms","start":"2026-05-24T07:18:07.482647Z","end":"2026-05-24T07:18:07.743063Z","steps":["trace[890878325] 'agreement among raft nodes before linearized reading' (duration: 260.381063ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:18:07.743517Z","caller":"traceutil/trace.go:171","msg":"trace[953850813] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"459.089787ms","start":"2026-05-24T07:18:07.284404Z","end":"2026-05-24T07:18:07.743494Z","steps":["trace[953850813] 'process raft request' (duration: 457.782156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:07.746111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:07.284381Z","time spent":"461.464662ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:18:12.549708Z","caller":"traceutil/trace.go:171","msg":"trace[984892528] linearizableReadLoop","detail":"{readStateIndex:1478; appliedIndex:1477; }","duration":"129.848744ms","start":"2026-05-24T07:18:12.419836Z","end":"2026-05-24T07:18:12.549685Z","steps":["trace[984892528] 'read index received' (duration: 129.501056ms)","trace[984892528] 'applied index is now lower than readState.Index' (duration: 346.618µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:18:12.549823Z","caller":"traceutil/trace.go:171","msg":"trace[52070687] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"132.534377ms","start":"2026-05-24T07:18:12.417251Z","end":"2026-05-24T07:18:12.549786Z","steps":["trace[52070687] 'process raft request' (duration: 132.122867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:12.549895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.037428ms","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-24T07:18:12.549948Z","caller":"traceutil/trace.go:171","msg":"trace[862069848] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1423; }","duration":"130.116219ms","start":"2026-05-24T07:18:12.419811Z","end":"2026-05-24T07:18:12.549927Z","steps":["trace[862069848] 'agreement among raft nodes before linearized reading' (duration: 129.981306ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:18:12.555975Z","caller":"traceutil/trace.go:171","msg":"trace[399080560] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"135.667031ms","start":"2026-05-24T07:18:12.420298Z","end":"2026-05-24T07:18:12.555965Z","steps":["trace[399080560] 'process raft request' (duration: 135.612719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:12.556235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.640705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-05-24T07:18:12.556317Z","caller":"traceutil/trace.go:171","msg":"trace[208988428] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1424; }","duration":"125.840429ms","start":"2026-05-24T07:18:12.430455Z","end":"2026-05-24T07:18:12.556296Z","steps":["trace[208988428] 'agreement among raft nodes before linearized reading' (duration: 125.531262ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:18:18.113938Z","caller":"traceutil/trace.go:171","msg":"trace[493379201] linearizableReadLoop","detail":"{readStateIndex:1507; appliedIndex:1506; }","duration":"198.208272ms","start":"2026-05-24T07:18:17.915709Z","end":"2026-05-24T07:18:18.113918Z","steps":["trace[493379201] 'read index received' (duration: 198.113849ms)","trace[493379201] 'applied index is now lower than readState.Index' (duration: 93.383µs)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:18:18.114143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.421496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-24T07:18:18.114183Z","caller":"traceutil/trace.go:171","msg":"trace[336694379] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1451; }","duration":"198.514689ms","start":"2026-05-24T07:18:17.915657Z","end":"2026-05-24T07:18:18.114171Z","steps":["trace[336694379] 'agreement among raft nodes before linearized reading' (duration: 198.380055ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:18:18.114046Z","caller":"traceutil/trace.go:171","msg":"trace[265033505] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"206.434015ms","start":"2026-05-24T07:18:17.907583Z","end":"2026-05-24T07:18:18.114017Z","steps":["trace[265033505] 'process raft request' (duration: 206.161418ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:18:33.159634Z","caller":"traceutil/trace.go:171","msg":"trace[7675896] transaction","detail":"{read_only:false; response_revision:1519; number_of_response:1; }","duration":"131.41132ms","start":"2026-05-24T07:18:33.028193Z","end":"2026-05-24T07:18:33.159604Z","steps":["trace[7675896] 'process raft request' (duration: 131.203515ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:18:33.278641Z","caller":"traceutil/trace.go:171","msg":"trace[1656156064] transaction","detail":"{read_only:false; response_revision:1520; number_of_response:1; }","duration":"104.30417ms","start":"2026-05-24T07:18:33.174313Z","end":"2026-05-24T07:18:33.278617Z","steps":["trace[1656156064] 'process raft request' (duration: 56.470092ms)","trace[1656156064] 'compare' (duration: 47.696045ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:18:43.379233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.453812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:18:43.379389Z","caller":"traceutil/trace.go:171","msg":"trace[1440525804] linearizableReadLoop","detail":"{readStateIndex:1626; appliedIndex:1625; }","duration":"238.349763ms","start":"2026-05-24T07:18:43.141018Z","end":"2026-05-24T07:18:43.379368Z","steps":["trace[1440525804] 'read index received' (duration: 44.442µs)","trace[1440525804] 'applied index is now lower than readState.Index' (duration: 238.303811ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:18:43.3795Z","caller":"traceutil/trace.go:171","msg":"trace[2009965282] transaction","detail":"{read_only:false; response_revision:1565; number_of_response:1; }","duration":"366.581638ms","start":"2026-05-24T07:18:43.012903Z","end":"2026-05-24T07:18:43.379485Z","steps":["trace[2009965282] 'process raft request' (duration: 19.771697ms)","trace[2009965282] 'compare' (duration: 346.102174ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:18:43.379589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:18:43.012886Z","time spent":"366.657289ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:18:43.379909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.884315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-24T07:18:43.379967Z","caller":"traceutil/trace.go:171","msg":"trace[1729375493] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1565; }","duration":"238.982078ms","start":"2026-05-24T07:18:43.140969Z","end":"2026-05-24T07:18:43.379951Z","steps":["trace[1729375493] 'agreement among raft nodes before linearized reading' (duration: 238.881075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:18:43.380241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.134382ms","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-24T07:18:43.380316Z","caller":"traceutil/trace.go:171","msg":"trace[1854833883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1565; }","duration":"150.265535ms","start":"2026-05-24T07:18:43.230034Z","end":"2026-05-24T07:18:43.3803Z","steps":["trace[1854833883] 'agreement among raft nodes before linearized reading' (duration: 150.05726ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:18:44.150391Z","caller":"traceutil/trace.go:171","msg":"trace[1391462443] transaction","detail":"{read_only:false; response_revision:1570; number_of_response:1; }","duration":"144.58431ms","start":"2026-05-24T07:18:44.005768Z","end":"2026-05-24T07:18:44.150352Z","steps":["trace[1391462443] 'process raft request' (duration: 144.318364ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:18:44.575563Z","caller":"traceutil/trace.go:171","msg":"trace[373880003] transaction","detail":"{read_only:false; response_revision:1572; number_of_response:1; }","duration":"130.241992ms","start":"2026-05-24T07:18:44.445291Z","end":"2026-05-24T07:18:44.575533Z","steps":["trace[373880003] 'process raft request' (duration: 130.078478ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:18:45.147514Z","caller":"traceutil/trace.go:171","msg":"trace[561759273] transaction","detail":"{read_only:false; response_revision:1574; number_of_response:1; }","duration":"171.938317ms","start":"2026-05-24T07:18:44.975547Z","end":"2026-05-24T07:18:45.147485Z","steps":["trace[561759273] 'process raft request' (duration: 134.34058ms)","trace[561759273] 'compare' (duration: 37.454553ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:18:57.984925Z","caller":"traceutil/trace.go:171","msg":"trace[1369023790] transaction","detail":"{read_only:false; response_revision:1632; number_of_response:1; }","duration":"129.700249ms","start":"2026-05-24T07:18:57.855187Z","end":"2026-05-24T07:18:57.984888Z","steps":["trace[1369023790] 'process raft request' (duration: 52.496838ms)","trace[1369023790] 'compare' (duration: 77.033227ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:19:18.191884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.223752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"warn","ts":"2026-05-24T07:19:18.191908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.226734ms","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-24T07:19:18.191989Z","caller":"traceutil/trace.go:171","msg":"trace[971131734] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1723; }","duration":"119.382306ms","start":"2026-05-24T07:19:18.072576Z","end":"2026-05-24T07:19:18.191959Z","steps":["trace[971131734] 'range keys from in-memory index tree' (duration: 119.003177ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:19:18.191997Z","caller":"traceutil/trace.go:171","msg":"trace[1955287423] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1723; }","duration":"128.359018ms","start":"2026-05-24T07:19:18.063619Z","end":"2026-05-24T07:19:18.191978Z","steps":["trace[1955287423] 'range keys from in-memory index tree' (duration: 128.074411ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:19:18.33613Z","caller":"traceutil/trace.go:171","msg":"trace[535602437] transaction","detail":"{read_only:false; response_revision:1724; number_of_response:1; }","duration":"139.948422ms","start":"2026-05-24T07:19:18.196156Z","end":"2026-05-24T07:19:18.336104Z","steps":["trace[535602437] 'process raft request' (duration: 139.784288ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:19:18.371912Z","caller":"traceutil/trace.go:171","msg":"trace[59197275] transaction","detail":"{read_only:false; response_revision:1725; number_of_response:1; }","duration":"173.7865ms","start":"2026-05-24T07:19:18.198108Z","end":"2026-05-24T07:19:18.371895Z","steps":["trace[59197275] 'process raft request' (duration: 173.694428ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:19:18.632601Z","caller":"traceutil/trace.go:171","msg":"trace[1925750002] linearizableReadLoop","detail":"{readStateIndex:1794; appliedIndex:1793; }","duration":"144.436197ms","start":"2026-05-24T07:19:18.488131Z","end":"2026-05-24T07:19:18.632567Z","steps":["trace[1925750002] 'read index received' (duration: 144.243203ms)","trace[1925750002] 'applied index is now lower than readState.Index' (duration: 191.844µs)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:19:18.632848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.695983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-24T07:19:18.632908Z","caller":"traceutil/trace.go:171","msg":"trace[1077051400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1726; }","duration":"144.801246ms","start":"2026-05-24T07:19:18.488096Z","end":"2026-05-24T07:19:18.632897Z","steps":["trace[1077051400] 'agreement among raft nodes before linearized reading' (duration: 144.600691ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:19:18.632653Z","caller":"traceutil/trace.go:171","msg":"trace[1596324325] transaction","detail":"{read_only:false; response_revision:1726; number_of_response:1; }","duration":"199.517626ms","start":"2026-05-24T07:19:18.433105Z","end":"2026-05-24T07:19:18.632622Z","steps":["trace[1596324325] 'process raft request' (duration: 199.285591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:19:18.63316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.724269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-24T07:19:18.633222Z","caller":"traceutil/trace.go:171","msg":"trace[87137725] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1726; }","duration":"101.848232ms","start":"2026-05-24T07:19:18.531357Z","end":"2026-05-24T07:19:18.633206Z","steps":["trace[87137725] 'agreement among raft nodes before linearized reading' (duration: 101.589526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:19:18.947038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.403252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-24T07:19:18.947183Z","caller":"traceutil/trace.go:171","msg":"trace[1754242386] transaction","detail":"{read_only:false; response_revision:1728; number_of_response:1; }","duration":"306.173761ms","start":"2026-05-24T07:19:18.640985Z","end":"2026-05-24T07:19:18.947159Z","steps":["trace[1754242386] 'process raft request' (duration: 98.218137ms)","trace[1754242386] 'compare' (duration: 207.103104ms)"],"step_count":2} {"level":"warn","ts":"2026-05-24T07:19:18.948014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:19:18.64097Z","time spent":"306.303355ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:19:19.31903Z","caller":"traceutil/trace.go:171","msg":"trace[981016472] transaction","detail":"{read_only:false; response_revision:1729; number_of_response:1; }","duration":"270.986073ms","start":"2026-05-24T07:19:19.048Z","end":"2026-05-24T07:19:19.318987Z","steps":["trace[981016472] 'process raft request' (duration: 233.206121ms)","trace[981016472] 'compare' (duration: 37.246909ms)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:19:19.64385Z","caller":"traceutil/trace.go:171","msg":"trace[1162344499] transaction","detail":"{read_only:false; response_revision:1730; number_of_response:1; }","duration":"159.928003ms","start":"2026-05-24T07:19:19.483881Z","end":"2026-05-24T07:19:19.643809Z","steps":["trace[1162344499] 'process raft request' (duration: 159.657347ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:19:20.119205Z","caller":"traceutil/trace.go:171","msg":"trace[664408182] linearizableReadLoop","detail":"{readStateIndex:1799; appliedIndex:1798; }","duration":"368.517284ms","start":"2026-05-24T07:19:19.750655Z","end":"2026-05-24T07:19:20.119172Z","steps":["trace[664408182] 'read index received' (duration: 368.409331ms)","trace[664408182] 'applied index is now lower than readState.Index' (duration: 107.013µs)"],"step_count":2} {"level":"info","ts":"2026-05-24T07:19:20.119347Z","caller":"traceutil/trace.go:171","msg":"trace[801379968] transaction","detail":"{read_only:false; response_revision:1731; number_of_response:1; }","duration":"369.539657ms","start":"2026-05-24T07:19:19.749783Z","end":"2026-05-24T07:19:20.119323Z","steps":["trace[801379968] 'process raft request' (duration: 369.207489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:19:20.119517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:19:19.749752Z","time spent":"369.66412ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-24T07:19:20.119553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.878142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-24T07:19:20.119663Z","caller":"traceutil/trace.go:171","msg":"trace[920391641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1731; }","duration":"369.010035ms","start":"2026-05-24T07:19:19.750602Z","end":"2026-05-24T07:19:20.119612Z","steps":["trace[920391641] 'agreement among raft nodes before linearized reading' (duration: 368.751389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:19:20.119795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:19:19.750591Z","time spent":"369.187429ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-24T07:19:20.20302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.868088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-24T07:19:20.20302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.904176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-24T07:19:20.203066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.066528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-24T07:19:20.203103Z","caller":"traceutil/trace.go:171","msg":"trace[803323618] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1732; }","duration":"301.015491ms","start":"2026-05-24T07:19:19.902069Z","end":"2026-05-24T07:19:20.203085Z","steps":["trace[803323618] 'agreement among raft nodes before linearized reading' (duration: 300.869878ms)"],"step_count":1} {"level":"info","ts":"2026-05-24T07:19:20.203127Z","caller":"traceutil/trace.go:171","msg":"trace[1704189838] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1732; }","duration":"180.18087ms","start":"2026-05-24T07:19:20.022931Z","end":"2026-05-24T07:19:20.203112Z","steps":["trace[1704189838] 'agreement among raft nodes before linearized reading' (duration: 180.003576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:19:20.203152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:19:19.902048Z","time spent":"301.091783ms","remote":"127.0.0.1:44164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-05-24T07:19:20.20306Z","caller":"traceutil/trace.go:171","msg":"trace[652514608] transaction","detail":"{read_only:false; response_revision:1732; number_of_response:1; }","duration":"451.846519ms","start":"2026-05-24T07:19:19.751185Z","end":"2026-05-24T07:19:20.203032Z","steps":["trace[652514608] 'process raft request' (duration: 442.492238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:19:20.204275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:19:19.751169Z","time spent":"452.981275ms","remote":"127.0.0.1:37580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-24T07:19:20.20313Z","caller":"traceutil/trace.go:171","msg":"trace[1273353057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1732; }","duration":"381.08781ms","start":"2026-05-24T07:19:19.822015Z","end":"2026-05-24T07:19:20.203103Z","steps":["trace[1273353057] 'agreement among raft nodes before linearized reading' (duration: 380.874795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-24T07:19:20.204863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-24T07:19:19.821992Z","time spent":"382.832211ms","remote":"127.0.0.1:37316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-24T07:19:20.224793Z","caller":"traceutil/trace.go:171","msg":"trace[387133768] transaction","detail":"{read_only:false; response_revision:1733; number_of_response:1; }","duration":"100.606983ms","start":"2026-05-24T07:19:20.124151Z","end":"2026-05-24T07:19:20.224758Z","steps":["trace[387133768] 'process raft request' (duration: 100.394098ms)"],"step_count":1}