{"level":"warn","ts":"2026-05-06T01:15:28.959267Z","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-06T01:15:28.959352Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129: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.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129: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-06T01:15:28.959418Z","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-06T01:15:28.959431Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-05-06T01:15:28.959447Z","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-06T01:15:28.96008Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-05-06T01:15:28.960149Z","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.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129: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-06T01:15:28.964494Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.140612ms"} {"level":"info","ts":"2026-05-06T01:15:28.973676Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-05-06T01:15:28.973743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-05-06T01:15:28.973766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-05-06T01:15:28.97378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-06T01:15:28.973789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-05-06T01:15:28.973821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-05-06T01:15:28.989643Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-06T01:15:28.991413Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-06T01:15:28.993161Z","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-06T01:15:28.996897Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-06T01:15:28.99711Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-06T01:15:28.997931Z","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-06T01:15:28.99804Z","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-06T01:15:28.998079Z","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-06T01:15:28.998528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-05-06T01:15:28.999204Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-05-06T01:15:29.001545Z","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-06T01:15:29.001768Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-05-06T01:15:29.001826Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-05-06T01:15:29.001966Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-06T01:15:29.002025Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-06T01:15:29.274376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-05-06T01:15:29.274448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-05-06T01:15:29.27449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-05-06T01:15:29.27451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-05-06T01:15:29.27452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-05-06T01:15:29.274537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-05-06T01:15:29.27455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-05-06T01:15:29.275318Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-05-06T01:15:29.275603Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:15:29.276234Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T01:15:29.276657Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T01:15:29.27688Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-06T01:15:29.276908Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-06T01:15:29.27694Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:15:29.277045Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:15:29.277113Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T01:15:29.27973Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-05-06T01:15:29.282509Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-06T01:15:37.685964Z","caller":"traceutil/trace.go:171","msg":"trace[565131404] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"123.492792ms","start":"2026-05-06T01:15:37.562441Z","end":"2026-05-06T01:15:37.685934Z","steps":["trace[565131404] 'process raft request' (duration: 123.071234ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:15:38.079563Z","caller":"traceutil/trace.go:171","msg":"trace[96203965] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:375; }","duration":"123.266778ms","start":"2026-05-06T01:15:37.956267Z","end":"2026-05-06T01:15:38.079533Z","steps":["trace[96203965] 'read index received' (duration: 87.604316ms)","trace[96203965] 'applied index is now lower than readState.Index' (duration: 35.661142ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:15:38.079707Z","caller":"traceutil/trace.go:171","msg":"trace[1804056636] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"164.982253ms","start":"2026-05-06T01:15:37.914677Z","end":"2026-05-06T01:15:38.079659Z","steps":["trace[1804056636] 'process raft request' (duration: 129.306779ms)","trace[1804056636] 'compare' (duration: 35.439328ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:15:38.07981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.515914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-05-06T01:15:38.079917Z","caller":"traceutil/trace.go:171","msg":"trace[424039032] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:364; }","duration":"123.650736ms","start":"2026-05-06T01:15:37.956252Z","end":"2026-05-06T01:15:38.079903Z","steps":["trace[424039032] 'agreement among raft nodes before linearized reading' (duration: 123.480233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:15:38.080168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.555378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-05-06T01:15:38.080223Z","caller":"traceutil/trace.go:171","msg":"trace[1212976488] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:364; }","duration":"102.66477ms","start":"2026-05-06T01:15:37.977545Z","end":"2026-05-06T01:15:38.08021Z","steps":["trace[1212976488] 'agreement among raft nodes before linearized reading' (duration: 102.466697ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:15:38.38508Z","caller":"traceutil/trace.go:171","msg":"trace[883159548] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"293.657761ms","start":"2026-05-06T01:15:38.0914Z","end":"2026-05-06T01:15:38.385058Z","steps":["trace[883159548] 'process raft request' (duration: 293.580829ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:15:38.385118Z","caller":"traceutil/trace.go:171","msg":"trace[1538516439] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"293.762812ms","start":"2026-05-06T01:15:38.091322Z","end":"2026-05-06T01:15:38.385085Z","steps":["trace[1538516439] 'process raft request' (duration: 255.742892ms)","trace[1538516439] 'compare' (duration: 37.790876ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:15:38.385437Z","caller":"traceutil/trace.go:171","msg":"trace[397158761] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"293.362365ms","start":"2026-05-06T01:15:38.092045Z","end":"2026-05-06T01:15:38.385408Z","steps":["trace[397158761] 'process raft request' (duration: 292.985667ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:15:38.385422Z","caller":"traceutil/trace.go:171","msg":"trace[1996775393] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:376; }","duration":"280.251878ms","start":"2026-05-06T01:15:38.105135Z","end":"2026-05-06T01:15:38.385387Z","steps":["trace[1996775393] 'read index received' (duration: 12.209816ms)","trace[1996775393] 'applied index is now lower than readState.Index' (duration: 268.040002ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:15:38.385558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.423282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-06T01:15:38.385617Z","caller":"traceutil/trace.go:171","msg":"trace[1768742742] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:368; }","duration":"280.531484ms","start":"2026-05-06T01:15:38.105074Z","end":"2026-05-06T01:15:38.385605Z","steps":["trace[1768742742] 'agreement among raft nodes before linearized reading' (duration: 280.396762ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:15:38.50154Z","caller":"traceutil/trace.go:171","msg":"trace[801252100] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"105.490198ms","start":"2026-05-06T01:15:38.396032Z","end":"2026-05-06T01:15:38.501522Z","steps":["trace[801252100] 'process raft request' (duration: 93.602256ms)","trace[801252100] 'compare' (duration: 11.719858ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:15:38.501594Z","caller":"traceutil/trace.go:171","msg":"trace[1951169465] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"100.14446ms","start":"2026-05-06T01:15:38.40143Z","end":"2026-05-06T01:15:38.501574Z","steps":["trace[1951169465] 'process raft request' (duration: 100.020807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:15:38.786683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.011122ms","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-06T01:15:38.78675Z","caller":"traceutil/trace.go:171","msg":"trace[399706247] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"108.477619ms","start":"2026-05-06T01:15:38.678242Z","end":"2026-05-06T01:15:38.78672Z","steps":["trace[399706247] 'process raft request' (duration: 30.58329ms)","trace[399706247] 'compare' (duration: 77.735955ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:15:38.786781Z","caller":"traceutil/trace.go:171","msg":"trace[1923483180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:383; }","duration":"161.161366ms","start":"2026-05-06T01:15:38.625596Z","end":"2026-05-06T01:15:38.786758Z","steps":["trace[1923483180] 'range keys from in-memory index tree' (duration: 160.889009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:15:45.152003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128216931s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:15:45.152135Z","caller":"traceutil/trace.go:171","msg":"trace[1753169707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:407; }","duration":"1.128372824s","start":"2026-05-06T01:15:44.023721Z","end":"2026-05-06T01:15:45.152094Z","steps":["trace[1753169707] 'range keys from in-memory index tree' (duration: 1.12815163s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:15:45.152194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.258732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-05-06T01:15:45.152294Z","caller":"traceutil/trace.go:171","msg":"trace[1727762524] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:407; }","duration":"829.392875ms","start":"2026-05-06T01:15:44.322879Z","end":"2026-05-06T01:15:45.152271Z","steps":["trace[1727762524] 'range keys from in-memory index tree' (duration: 829.145371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:15:45.152189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:15:44.023703Z","time spent":"1.128472846s","remote":"127.0.0.1:51950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T01:15:45.152361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:15:44.322859Z","time spent":"829.488977ms","remote":"127.0.0.1:52124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7325,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-05-06T01:15:45.255751Z","caller":"traceutil/trace.go:171","msg":"trace[1094919020] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"442.783782ms","start":"2026-05-06T01:15:44.812933Z","end":"2026-05-06T01:15:45.255716Z","steps":["trace[1094919020] 'read index received' (duration: 442.160288ms)","trace[1094919020] 'applied index is now lower than readState.Index' (duration: 621.924µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:15:45.255895Z","caller":"traceutil/trace.go:171","msg":"trace[834653518] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"918.226007ms","start":"2026-05-06T01:15:44.337656Z","end":"2026-05-06T01:15:45.255882Z","steps":["trace[834653518] 'process raft request' (duration: 917.86608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:15:45.256378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.235941ms","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-06T01:15:45.257101Z","caller":"traceutil/trace.go:171","msg":"trace[876603958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:408; }","duration":"444.194291ms","start":"2026-05-06T01:15:44.812871Z","end":"2026-05-06T01:15:45.257066Z","steps":["trace[876603958] 'agreement among raft nodes before linearized reading' (duration: 443.15589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:15:45.257251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:15:44.812846Z","time spent":"444.371344ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T01:15:45.256629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:15:44.337629Z","time spent":"918.303058ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:15:45.756315Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102161800319128,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T01:15:46.257159Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102161800319128,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T01:15:46.758343Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102161800319128,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T01:15:46.821972Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.566848979s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T01:15:46.837262Z","caller":"traceutil/trace.go:171","msg":"trace[694727875] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"1.675683223s","start":"2026-05-06T01:15:45.161553Z","end":"2026-05-06T01:15:46.837236Z","steps":["trace[694727875] 'process raft request' (duration: 1.66069541s)","trace[694727875] 'compare' (duration: 13.590925ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:15:46.837623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:15:45.16154Z","time spent":"1.675989201s","remote":"127.0.0.1:52124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:15:46.854621Z","caller":"traceutil/trace.go:171","msg":"trace[1807895083] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:422; }","duration":"1.598762636s","start":"2026-05-06T01:15:45.255834Z","end":"2026-05-06T01:15:46.854597Z","steps":["trace[1807895083] 'read index received' (duration: 1.566377689s)","trace[1807895083] 'applied index is now lower than readState.Index' (duration: 32.383417ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:15:46.854672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.69319805s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:15:46.854723Z","caller":"traceutil/trace.go:171","msg":"trace[1227423757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:410; }","duration":"1.693269582s","start":"2026-05-06T01:15:45.161438Z","end":"2026-05-06T01:15:46.854708Z","steps":["trace[1227423757] 'agreement among raft nodes before linearized reading' (duration: 1.69318702s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:15:46.854773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:15:45.161423Z","time spent":"1.693342963s","remote":"127.0.0.1:51960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T01:15:46.854793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.040107069s","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-06T01:15:46.854783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.712952481s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-06T01:15:46.854845Z","caller":"traceutil/trace.go:171","msg":"trace[74815374] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:410; }","duration":"2.04019138s","start":"2026-05-06T01:15:44.814644Z","end":"2026-05-06T01:15:46.854835Z","steps":["trace[74815374] 'agreement among raft nodes before linearized reading' (duration: 2.040094148s)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:15:46.85487Z","caller":"traceutil/trace.go:171","msg":"trace[471399458] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:410; }","duration":"1.713093463s","start":"2026-05-06T01:15:45.141757Z","end":"2026-05-06T01:15:46.85485Z","steps":["trace[471399458] 'agreement among raft nodes before linearized reading' (duration: 1.712925209s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:15:46.854886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:15:44.814636Z","time spent":"2.040241201s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T01:15:46.854926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:15:45.141737Z","time spent":"1.713178865s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T01:15:46.855028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.58810644s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-06T01:15:46.855081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.737901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"warn","ts":"2026-05-06T01:15:46.855072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.457059635s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:15:46.855125Z","caller":"traceutil/trace.go:171","msg":"trace[1613873068] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:410; }","duration":"136.802132ms","start":"2026-05-06T01:15:46.718312Z","end":"2026-05-06T01:15:46.855114Z","steps":["trace[1613873068] 'agreement among raft nodes before linearized reading' (duration: 136.73157ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:15:46.85514Z","caller":"traceutil/trace.go:171","msg":"trace[382053283] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:410; }","duration":"1.457126766s","start":"2026-05-06T01:15:45.398002Z","end":"2026-05-06T01:15:46.855129Z","steps":["trace[382053283] 'agreement among raft nodes before linearized reading' (duration: 1.457048814s)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:15:46.855012Z","caller":"traceutil/trace.go:171","msg":"trace[1852677679] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"1.592793775s","start":"2026-05-06T01:15:45.262182Z","end":"2026-05-06T01:15:46.854976Z","steps":["trace[1852677679] 'process raft request' (duration: 1.592294175s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:15:46.855491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:15:45.262157Z","time spent":"1.593286295s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:15:46.855376Z","caller":"traceutil/trace.go:171","msg":"trace[115665472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:410; }","duration":"1.588222351s","start":"2026-05-06T01:15:45.266875Z","end":"2026-05-06T01:15:46.855097Z","steps":["trace[115665472] 'agreement among raft nodes before linearized reading' (duration: 1.588108439s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:15:46.855708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:15:45.266862Z","time spent":"1.588831795s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T01:16:03.445192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.889555ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:16:03.445306Z","caller":"traceutil/trace.go:171","msg":"trace[1126599267] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"984.930357ms","start":"2026-05-06T01:16:02.460355Z","end":"2026-05-06T01:16:03.445286Z","steps":["trace[1126599267] 'process raft request' (duration: 804.629483ms)","trace[1126599267] 'compare' (duration: 179.641121ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:16:03.445399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:02.460331Z","time spent":"985.027399ms","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T01:16:03.465571Z","caller":"traceutil/trace.go:171","msg":"trace[1591178024] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.001399062s","start":"2026-05-06T01:16:02.46415Z","end":"2026-05-06T01:16:03.465549Z","steps":["trace[1591178024] 'process raft request' (duration: 1.001246768s)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:16:03.46566Z","caller":"traceutil/trace.go:171","msg":"trace[1137778767] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"992.569402ms","start":"2026-05-06T01:16:02.473058Z","end":"2026-05-06T01:16:03.465627Z","steps":["trace[1137778767] 'process raft request' (duration: 992.379099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:03.465726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:02.464119Z","time spent":"1.001532195s","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:16:03.465935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:02.473048Z","time spent":"992.709295ms","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T01:16:03.466078Z","caller":"traceutil/trace.go:171","msg":"trace[281346529] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"991.167654ms","start":"2026-05-06T01:16:02.47486Z","end":"2026-05-06T01:16:03.466028Z","steps":["trace[281346529] 'process raft request' (duration: 990.784107ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:16:03.465553Z","caller":"traceutil/trace.go:171","msg":"trace[1292038648] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.001399112s","start":"2026-05-06T01:16:02.464115Z","end":"2026-05-06T01:16:03.465514Z","steps":["trace[1292038648] 'process raft request' (duration: 1.001184437s)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:16:03.466264Z","caller":"traceutil/trace.go:171","msg":"trace[1512121811] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"991.50504ms","start":"2026-05-06T01:16:02.474737Z","end":"2026-05-06T01:16:03.466242Z","steps":["trace[1512121811] 'process raft request' (duration: 990.862738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:03.466306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:02.47473Z","time spent":"991.560101ms","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T01:16:03.46624Z","caller":"traceutil/trace.go:171","msg":"trace[2104228619] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"992.887279ms","start":"2026-05-06T01:16:02.473171Z","end":"2026-05-06T01:16:03.466058Z","steps":["trace[2104228619] 'process raft request' (duration: 992.305857ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:16:03.466568Z","caller":"traceutil/trace.go:171","msg":"trace[575578662] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"972.929374ms","start":"2026-05-06T01:16:02.49362Z","end":"2026-05-06T01:16:03.466549Z","steps":["trace[575578662] 'process raft request' (duration: 972.170369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:03.466618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.047038ms","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-06T01:16:03.466675Z","caller":"traceutil/trace.go:171","msg":"trace[1489330465] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"991.506731ms","start":"2026-05-06T01:16:02.47516Z","end":"2026-05-06T01:16:03.466667Z","steps":["trace[1489330465] 'process raft request' (duration: 990.542922ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:16:03.466694Z","caller":"traceutil/trace.go:171","msg":"trace[876205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:524; }","duration":"483.1677ms","start":"2026-05-06T01:16:02.983514Z","end":"2026-05-06T01:16:03.466682Z","steps":["trace[876205] 'agreement among raft nodes before linearized reading' (duration: 482.615999ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:03.466701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:02.473166Z","time spent":"993.359888ms","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:16:03.466716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:02.475154Z","time spent":"991.543831ms","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:16:03.466737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:02.983446Z","time spent":"483.280662ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T01:16:03.466208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:02.474855Z","time spent":"991.294116ms","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:16:03.466421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:02.464089Z","time spent":"1.002285219s","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T01:16:03.466793Z","caller":"traceutil/trace.go:171","msg":"trace[1095889072] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"985.286964ms","start":"2026-05-06T01:16:02.481486Z","end":"2026-05-06T01:16:03.466773Z","steps":["trace[1095889072] 'process raft request' (duration: 984.256043ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:16:03.467087Z","caller":"traceutil/trace.go:171","msg":"trace[1133506206] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"837.522601ms","start":"2026-05-06T01:16:02.629546Z","end":"2026-05-06T01:16:03.467069Z","steps":["trace[1133506206] 'process raft request' (duration: 836.440519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:03.466641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:02.493596Z","time spent":"973.013636ms","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:16:03.467181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:02.481479Z","time spent":"985.617291ms","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:16:03.467193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:02.62952Z","time spent":"837.637583ms","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T01:16:03.466099Z","caller":"traceutil/trace.go:171","msg":"trace[151965048] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:531; }","duration":"482.529438ms","start":"2026-05-06T01:16:02.983527Z","end":"2026-05-06T01:16:03.466057Z","steps":["trace[151965048] 'read index received' (duration: 281.544584ms)","trace[151965048] 'applied index is now lower than readState.Index' (duration: 200.984054ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:16:03.466809Z","caller":"traceutil/trace.go:171","msg":"trace[754181597] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"948.082241ms","start":"2026-05-06T01:16:02.518645Z","end":"2026-05-06T01:16:03.466727Z","steps":["trace[754181597] 'process raft request' (duration: 947.250384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:03.467554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:02.518621Z","time spent":"948.891447ms","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:16:03.490743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.046908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-06T01:16:03.490796Z","caller":"traceutil/trace.go:171","msg":"trace[2072673232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:526; }","duration":"479.144879ms","start":"2026-05-06T01:16:03.01164Z","end":"2026-05-06T01:16:03.490785Z","steps":["trace[2072673232] 'agreement among raft nodes before linearized reading' (duration: 479.004336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:03.49082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:03.011624Z","time spent":"479.190631ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T01:16:03.491033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.925411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:16:03.491081Z","caller":"traceutil/trace.go:171","msg":"trace[1930695484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"468.002943ms","start":"2026-05-06T01:16:03.023068Z","end":"2026-05-06T01:16:03.49107Z","steps":["trace[1930695484] 'agreement among raft nodes before linearized reading' (duration: 467.931531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:03.491104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:03.023052Z","time spent":"468.048733ms","remote":"127.0.0.1:51960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T01:16:03.491207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.066624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-06T01:16:03.491235Z","caller":"traceutil/trace.go:171","msg":"trace[2031640805] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:526; }","duration":"468.118455ms","start":"2026-05-06T01:16:03.02311Z","end":"2026-05-06T01:16:03.491228Z","steps":["trace[2031640805] 'agreement among raft nodes before linearized reading' (duration: 468.051633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:03.491263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:03.023097Z","time spent":"468.161475ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T01:16:03.491409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.619664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-06T01:16:03.491432Z","caller":"traceutil/trace.go:171","msg":"trace[609887232] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:526; }","duration":"425.669065ms","start":"2026-05-06T01:16:03.065756Z","end":"2026-05-06T01:16:03.491425Z","steps":["trace[609887232] 'agreement among raft nodes before linearized reading' (duration: 425.171935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:03.491449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:03.065743Z","time spent":"425.701656ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-06T01:16:08.93994Z","caller":"traceutil/trace.go:171","msg":"trace[2138493571] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"401.75234ms","start":"2026-05-06T01:16:08.538164Z","end":"2026-05-06T01:16:08.939917Z","steps":["trace[2138493571] 'process raft request' (duration: 400.587787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:08.940123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:08.538082Z","time spent":"401.971826ms","remote":"127.0.0.1:52042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:16:10.243263Z","caller":"traceutil/trace.go:171","msg":"trace[1705092962] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"219.182821ms","start":"2026-05-06T01:16:10.024036Z","end":"2026-05-06T01:16:10.243219Z","steps":["trace[1705092962] 'read index received' (duration: 211.931114ms)","trace[1705092962] 'applied index is now lower than readState.Index' (duration: 7.250887ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:16:10.243337Z","caller":"traceutil/trace.go:171","msg":"trace[158929825] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"360.477183ms","start":"2026-05-06T01:16:09.88283Z","end":"2026-05-06T01:16:10.243307Z","steps":["trace[158929825] 'process raft request' (duration: 354.272688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:10.243541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:09.882801Z","time spent":"360.648827ms","remote":"127.0.0.1:52042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:16:10.243547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.490528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:16:10.243583Z","caller":"traceutil/trace.go:171","msg":"trace[2018403898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"219.57199ms","start":"2026-05-06T01:16:10.024002Z","end":"2026-05-06T01:16:10.243574Z","steps":["trace[2018403898] 'agreement among raft nodes before linearized reading' (duration: 219.317524ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:16:10.852915Z","caller":"traceutil/trace.go:171","msg":"trace[18930629] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"468.158787ms","start":"2026-05-06T01:16:10.384726Z","end":"2026-05-06T01:16:10.852885Z","steps":["trace[18930629] 'read index received' (duration: 404.919965ms)","trace[18930629] 'applied index is now lower than readState.Index' (duration: 63.237542ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:16:10.853236Z","caller":"traceutil/trace.go:171","msg":"trace[1190617423] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"561.768812ms","start":"2026-05-06T01:16:10.291448Z","end":"2026-05-06T01:16:10.853216Z","steps":["trace[1190617423] 'process raft request' (duration: 498.282116ms)","trace[1190617423] 'compare' (duration: 62.786861ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:16:10.85344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:10.291428Z","time spent":"561.943706ms","remote":"127.0.0.1:52406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:16:10.853852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.080975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-k7m2b\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-06T01:16:10.853931Z","caller":"traceutil/trace.go:171","msg":"trace[1597760660] range","detail":"{range_begin:/registry/pods/kube-system/cilium-k7m2b; range_end:; response_count:1; response_revision:623; }","duration":"469.241528ms","start":"2026-05-06T01:16:10.384669Z","end":"2026-05-06T01:16:10.853911Z","steps":["trace[1597760660] 'agreement among raft nodes before linearized reading' (duration: 468.3168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:10.853976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:10.384647Z","time spent":"469.319591ms","remote":"127.0.0.1:52124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-k7m2b\" "} {"level":"warn","ts":"2026-05-06T01:16:11.906308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.667863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:16:11.906412Z","caller":"traceutil/trace.go:171","msg":"trace[352718332] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"1.037461662s","start":"2026-05-06T01:16:10.868932Z","end":"2026-05-06T01:16:11.906394Z","steps":["trace[352718332] 'process raft request' (duration: 775.620346ms)","trace[352718332] 'compare' (duration: 261.391368ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:16:11.906473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:10.86876Z","time spent":"1.037683196s","remote":"127.0.0.1:52124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:16:12.602296Z","caller":"traceutil/trace.go:171","msg":"trace[136307776] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:647; }","duration":"1.058252873s","start":"2026-05-06T01:16:11.544018Z","end":"2026-05-06T01:16:12.60227Z","steps":["trace[136307776] 'read index received' (duration: 100.579007ms)","trace[136307776] 'applied index is now lower than readState.Index' (duration: 957.672716ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:16:12.602404Z","caller":"traceutil/trace.go:171","msg":"trace[1262006358] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"1.341462932s","start":"2026-05-06T01:16:11.260912Z","end":"2026-05-06T01:16:12.602375Z","steps":["trace[1262006358] 'process raft request' (duration: 1.340789569s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:12.602546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058514948s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-06T01:16:12.602547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:11.260885Z","time spent":"1.341572353s","remote":"127.0.0.1:52042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:16:12.602593Z","caller":"traceutil/trace.go:171","msg":"trace[1268847304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"1.058569619s","start":"2026-05-06T01:16:11.544011Z","end":"2026-05-06T01:16:12.602581Z","steps":["trace[1268847304] 'agreement among raft nodes before linearized reading' (duration: 1.058410956s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:12.602624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:11.543955Z","time spent":"1.05866128s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T01:16:12.620091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040029104s","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-06T01:16:12.620152Z","caller":"traceutil/trace.go:171","msg":"trace[1660407734] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"1.040135696s","start":"2026-05-06T01:16:11.580001Z","end":"2026-05-06T01:16:12.620137Z","steps":["trace[1660407734] 'agreement among raft nodes before linearized reading' (duration: 1.039969993s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:12.620211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:11.57998Z","time spent":"1.040197967s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T01:16:12.620255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025423768s","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-06T01:16:12.620334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.032047823s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-06T01:16:12.620212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.311654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:16:12.620372Z","caller":"traceutil/trace.go:171","msg":"trace[2004030246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:626; }","duration":"1.025552011s","start":"2026-05-06T01:16:11.594778Z","end":"2026-05-06T01:16:12.62033Z","steps":["trace[2004030246] 'agreement among raft nodes before linearized reading' (duration: 1.025229774s)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:16:12.620382Z","caller":"traceutil/trace.go:171","msg":"trace[1835932215] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"1.032112604s","start":"2026-05-06T01:16:11.588256Z","end":"2026-05-06T01:16:12.620369Z","steps":["trace[1835932215] 'agreement among raft nodes before linearized reading' (duration: 1.032020902s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:12.620409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:11.588246Z","time spent":"1.032158294s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T01:16:12.620415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:11.594766Z","time spent":"1.025639762s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-06T01:16:12.620446Z","caller":"traceutil/trace.go:171","msg":"trace[912467673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"597.541408ms","start":"2026-05-06T01:16:12.022849Z","end":"2026-05-06T01:16:12.62039Z","steps":["trace[912467673] 'agreement among raft nodes before linearized reading' (duration: 597.340624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:12.620498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:12.022824Z","time spent":"597.667521ms","remote":"127.0.0.1:51960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T01:16:12.620624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.498939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-06T01:16:12.62068Z","caller":"traceutil/trace.go:171","msg":"trace[2147395385] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:626; }","duration":"303.091591ms","start":"2026-05-06T01:16:12.31757Z","end":"2026-05-06T01:16:12.620661Z","steps":["trace[2147395385] 'agreement among raft nodes before linearized reading' (duration: 302.421258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:12.620741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:12.31755Z","time spent":"303.184273ms","remote":"127.0.0.1:51982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.129\" "} {"level":"info","ts":"2026-05-06T01:16:12.620638Z","caller":"traceutil/trace.go:171","msg":"trace[1682034040] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"720.298225ms","start":"2026-05-06T01:16:11.90033Z","end":"2026-05-06T01:16:12.620629Z","steps":["trace[1682034040] 'process raft request' (duration: 719.5336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:12.620932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:11.900311Z","time spent":"720.54731ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:16:28.447023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.075221ms","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-06T01:16:28.447485Z","caller":"traceutil/trace.go:171","msg":"trace[1188574453] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:788; }","duration":"492.574951ms","start":"2026-05-06T01:16:27.954872Z","end":"2026-05-06T01:16:28.447446Z","steps":["trace[1188574453] 'range keys from in-memory index tree' (duration: 492.041711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:28.447712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.346159ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:16:28.44874Z","caller":"traceutil/trace.go:171","msg":"trace[742360061] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"961.433292ms","start":"2026-05-06T01:16:27.487286Z","end":"2026-05-06T01:16:28.448719Z","steps":["trace[742360061] 'process raft request' (duration: 376.99393ms)","trace[742360061] 'compare' (duration: 582.834439ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:16:28.448756Z","caller":"traceutil/trace.go:171","msg":"trace[1421312037] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"426.145295ms","start":"2026-05-06T01:16:28.022528Z","end":"2026-05-06T01:16:28.448673Z","steps":["trace[1421312037] 'read index received' (duration: 30.491µs)","trace[1421312037] 'applied index is now lower than readState.Index' (duration: 426.109414ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:16:28.448849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:27.48727Z","time spent":"961.527183ms","remote":"127.0.0.1:52124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:16:28.449789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.531172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:16:28.449884Z","caller":"traceutil/trace.go:171","msg":"trace[2113591604] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:789; }","duration":"427.361959ms","start":"2026-05-06T01:16:28.0225Z","end":"2026-05-06T01:16:28.449862Z","steps":["trace[2113591604] 'agreement among raft nodes before linearized reading' (duration: 426.45716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:28.449963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:28.022485Z","time spent":"427.456962ms","remote":"127.0.0.1:51960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T01:16:29.071853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.580566ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-06T01:16:29.071947Z","caller":"traceutil/trace.go:171","msg":"trace[11458952] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"623.056215ms","start":"2026-05-06T01:16:28.448873Z","end":"2026-05-06T01:16:29.07193Z","steps":["trace[11458952] 'read index received' (duration: 187.461369ms)","trace[11458952] 'applied index is now lower than readState.Index' (duration: 435.593336ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:16:29.072102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.667417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T01:16:29.072176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.449291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-05-06T01:16:29.072212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.209238ms","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-06T01:16:29.072258Z","caller":"traceutil/trace.go:171","msg":"trace[1309152146] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:789; }","duration":"360.263769ms","start":"2026-05-06T01:16:28.711982Z","end":"2026-05-06T01:16:29.072245Z","steps":["trace[1309152146] 'agreement among raft nodes before linearized reading' (duration: 360.181048ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:16:29.072265Z","caller":"traceutil/trace.go:171","msg":"trace[250815511] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:789; }","duration":"844.573903ms","start":"2026-05-06T01:16:28.227666Z","end":"2026-05-06T01:16:29.07224Z","steps":["trace[250815511] 'agreement among raft nodes before linearized reading' (duration: 844.320998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:29.072297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:28.711971Z","time spent":"360.318701ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T01:16:29.072319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:28.22765Z","time spent":"844.656915ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-06T01:16:29.072202Z","caller":"traceutil/trace.go:171","msg":"trace[432280633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:789; }","duration":"620.84495ms","start":"2026-05-06T01:16:28.451339Z","end":"2026-05-06T01:16:29.072184Z","steps":["trace[432280633] 'agreement among raft nodes before linearized reading' (duration: 620.693227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:29.072396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.149818ms","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-06T01:16:29.072452Z","caller":"traceutil/trace.go:171","msg":"trace[2227025] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:789; }","duration":"363.227139ms","start":"2026-05-06T01:16:28.709218Z","end":"2026-05-06T01:16:29.072445Z","steps":["trace[2227025] 'agreement among raft nodes before linearized reading' (duration: 363.006085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:29.072443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:28.45132Z","time spent":"621.097855ms","remote":"127.0.0.1:51950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T01:16:29.072142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.28641ms","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-06T01:16:29.072519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:28.709208Z","time spent":"363.301662ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T01:16:29.072609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.867636ms","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-06T01:16:29.072772Z","caller":"traceutil/trace.go:171","msg":"trace[623659592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:789; }","duration":"372.053029ms","start":"2026-05-06T01:16:28.700693Z","end":"2026-05-06T01:16:29.072746Z","steps":["trace[623659592] 'agreement among raft nodes before linearized reading' (duration: 371.753392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:29.072825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:28.700675Z","time spent":"372.13861ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T01:16:29.072614Z","caller":"traceutil/trace.go:171","msg":"trace[839737446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:789; }","duration":"360.75713ms","start":"2026-05-06T01:16:28.711795Z","end":"2026-05-06T01:16:29.072552Z","steps":["trace[839737446] 'agreement among raft nodes before linearized reading' (duration: 360.233149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:29.074354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:16:28.711772Z","time spent":"362.535517ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-06T01:16:39.620246Z","caller":"traceutil/trace.go:171","msg":"trace[291748353] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"108.274118ms","start":"2026-05-06T01:16:39.511946Z","end":"2026-05-06T01:16:39.62022Z","steps":["trace[291748353] 'process raft request' (duration: 108.117825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:39.809935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.917028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:16:39.810039Z","caller":"traceutil/trace.go:171","msg":"trace[1401760375] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"220.650503ms","start":"2026-05-06T01:16:39.589368Z","end":"2026-05-06T01:16:39.810019Z","steps":["trace[1401760375] 'process raft request' (duration: 109.508851ms)","trace[1401760375] 'compare' (duration: 110.804906ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:16:57.445734Z","caller":"traceutil/trace.go:171","msg":"trace[98886182] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1014; }","duration":"153.087063ms","start":"2026-05-06T01:16:57.292625Z","end":"2026-05-06T01:16:57.445712Z","steps":["trace[98886182] 'read index received' (duration: 152.941371ms)","trace[98886182] 'applied index is now lower than readState.Index' (duration: 144.382µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:16:57.445785Z","caller":"traceutil/trace.go:171","msg":"trace[1538267915] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"174.799019ms","start":"2026-05-06T01:16:57.270956Z","end":"2026-05-06T01:16:57.445755Z","steps":["trace[1538267915] 'process raft request' (duration: 174.611636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:57.445945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.301127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-06T01:16:57.445962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.214649ms","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-06T01:16:57.445982Z","caller":"traceutil/trace.go:171","msg":"trace[1672439158] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:979; }","duration":"153.384329ms","start":"2026-05-06T01:16:57.29259Z","end":"2026-05-06T01:16:57.445974Z","steps":["trace[1672439158] 'agreement among raft nodes before linearized reading' (duration: 153.231616ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:16:57.446001Z","caller":"traceutil/trace.go:171","msg":"trace[598782687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:979; }","duration":"151.27752ms","start":"2026-05-06T01:16:57.29471Z","end":"2026-05-06T01:16:57.445988Z","steps":["trace[598782687] 'agreement among raft nodes before linearized reading' (duration: 151.137787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:16:57.445988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.933444ms","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-06T01:16:57.446056Z","caller":"traceutil/trace.go:171","msg":"trace[2060672362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:979; }","duration":"105.039887ms","start":"2026-05-06T01:16:57.341002Z","end":"2026-05-06T01:16:57.446042Z","steps":["trace[2060672362] 'agreement among raft nodes before linearized reading' (duration: 104.867303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:03.99013Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102161800320365,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T01:17:04.107668Z","caller":"traceutil/trace.go:171","msg":"trace[1209047619] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"618.009624ms","start":"2026-05-06T01:17:03.489636Z","end":"2026-05-06T01:17:04.107646Z","steps":["trace[1209047619] 'process raft request' (duration: 617.875601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:04.107805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:03.489617Z","time spent":"618.119845ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:17:04.409811Z","caller":"traceutil/trace.go:171","msg":"trace[684752829] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"919.172196ms","start":"2026-05-06T01:17:03.490609Z","end":"2026-05-06T01:17:04.409782Z","steps":["trace[684752829] 'process raft request' (duration: 918.915291ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:17:04.409841Z","caller":"traceutil/trace.go:171","msg":"trace[1110606756] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"919.265429ms","start":"2026-05-06T01:17:03.490543Z","end":"2026-05-06T01:17:04.409809Z","steps":["trace[1110606756] 'process raft request' (duration: 919.145926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:04.409997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:03.490584Z","time spent":"919.30476ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:17:04.410013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:03.490533Z","time spent":"919.389601ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:17:04.410036Z","caller":"traceutil/trace.go:171","msg":"trace[379591422] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"917.980503ms","start":"2026-05-06T01:17:03.492037Z","end":"2026-05-06T01:17:04.410018Z","steps":["trace[379591422] 'process raft request' (duration: 917.707928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:04.410129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:03.492012Z","time spent":"918.068514ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:17:04.410242Z","caller":"traceutil/trace.go:171","msg":"trace[813830887] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"663.030453ms","start":"2026-05-06T01:17:03.747203Z","end":"2026-05-06T01:17:04.410233Z","steps":["trace[813830887] 'process raft request' (duration: 662.749468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:04.410301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:03.747177Z","time spent":"663.084235ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:17:04.41034Z","caller":"traceutil/trace.go:171","msg":"trace[1213769465] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1040; }","duration":"920.278488ms","start":"2026-05-06T01:17:03.490042Z","end":"2026-05-06T01:17:04.410321Z","steps":["trace[1213769465] 'read index received' (duration: 617.541616ms)","trace[1213769465] 'applied index is now lower than readState.Index' (duration: 302.735592ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:17:04.410353Z","caller":"traceutil/trace.go:171","msg":"trace[1381616996] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"511.621772ms","start":"2026-05-06T01:17:03.898713Z","end":"2026-05-06T01:17:04.410335Z","steps":["trace[1381616996] 'process raft request' (duration: 511.463389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:04.41045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:03.898686Z","time spent":"511.715093ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:17:04.410486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.752896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:17:04.410534Z","caller":"traceutil/trace.go:171","msg":"trace[1143186288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1007; }","duration":"387.799008ms","start":"2026-05-06T01:17:04.022719Z","end":"2026-05-06T01:17:04.410518Z","steps":["trace[1143186288] 'agreement among raft nodes before linearized reading' (duration: 387.727035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:04.410547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.486763ms","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-06T01:17:04.410572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:04.022662Z","time spent":"387.900769ms","remote":"127.0.0.1:51960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T01:17:04.410617Z","caller":"traceutil/trace.go:171","msg":"trace[1215062954] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1007; }","duration":"920.580034ms","start":"2026-05-06T01:17:03.49002Z","end":"2026-05-06T01:17:04.4106Z","steps":["trace[1215062954] 'agreement among raft nodes before linearized reading' (duration: 920.40754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:04.410656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:03.490011Z","time spent":"920.638005ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-06T01:17:06.890524Z","caller":"traceutil/trace.go:171","msg":"trace[105165753] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1047; }","duration":"474.734147ms","start":"2026-05-06T01:17:06.415769Z","end":"2026-05-06T01:17:06.890503Z","steps":["trace[105165753] 'read index received' (duration: 474.601035ms)","trace[105165753] 'applied index is now lower than readState.Index' (duration: 132.092µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:17:06.890637Z","caller":"traceutil/trace.go:171","msg":"trace[1622813407] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"775.270429ms","start":"2026-05-06T01:17:06.115354Z","end":"2026-05-06T01:17:06.890625Z","steps":["trace[1622813407] 'process raft request' (duration: 774.980133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:06.890745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:06.115337Z","time spent":"775.324669ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:17:06.890907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.119764ms","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-06T01:17:06.890979Z","caller":"traceutil/trace.go:171","msg":"trace[1926866831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1009; }","duration":"475.228877ms","start":"2026-05-06T01:17:06.415734Z","end":"2026-05-06T01:17:06.890962Z","steps":["trace[1926866831] 'agreement among raft nodes before linearized reading' (duration: 475.036923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:06.89102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:06.415718Z","time spent":"475.290578ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T01:17:06.91026Z","caller":"traceutil/trace.go:171","msg":"trace[276084295] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"263.158877ms","start":"2026-05-06T01:17:06.647058Z","end":"2026-05-06T01:17:06.910217Z","steps":["trace[276084295] 'process raft request' (duration: 263.085546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:06.910317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.987525ms","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-06T01:17:06.910402Z","caller":"traceutil/trace.go:171","msg":"trace[97493789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1011; }","duration":"494.088097ms","start":"2026-05-06T01:17:06.416291Z","end":"2026-05-06T01:17:06.910379Z","steps":["trace[97493789] 'agreement among raft nodes before linearized reading' (duration: 493.895163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:06.910494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:06.416285Z","time spent":"494.19049ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-06T01:17:06.91053Z","caller":"traceutil/trace.go:171","msg":"trace[955483160] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"493.828082ms","start":"2026-05-06T01:17:06.416675Z","end":"2026-05-06T01:17:06.910504Z","steps":["trace[955483160] 'process raft request' (duration: 493.354513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:06.910624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.023671ms","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-06T01:17:06.910642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:06.416648Z","time spent":"493.924574ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:17:06.910636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.032126ms","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-06T01:17:06.910677Z","caller":"traceutil/trace.go:171","msg":"trace[1021206235] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1011; }","duration":"444.124553ms","start":"2026-05-06T01:17:06.466539Z","end":"2026-05-06T01:17:06.910663Z","steps":["trace[1021206235] 'agreement among raft nodes before linearized reading' (duration: 443.98156ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:17:06.910701Z","caller":"traceutil/trace.go:171","msg":"trace[1883750565] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1011; }","duration":"494.143718ms","start":"2026-05-06T01:17:06.416532Z","end":"2026-05-06T01:17:06.910676Z","steps":["trace[1883750565] 'agreement among raft nodes before linearized reading' (duration: 493.934114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:06.910712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:06.466517Z","time spent":"444.186744ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T01:17:06.910741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:06.416521Z","time spent":"494.207739ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-06T01:17:14.585645Z","caller":"traceutil/trace.go:171","msg":"trace[588714603] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"108.113835ms","start":"2026-05-06T01:17:14.477515Z","end":"2026-05-06T01:17:14.585628Z","steps":["trace[588714603] 'process raft request' (duration: 108.011323ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:17:23.238104Z","caller":"traceutil/trace.go:171","msg":"trace[301183010] linearizableReadLoop","detail":"{readStateIndex:1107; appliedIndex:1106; }","duration":"119.650476ms","start":"2026-05-06T01:17:23.118439Z","end":"2026-05-06T01:17:23.23809Z","steps":["trace[301183010] 'read index received' (duration: 114.424396ms)","trace[301183010] 'applied index is now lower than readState.Index' (duration: 5.22555ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:17:23.23818Z","caller":"traceutil/trace.go:171","msg":"trace[1434559491] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"129.689377ms","start":"2026-05-06T01:17:23.108443Z","end":"2026-05-06T01:17:23.238133Z","steps":["trace[1434559491] 'process raft request' (duration: 124.426117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:23.23827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.820319ms","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-06T01:17:23.238319Z","caller":"traceutil/trace.go:171","msg":"trace[963967656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1064; }","duration":"119.901831ms","start":"2026-05-06T01:17:23.118409Z","end":"2026-05-06T01:17:23.238311Z","steps":["trace[963967656] 'agreement among raft nodes before linearized reading' (duration: 119.773188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:23.238435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.176275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-06T01:17:23.238456Z","caller":"traceutil/trace.go:171","msg":"trace[176046089] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1064; }","duration":"109.213866ms","start":"2026-05-06T01:17:23.129237Z","end":"2026-05-06T01:17:23.238451Z","steps":["trace[176046089] 'agreement among raft nodes before linearized reading' (duration: 109.152855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:23.238535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.865916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-06T01:17:23.238602Z","caller":"traceutil/trace.go:171","msg":"trace[1512122339] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1064; }","duration":"112.956328ms","start":"2026-05-06T01:17:23.125628Z","end":"2026-05-06T01:17:23.238584Z","steps":["trace[1512122339] 'agreement among raft nodes before linearized reading' (duration: 112.718923ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:17:25.255215Z","caller":"traceutil/trace.go:171","msg":"trace[1680189068] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"145.653622ms","start":"2026-05-06T01:17:25.109525Z","end":"2026-05-06T01:17:25.255179Z","steps":["trace[1680189068] 'process raft request' (duration: 101.979268ms)","trace[1680189068] 'compare' (duration: 43.434289ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:17:29.659756Z","caller":"traceutil/trace.go:171","msg":"trace[32044571] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"559.263311ms","start":"2026-05-06T01:17:29.100465Z","end":"2026-05-06T01:17:29.659728Z","steps":["trace[32044571] 'process raft request' (duration: 558.928665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:29.659978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:29.100447Z","time spent":"559.456525ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:17:30.300326Z","caller":"traceutil/trace.go:171","msg":"trace[646381330] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1125; }","duration":"993.643379ms","start":"2026-05-06T01:17:29.306648Z","end":"2026-05-06T01:17:30.300292Z","steps":["trace[646381330] 'read index received' (duration: 352.84342ms)","trace[646381330] 'applied index is now lower than readState.Index' (duration: 640.799029ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:17:30.300484Z","caller":"traceutil/trace.go:171","msg":"trace[369683117] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"1.035288265s","start":"2026-05-06T01:17:29.265185Z","end":"2026-05-06T01:17:30.300473Z","steps":["trace[369683117] 'process raft request' (duration: 935.556209ms)","trace[369683117] 'compare' (duration: 98.885619ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:17:30.300599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:29.265126Z","time spent":"1.035396337s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:17:30.300888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.648531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T01:17:30.300912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.269531ms","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-06T01:17:30.300995Z","caller":"traceutil/trace.go:171","msg":"trace[670463109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1083; }","duration":"994.385482ms","start":"2026-05-06T01:17:29.306602Z","end":"2026-05-06T01:17:30.300988Z","steps":["trace[670463109] 'agreement among raft nodes before linearized reading' (duration: 994.221269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:30.300999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.002386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-06T01:17:30.301104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.347106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T01:17:30.301174Z","caller":"traceutil/trace.go:171","msg":"trace[1767542531] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1083; }","duration":"546.444768ms","start":"2026-05-06T01:17:29.754711Z","end":"2026-05-06T01:17:30.301156Z","steps":["trace[1767542531] 'agreement among raft nodes before linearized reading' (duration: 546.342676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:30.301217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:29.754627Z","time spent":"546.58226ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-06T01:17:30.301217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.763908ms","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":"warn","ts":"2026-05-06T01:17:30.301176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.998172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T01:17:30.301256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.078654ms","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-06T01:17:30.301372Z","caller":"traceutil/trace.go:171","msg":"trace[559055092] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1083; }","duration":"975.206976ms","start":"2026-05-06T01:17:29.326141Z","end":"2026-05-06T01:17:30.301348Z","steps":["trace[559055092] 'agreement among raft nodes before linearized reading' (duration: 974.89294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:30.301439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:29.326137Z","time spent":"975.288088ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T01:17:30.301038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:29.306587Z","time spent":"994.444534ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-06T01:17:30.301469Z","caller":"traceutil/trace.go:171","msg":"trace[1206526262] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1083; }","duration":"153.286497ms","start":"2026-05-06T01:17:30.148017Z","end":"2026-05-06T01:17:30.301304Z","steps":["trace[1206526262] 'agreement among raft nodes before linearized reading' (duration: 152.997672ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:17:30.301275Z","caller":"traceutil/trace.go:171","msg":"trace[1071741458] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1083; }","duration":"975.823458ms","start":"2026-05-06T01:17:29.325434Z","end":"2026-05-06T01:17:30.301257Z","steps":["trace[1071741458] 'agreement among raft nodes before linearized reading' (duration: 975.690246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:30.301715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:29.325429Z","time spent":"976.276447ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-06T01:17:30.301084Z","caller":"traceutil/trace.go:171","msg":"trace[1323483393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1083; }","duration":"982.114388ms","start":"2026-05-06T01:17:29.318951Z","end":"2026-05-06T01:17:30.301065Z","steps":["trace[1323483393] 'agreement among raft nodes before linearized reading' (duration: 981.794522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:30.301894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:29.318946Z","time spent":"982.940604ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T01:17:30.300956Z","caller":"traceutil/trace.go:171","msg":"trace[1217993371] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1083; }","duration":"368.767874ms","start":"2026-05-06T01:17:29.932171Z","end":"2026-05-06T01:17:30.300939Z","steps":["trace[1217993371] 'agreement among raft nodes before linearized reading' (duration: 368.615101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:30.302011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:29.932144Z","time spent":"369.837154ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-06T01:17:30.713029Z","caller":"traceutil/trace.go:171","msg":"trace[674668369] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"407.231268ms","start":"2026-05-06T01:17:30.305769Z","end":"2026-05-06T01:17:30.713Z","steps":["trace[674668369] 'process raft request' (duration: 406.946263ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:17:30.713066Z","caller":"traceutil/trace.go:171","msg":"trace[1701425577] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"406.900651ms","start":"2026-05-06T01:17:30.306129Z","end":"2026-05-06T01:17:30.713029Z","steps":["trace[1701425577] 'process raft request' (duration: 406.792199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:30.713191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:30.306122Z","time spent":"406.990863ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:17:30.713214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:30.305741Z","time spent":"407.36756ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:17:30.713307Z","caller":"traceutil/trace.go:171","msg":"trace[1587495300] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"405.551545ms","start":"2026-05-06T01:17:30.307749Z","end":"2026-05-06T01:17:30.7133Z","steps":["trace[1587495300] 'process raft request' (duration: 405.248719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:30.713346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:30.307739Z","time spent":"405.590006ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:17:32.759393Z","caller":"traceutil/trace.go:171","msg":"trace[1154778305] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1132; }","duration":"437.301563ms","start":"2026-05-06T01:17:32.322066Z","end":"2026-05-06T01:17:32.759368Z","steps":["trace[1154778305] 'read index received' (duration: 376.141594ms)","trace[1154778305] 'applied index is now lower than readState.Index' (duration: 61.158319ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:17:32.759563Z","caller":"traceutil/trace.go:171","msg":"trace[2062998928] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"452.935211ms","start":"2026-05-06T01:17:32.306614Z","end":"2026-05-06T01:17:32.759549Z","steps":["trace[2062998928] 'process raft request' (duration: 391.696551ms)","trace[2062998928] 'compare' (duration: 60.562287ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:17:32.759707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:32.306589Z","time spent":"453.024923ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:17:32.759892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.610452ms","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-06T01:17:32.759992Z","caller":"traceutil/trace.go:171","msg":"trace[1387646515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1089; }","duration":"400.731604ms","start":"2026-05-06T01:17:32.359233Z","end":"2026-05-06T01:17:32.759964Z","steps":["trace[1387646515] 'agreement among raft nodes before linearized reading' (duration: 400.50746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:32.760054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:32.359222Z","time spent":"400.818465ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T01:17:32.760398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.249364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T01:17:32.760478Z","caller":"traceutil/trace.go:171","msg":"trace[595311253] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1089; }","duration":"156.389318ms","start":"2026-05-06T01:17:32.60407Z","end":"2026-05-06T01:17:32.76046Z","steps":["trace[595311253] 'agreement among raft nodes before linearized reading' (duration: 156.202134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:32.760495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.426155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-06T01:17:32.76055Z","caller":"traceutil/trace.go:171","msg":"trace[2024180141] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:1089; }","duration":"438.507486ms","start":"2026-05-06T01:17:32.322025Z","end":"2026-05-06T01:17:32.760533Z","steps":["trace[2024180141] 'agreement among raft nodes before linearized reading' (duration: 438.387224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:32.760604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:32.322014Z","time spent":"438.578478ms","remote":"127.0.0.1:51982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.129\" "} {"level":"info","ts":"2026-05-06T01:17:33.346558Z","caller":"traceutil/trace.go:171","msg":"trace[1728602433] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"581.831352ms","start":"2026-05-06T01:17:32.764702Z","end":"2026-05-06T01:17:33.346533Z","steps":["trace[1728602433] 'process raft request' (duration: 581.599388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:33.346702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:32.764687Z","time spent":"581.940854ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:17:33.346916Z","caller":"traceutil/trace.go:171","msg":"trace[359275242] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"581.13383ms","start":"2026-05-06T01:17:32.765759Z","end":"2026-05-06T01:17:33.346893Z","steps":["trace[359275242] 'process raft request' (duration: 581.077419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:33.347057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:32.765746Z","time spent":"581.241342ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:17:33.346999Z","caller":"traceutil/trace.go:171","msg":"trace[795633633] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"581.781251ms","start":"2026-05-06T01:17:32.765191Z","end":"2026-05-06T01:17:33.346972Z","steps":["trace[795633633] 'process raft request' (duration: 581.548857ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:17:33.347165Z","caller":"traceutil/trace.go:171","msg":"trace[1072786511] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"582.304102ms","start":"2026-05-06T01:17:32.764798Z","end":"2026-05-06T01:17:33.347102Z","steps":["trace[1072786511] 'process raft request' (duration: 581.69484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:33.347318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:32.764781Z","time spent":"582.464295ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:17:33.347239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:32.765176Z","time spent":"581.932425ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:17:33.364083Z","caller":"traceutil/trace.go:171","msg":"trace[1661351547] linearizableReadLoop","detail":"{readStateIndex:1139; appliedIndex:1134; }","duration":"407.516324ms","start":"2026-05-06T01:17:32.95654Z","end":"2026-05-06T01:17:33.364057Z","steps":["trace[1661351547] 'read index received' (duration: 389.767674ms)","trace[1661351547] 'applied index is now lower than readState.Index' (duration: 17.74747ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:17:33.364128Z","caller":"traceutil/trace.go:171","msg":"trace[1355355494] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"531.88751ms","start":"2026-05-06T01:17:32.832221Z","end":"2026-05-06T01:17:33.364108Z","steps":["trace[1355355494] 'process raft request' (duration: 531.687886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:33.364246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.707747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:17:33.364288Z","caller":"traceutil/trace.go:171","msg":"trace[758764842] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1094; }","duration":"407.806049ms","start":"2026-05-06T01:17:32.956472Z","end":"2026-05-06T01:17:33.364278Z","steps":["trace[758764842] 'agreement among raft nodes before linearized reading' (duration: 407.702987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:33.364318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:32.956451Z","time spent":"407.86022ms","remote":"127.0.0.1:51960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T01:17:33.364331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:32.832194Z","time spent":"532.075753ms","remote":"127.0.0.1:51982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:17:33.364347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.386301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:17:33.364404Z","caller":"traceutil/trace.go:171","msg":"trace[1236106332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1094; }","duration":"341.444162ms","start":"2026-05-06T01:17:33.02295Z","end":"2026-05-06T01:17:33.364394Z","steps":["trace[1236106332] 'agreement among raft nodes before linearized reading' (duration: 341.32222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:33.364446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:33.022871Z","time spent":"341.552583ms","remote":"127.0.0.1:51950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T01:17:35.46833Z","caller":"traceutil/trace.go:171","msg":"trace[1563366624] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1141; }","duration":"117.019205ms","start":"2026-05-06T01:17:35.35129Z","end":"2026-05-06T01:17:35.46831Z","steps":["trace[1563366624] 'read index received' (duration: 116.894952ms)","trace[1563366624] 'applied index is now lower than readState.Index' (duration: 123.363µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:17:35.468385Z","caller":"traceutil/trace.go:171","msg":"trace[670906611] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"233.727464ms","start":"2026-05-06T01:17:35.234637Z","end":"2026-05-06T01:17:35.468364Z","steps":["trace[670906611] 'process raft request' (duration: 233.53772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:35.468599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.28334ms","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-06T01:17:35.468692Z","caller":"traceutil/trace.go:171","msg":"trace[761476242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1097; }","duration":"117.428913ms","start":"2026-05-06T01:17:35.351245Z","end":"2026-05-06T01:17:35.468674Z","steps":["trace[761476242] 'agreement among raft nodes before linearized reading' (duration: 117.183488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:35.468697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.2551ms","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-06T01:17:35.468759Z","caller":"traceutil/trace.go:171","msg":"trace[759785237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1097; }","duration":"117.350141ms","start":"2026-05-06T01:17:35.351396Z","end":"2026-05-06T01:17:35.468746Z","steps":["trace[759785237] 'agreement among raft nodes before linearized reading' (duration: 117.164658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:35.468777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.997405ms","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-06T01:17:35.468837Z","caller":"traceutil/trace.go:171","msg":"trace[1193825349] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1097; }","duration":"117.101897ms","start":"2026-05-06T01:17:35.351719Z","end":"2026-05-06T01:17:35.468821Z","steps":["trace[1193825349] 'agreement among raft nodes before linearized reading' (duration: 116.910883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:35.469433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.050295ms","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-06T01:17:35.469547Z","caller":"traceutil/trace.go:171","msg":"trace[1350705931] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1097; }","duration":"117.207018ms","start":"2026-05-06T01:17:35.352314Z","end":"2026-05-06T01:17:35.469521Z","steps":["trace[1350705931] 'agreement among raft nodes before linearized reading' (duration: 116.247751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:35.829005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.060237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:17:35.829107Z","caller":"traceutil/trace.go:171","msg":"trace[1770817383] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"356.066871ms","start":"2026-05-06T01:17:35.473022Z","end":"2026-05-06T01:17:35.829089Z","steps":["trace[1770817383] 'process raft request' (duration: 183.842841ms)","trace[1770817383] 'compare' (duration: 171.960205ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:17:35.829199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:35.473001Z","time spent":"356.168702ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:17:36.107946Z","caller":"traceutil/trace.go:171","msg":"trace[1372103841] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1142; }","duration":"461.454883ms","start":"2026-05-06T01:17:35.64647Z","end":"2026-05-06T01:17:36.107925Z","steps":["trace[1372103841] 'read index received' (duration: 10.406178ms)","trace[1372103841] 'applied index is now lower than readState.Index' (duration: 451.047755ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:17:36.108206Z","caller":"traceutil/trace.go:171","msg":"trace[813189810] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"634.977528ms","start":"2026-05-06T01:17:35.473215Z","end":"2026-05-06T01:17:36.108193Z","steps":["trace[813189810] 'process raft request' (duration: 571.367053ms)","trace[813189810] 'compare' (duration: 63.125085ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:17:36.108309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:35.473191Z","time spent":"635.05156ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:17:36.108413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.910843ms","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-06T01:17:36.108497Z","caller":"traceutil/trace.go:171","msg":"trace[1944068277] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1101; }","duration":"462.052165ms","start":"2026-05-06T01:17:35.646427Z","end":"2026-05-06T01:17:36.10848Z","steps":["trace[1944068277] 'agreement among raft nodes before linearized reading' (duration: 461.881692ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:17:36.108621Z","caller":"traceutil/trace.go:171","msg":"trace[874829773] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"635.303155ms","start":"2026-05-06T01:17:35.473301Z","end":"2026-05-06T01:17:36.108604Z","steps":["trace[874829773] 'process raft request' (duration: 634.54232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:36.108698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:35.473283Z","time spent":"635.376826ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:17:36.108552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:35.646409Z","time spent":"462.132667ms","remote":"127.0.0.1:52086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-05-06T01:17:36.108827Z","caller":"traceutil/trace.go:171","msg":"trace[1593104232] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"635.07767ms","start":"2026-05-06T01:17:35.473715Z","end":"2026-05-06T01:17:36.108793Z","steps":["trace[1593104232] 'process raft request' (duration: 634.167603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:36.109072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:35.473696Z","time spent":"635.263273ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:17:36.109177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.747482ms","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-06T01:17:36.109224Z","caller":"traceutil/trace.go:171","msg":"trace[918071764] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1101; }","duration":"313.838664ms","start":"2026-05-06T01:17:35.795373Z","end":"2026-05-06T01:17:36.109212Z","steps":["trace[918071764] 'agreement among raft nodes before linearized reading' (duration: 313.137441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:36.109257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:35.795345Z","time spent":"313.905476ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T01:17:36.571915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.244477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:17:36.572131Z","caller":"traceutil/trace.go:171","msg":"trace[1597517502] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"458.722472ms","start":"2026-05-06T01:17:36.113379Z","end":"2026-05-06T01:17:36.572102Z","steps":["trace[1597517502] 'process raft request' (duration: 94.217891ms)","trace[1597517502] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-scheduler; req_size:460; } (duration: 363.814068ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:17:36.572325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:36.113364Z","time spent":"458.881355ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:17:42.857554Z","caller":"traceutil/trace.go:171","msg":"trace[1732298739] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"244.458935ms","start":"2026-05-06T01:17:42.613069Z","end":"2026-05-06T01:17:42.857528Z","steps":["trace[1732298739] 'process raft request' (duration: 244.310482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:43.197045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.942971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:17:43.197181Z","caller":"traceutil/trace.go:171","msg":"trace[1656408312] linearizableReadLoop","detail":"{readStateIndex:1170; appliedIndex:1169; }","duration":"240.718494ms","start":"2026-05-06T01:17:42.956446Z","end":"2026-05-06T01:17:43.197165Z","steps":["trace[1656408312] 'read index received' (duration: 70.528598ms)","trace[1656408312] 'applied index is now lower than readState.Index' (duration: 170.188675ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:17:43.197295Z","caller":"traceutil/trace.go:171","msg":"trace[1347452298] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"354.918085ms","start":"2026-05-06T01:17:42.842331Z","end":"2026-05-06T01:17:43.197249Z","steps":["trace[1347452298] 'process raft request' (duration: 184.713239ms)","trace[1347452298] 'compare' (duration: 169.800238ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:17:43.197432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.164641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T01:17:43.197392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.947117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:17:43.197511Z","caller":"traceutil/trace.go:171","msg":"trace[1169458419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1123; }","duration":"241.093091ms","start":"2026-05-06T01:17:42.956406Z","end":"2026-05-06T01:17:43.197499Z","steps":["trace[1169458419] 'agreement among raft nodes before linearized reading' (duration: 240.888347ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:17:43.197508Z","caller":"traceutil/trace.go:171","msg":"trace[1713050017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1123; }","duration":"174.249972ms","start":"2026-05-06T01:17:43.023242Z","end":"2026-05-06T01:17:43.197491Z","steps":["trace[1713050017] 'agreement among raft nodes before linearized reading' (duration: 174.12561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:43.197453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:42.842306Z","time spent":"355.060288ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:17:44.078624Z","caller":"traceutil/trace.go:171","msg":"trace[1185573722] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"125.657177ms","start":"2026-05-06T01:17:43.952938Z","end":"2026-05-06T01:17:44.078595Z","steps":["trace[1185573722] 'process raft request' (duration: 125.343632ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:17:50.401441Z","caller":"traceutil/trace.go:171","msg":"trace[880124313] linearizableReadLoop","detail":"{readStateIndex:1246; appliedIndex:1245; }","duration":"113.927175ms","start":"2026-05-06T01:17:50.287498Z","end":"2026-05-06T01:17:50.401426Z","steps":["trace[880124313] 'read index received' (duration: 113.839484ms)","trace[880124313] 'applied index is now lower than readState.Index' (duration: 87.231µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:17:50.401508Z","caller":"traceutil/trace.go:171","msg":"trace[1800460988] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"126.572594ms","start":"2026-05-06T01:17:50.274929Z","end":"2026-05-06T01:17:50.401501Z","steps":["trace[1800460988] 'process raft request' (duration: 126.410631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:50.40163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.129659ms","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-06T01:17:50.401644Z","caller":"traceutil/trace.go:171","msg":"trace[1632374543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1196; }","duration":"114.19174ms","start":"2026-05-06T01:17:50.287448Z","end":"2026-05-06T01:17:50.40164Z","steps":["trace[1632374543] 'agreement among raft nodes before linearized reading' (duration: 114.120629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:50.401717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.644539ms","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-06T01:17:50.401737Z","caller":"traceutil/trace.go:171","msg":"trace[1642781643] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1196; }","duration":"106.703009ms","start":"2026-05-06T01:17:50.295027Z","end":"2026-05-06T01:17:50.40173Z","steps":["trace[1642781643] 'agreement among raft nodes before linearized reading' (duration: 106.655428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:50.892798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.405684ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:17:50.89313Z","caller":"traceutil/trace.go:171","msg":"trace[1002147164] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"487.93075ms","start":"2026-05-06T01:17:50.405188Z","end":"2026-05-06T01:17:50.893119Z","steps":["trace[1002147164] 'process raft request' (duration: 106.155539ms)","trace[1002147164] 'compare' (duration: 381.123409ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:17:50.893181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:50.405173Z","time spent":"487.991741ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:17:50.893244Z","caller":"traceutil/trace.go:171","msg":"trace[263947313] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"487.481572ms","start":"2026-05-06T01:17:50.405757Z","end":"2026-05-06T01:17:50.893239Z","steps":["trace[263947313] 'process raft request' (duration: 487.107545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:50.893265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:50.405749Z","time spent":"487.504082ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:17:51.44112Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102161800320814,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T01:17:52.300944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.736051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:17:52.301325Z","caller":"traceutil/trace.go:171","msg":"trace[1055407243] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"1.405143747s","start":"2026-05-06T01:17:50.896161Z","end":"2026-05-06T01:17:52.301305Z","steps":["trace[1055407243] 'process raft request' (duration: 851.765294ms)","trace[1055407243] 'compare' (duration: 552.485925ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:17:52.301959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:50.896141Z","time spent":"1.405758478s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:17:52.304502Z","caller":"traceutil/trace.go:171","msg":"trace[896428945] linearizableReadLoop","detail":"{readStateIndex:1250; appliedIndex:1248; }","duration":"1.364411149s","start":"2026-05-06T01:17:50.940062Z","end":"2026-05-06T01:17:52.304473Z","steps":["trace[896428945] 'read index received' (duration: 807.851276ms)","trace[896428945] 'applied index is now lower than readState.Index' (duration: 556.558913ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:17:52.304587Z","caller":"traceutil/trace.go:171","msg":"trace[1691066039] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"1.08306495s","start":"2026-05-06T01:17:51.221505Z","end":"2026-05-06T01:17:52.30457Z","steps":["trace[1691066039] 'process raft request' (duration: 1.082741704s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:52.304639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:51.221482Z","time spent":"1.083133331s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:17:52.304699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.282116269s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T01:17:52.304711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.364626943s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T01:17:52.304734Z","caller":"traceutil/trace.go:171","msg":"trace[1133195664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1200; }","duration":"1.28218621s","start":"2026-05-06T01:17:51.022539Z","end":"2026-05-06T01:17:52.304725Z","steps":["trace[1133195664] 'agreement among raft nodes before linearized reading' (duration: 1.282132109s)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:17:52.304749Z","caller":"traceutil/trace.go:171","msg":"trace[1431561253] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1200; }","duration":"1.364728155s","start":"2026-05-06T01:17:50.940012Z","end":"2026-05-06T01:17:52.30474Z","steps":["trace[1431561253] 'agreement among raft nodes before linearized reading' (duration: 1.364600103s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:52.304757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:51.02252Z","time spent":"1.282231521s","remote":"127.0.0.1:51960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T01:17:52.304775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:50.939994Z","time spent":"1.364774835s","remote":"127.0.0.1:52144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-05-06T01:17:52.304927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.339186ms","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-06T01:17:52.304956Z","caller":"traceutil/trace.go:171","msg":"trace[902199246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1200; }","duration":"198.420078ms","start":"2026-05-06T01:17:52.106529Z","end":"2026-05-06T01:17:52.30495Z","steps":["trace[902199246] 'agreement among raft nodes before linearized reading' (duration: 198.329476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:52.305021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.462993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:17:52.305049Z","caller":"traceutil/trace.go:171","msg":"trace[1847718284] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1200; }","duration":"855.522094ms","start":"2026-05-06T01:17:51.449521Z","end":"2026-05-06T01:17:52.305043Z","steps":["trace[1847718284] 'agreement among raft nodes before linearized reading' (duration: 855.480854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:17:52.305069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:17:51.449505Z","time spent":"855.557645ms","remote":"127.0.0.1:55326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-05-06T01:18:06.802909Z","caller":"traceutil/trace.go:171","msg":"trace[791845293] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"313.891582ms","start":"2026-05-06T01:18:06.488991Z","end":"2026-05-06T01:18:06.802883Z","steps":["trace[791845293] 'process raft request' (duration: 313.734959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:06.803064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:06.488971Z","time spent":"314.015944ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:18:08.774383Z","caller":"traceutil/trace.go:171","msg":"trace[1569118844] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"388.393224ms","start":"2026-05-06T01:18:08.385966Z","end":"2026-05-06T01:18:08.774359Z","steps":["trace[1569118844] 'read index received' (duration: 388.220071ms)","trace[1569118844] 'applied index is now lower than readState.Index' (duration: 171.863µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:08.774494Z","caller":"traceutil/trace.go:171","msg":"trace[879300054] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"396.955656ms","start":"2026-05-06T01:18:08.377526Z","end":"2026-05-06T01:18:08.774482Z","steps":["trace[879300054] 'process raft request' (duration: 396.64341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:08.774629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:08.377501Z","time spent":"397.021147ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:18:08.774765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.783913ms","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-06T01:18:08.774841Z","caller":"traceutil/trace.go:171","msg":"trace[444289360] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1260; }","duration":"388.891035ms","start":"2026-05-06T01:18:08.385932Z","end":"2026-05-06T01:18:08.774823Z","steps":["trace[444289360] 'agreement among raft nodes before linearized reading' (duration: 388.703371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:08.774878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:08.38592Z","time spent":"388.949426ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-06T01:18:09.044437Z","caller":"traceutil/trace.go:171","msg":"trace[708204879] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"265.956199ms","start":"2026-05-06T01:18:08.778458Z","end":"2026-05-06T01:18:09.044414Z","steps":["trace[708204879] 'process raft request' (duration: 265.873768ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:09.044585Z","caller":"traceutil/trace.go:171","msg":"trace[930214419] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"308.800896ms","start":"2026-05-06T01:18:08.735756Z","end":"2026-05-06T01:18:09.044557Z","steps":["trace[930214419] 'process raft request' (duration: 229.658776ms)","trace[930214419] 'compare' (duration: 78.694322ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:09.044654Z","caller":"traceutil/trace.go:171","msg":"trace[1196182871] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1314; }","duration":"270.183879ms","start":"2026-05-06T01:18:08.774451Z","end":"2026-05-06T01:18:09.044635Z","steps":["trace[1196182871] 'read index received' (duration: 191.003959ms)","trace[1196182871] 'applied index is now lower than readState.Index' (duration: 79.17803ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:18:09.044724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:08.735734Z","time spent":"308.927778ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:18:09.044771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.239287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:09.04484Z","caller":"traceutil/trace.go:171","msg":"trace[1866991391] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1262; }","duration":"513.365769ms","start":"2026-05-06T01:18:08.531458Z","end":"2026-05-06T01:18:09.044824Z","steps":["trace[1866991391] 'agreement among raft nodes before linearized reading' (duration: 513.231696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:09.044845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.19813ms","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-06T01:18:09.044881Z","caller":"traceutil/trace.go:171","msg":"trace[1919845711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1262; }","duration":"656.23747ms","start":"2026-05-06T01:18:08.388633Z","end":"2026-05-06T01:18:09.044871Z","steps":["trace[1919845711] 'agreement among raft nodes before linearized reading' (duration: 656.094247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:09.044887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:08.531441Z","time spent":"513.435792ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-05-06T01:18:09.044919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:08.388597Z","time spent":"656.315052ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T01:18:09.044922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.052684ms","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-06T01:18:09.044979Z","caller":"traceutil/trace.go:171","msg":"trace[787407228] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1262; }","duration":"238.159007ms","start":"2026-05-06T01:18:08.806794Z","end":"2026-05-06T01:18:09.044953Z","steps":["trace[787407228] 'agreement among raft nodes before linearized reading' (duration: 237.971493ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:09.248668Z","caller":"traceutil/trace.go:171","msg":"trace[1930616350] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"198.407208ms","start":"2026-05-06T01:18:09.050199Z","end":"2026-05-06T01:18:09.248606Z","steps":["trace[1930616350] 'process raft request' (duration: 120.873568ms)","trace[1930616350] 'compare' (duration: 77.321886ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:09.249011Z","caller":"traceutil/trace.go:171","msg":"trace[620454509] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"198.445137ms","start":"2026-05-06T01:18:09.050516Z","end":"2026-05-06T01:18:09.248961Z","steps":["trace[620454509] 'process raft request' (duration: 198.317635ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:09.249045Z","caller":"traceutil/trace.go:171","msg":"trace[250358025] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"198.534999ms","start":"2026-05-06T01:18:09.050428Z","end":"2026-05-06T01:18:09.248963Z","steps":["trace[250358025] 'process raft request' (duration: 198.095731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:11.356712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.705473ms","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-06T01:18:11.356781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.019435ms","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-06T01:18:11.356796Z","caller":"traceutil/trace.go:171","msg":"trace[1975348528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1268; }","duration":"104.801314ms","start":"2026-05-06T01:18:11.251974Z","end":"2026-05-06T01:18:11.356776Z","steps":["trace[1975348528] 'range keys from in-memory index tree' (duration: 104.495269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:11.356806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.592632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T01:18:11.35685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.128152ms","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-06T01:18:11.356882Z","caller":"traceutil/trace.go:171","msg":"trace[697273977] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1268; }","duration":"104.205404ms","start":"2026-05-06T01:18:11.252669Z","end":"2026-05-06T01:18:11.356874Z","steps":["trace[697273977] 'range keys from in-memory index tree' (duration: 103.97407ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:11.356888Z","caller":"traceutil/trace.go:171","msg":"trace[1312114451] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1268; }","duration":"249.719834ms","start":"2026-05-06T01:18:11.107141Z","end":"2026-05-06T01:18:11.356861Z","steps":["trace[1312114451] 'count revisions from in-memory index tree' (duration: 249.51686ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:11.356845Z","caller":"traceutil/trace.go:171","msg":"trace[978307240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1268; }","duration":"289.100066ms","start":"2026-05-06T01:18:11.06773Z","end":"2026-05-06T01:18:11.35683Z","steps":["trace[978307240] 'range keys from in-memory index tree' (duration: 288.893552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:11.357015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.253156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-06T01:18:11.357032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.703534ms","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-06T01:18:11.357095Z","caller":"traceutil/trace.go:171","msg":"trace[120173402] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1268; }","duration":"308.356018ms","start":"2026-05-06T01:18:11.048723Z","end":"2026-05-06T01:18:11.357079Z","steps":["trace[120173402] 'range keys from in-memory index tree' (duration: 308.140144ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:11.357106Z","caller":"traceutil/trace.go:171","msg":"trace[266879236] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1268; }","duration":"103.813626ms","start":"2026-05-06T01:18:11.253276Z","end":"2026-05-06T01:18:11.357089Z","steps":["trace[266879236] 'range keys from in-memory index tree' (duration: 103.579321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:11.357181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:11.048709Z","time spent":"308.41924ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T01:18:12.85601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:12.325555Z","time spent":"530.450271ms","remote":"127.0.0.1:51982","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-06T01:18:13.660066Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102161800320998,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T01:18:13.983775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.555105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:18:13.98393Z","caller":"traceutil/trace.go:171","msg":"trace[1431022246] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"1.126895266s","start":"2026-05-06T01:18:12.857012Z","end":"2026-05-06T01:18:13.983908Z","steps":["trace[1431022246] 'process raft request' (duration: 237.125907ms)","trace[1431022246] 'compare' (duration: 889.194268ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:18:13.984015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:12.85699Z","time spent":"1.126985077s","remote":"127.0.0.1:51982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:18:14.161267Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102161800320998,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T01:18:14.364131Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.269992631s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-06T01:18:14.990809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.370208ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:18:14.990905Z","caller":"traceutil/trace.go:171","msg":"trace[1796640139] linearizableReadLoop","detail":"{readStateIndex:1330; appliedIndex:1329; }","duration":"1.831951305s","start":"2026-05-06T01:18:13.158937Z","end":"2026-05-06T01:18:14.990888Z","steps":["trace[1796640139] 'read index received' (duration: 1.205330213s)","trace[1796640139] 'applied index is now lower than readState.Index' (duration: 626.619912ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:14.990992Z","caller":"traceutil/trace.go:171","msg":"trace[2078694066] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"2.110025142s","start":"2026-05-06T01:18:12.880926Z","end":"2026-05-06T01:18:14.990952Z","steps":["trace[2078694066] 'process raft request' (duration: 1.48339119s)","trace[2078694066] 'compare' (duration: 626.174704ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:18:14.99107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.635388862s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T01:18:14.991114Z","caller":"traceutil/trace.go:171","msg":"trace[2111216707] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1275; }","duration":"1.635460724s","start":"2026-05-06T01:18:13.35564Z","end":"2026-05-06T01:18:14.991101Z","steps":["trace[2111216707] 'agreement among raft nodes before linearized reading' (duration: 1.635412613s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:14.991133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:12.880894Z","time spent":"2.110165645s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:18:14.991153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:13.355621Z","time spent":"1.635522755s","remote":"127.0.0.1:55500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-06T01:18:14.991022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.832089138s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T01:18:14.991323Z","caller":"traceutil/trace.go:171","msg":"trace[1398628855] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1275; }","duration":"1.832421034s","start":"2026-05-06T01:18:13.158888Z","end":"2026-05-06T01:18:14.991309Z","steps":["trace[1398628855] 'agreement among raft nodes before linearized reading' (duration: 1.832086247s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:14.991372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:13.158841Z","time spent":"1.832514526s","remote":"127.0.0.1:55492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-06T01:18:14.991373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005532171s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-05-06T01:18:14.99145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.543055303s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-06T01:18:14.991466Z","caller":"traceutil/trace.go:171","msg":"trace[1452902158] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1275; }","duration":"1.005667592s","start":"2026-05-06T01:18:13.985779Z","end":"2026-05-06T01:18:14.991447Z","steps":["trace[1452902158] 'agreement among raft nodes before linearized reading' (duration: 1.005433808s)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:14.991487Z","caller":"traceutil/trace.go:171","msg":"trace[641903002] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1275; }","duration":"1.543108175s","start":"2026-05-06T01:18:13.448368Z","end":"2026-05-06T01:18:14.991476Z","steps":["trace[641903002] 'agreement among raft nodes before linearized reading' (duration: 1.542977033s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:14.991519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:13.448361Z","time spent":"1.543150756s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T01:18:14.991646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.694525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-06T01:18:14.991679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124597952s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T01:18:14.991719Z","caller":"traceutil/trace.go:171","msg":"trace[1891492811] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1275; }","duration":"1.124674533s","start":"2026-05-06T01:18:13.867033Z","end":"2026-05-06T01:18:14.991707Z","steps":["trace[1891492811] 'agreement among raft nodes before linearized reading' (duration: 1.124593922s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:14.991744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.543569474s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-06T01:18:14.99176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:13.867013Z","time spent":"1.124739205s","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-05-06T01:18:14.991777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.238915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:14.99182Z","caller":"traceutil/trace.go:171","msg":"trace[1845351137] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1275; }","duration":"969.309657ms","start":"2026-05-06T01:18:14.0225Z","end":"2026-05-06T01:18:14.99181Z","steps":["trace[1845351137] 'agreement among raft nodes before linearized reading' (duration: 969.251066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:14.991851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:14.022488Z","time spent":"969.356838ms","remote":"127.0.0.1:51960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T01:18:14.991715Z","caller":"traceutil/trace.go:171","msg":"trace[1807174342] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1275; }","duration":"153.804947ms","start":"2026-05-06T01:18:14.837895Z","end":"2026-05-06T01:18:14.9917Z","steps":["trace[1807174342] 'agreement among raft nodes before linearized reading' (duration: 153.700965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:14.991642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.542738158s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-06T01:18:14.992001Z","caller":"traceutil/trace.go:171","msg":"trace[2021520005] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1275; }","duration":"1.543104544s","start":"2026-05-06T01:18:13.44888Z","end":"2026-05-06T01:18:14.991985Z","steps":["trace[2021520005] 'agreement among raft nodes before linearized reading' (duration: 1.54233904s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:14.992101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:13.448856Z","time spent":"1.543228797s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-06T01:18:14.99178Z","caller":"traceutil/trace.go:171","msg":"trace[1679492523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1275; }","duration":"1.543643615s","start":"2026-05-06T01:18:13.448127Z","end":"2026-05-06T01:18:14.991771Z","steps":["trace[1679492523] 'agreement among raft nodes before linearized reading' (duration: 1.543547534s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:14.992764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:13.448106Z","time spent":"1.544643165s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T01:18:14.992115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.543217576s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-06T01:18:14.992885Z","caller":"traceutil/trace.go:171","msg":"trace[774848050] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1275; }","duration":"1.543980441s","start":"2026-05-06T01:18:13.448888Z","end":"2026-05-06T01:18:14.992869Z","steps":["trace[774848050] 'agreement among raft nodes before linearized reading' (duration: 1.543132855s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:14.992939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:13.448863Z","time spent":"1.544060933s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T01:18:14.991519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:13.985764Z","time spent":"1.005745474s","remote":"127.0.0.1:52106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-05-06T01:18:14.991854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.591059368s","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-06T01:18:14.993207Z","caller":"traceutil/trace.go:171","msg":"trace[1262042200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1275; }","duration":"1.592430554s","start":"2026-05-06T01:18:13.400755Z","end":"2026-05-06T01:18:14.993185Z","steps":["trace[1262042200] 'agreement among raft nodes before linearized reading' (duration: 1.591043818s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:14.993279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:13.400737Z","time spent":"1.592519735s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T01:18:15.506633Z","caller":"traceutil/trace.go:171","msg":"trace[442985631] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"508.700632ms","start":"2026-05-06T01:18:14.99791Z","end":"2026-05-06T01:18:15.506611Z","steps":["trace[442985631] 'process raft request' (duration: 508.643321ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:15.506662Z","caller":"traceutil/trace.go:171","msg":"trace[1750045350] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"509.897335ms","start":"2026-05-06T01:18:14.996727Z","end":"2026-05-06T01:18:15.506624Z","steps":["trace[1750045350] 'process raft request' (duration: 509.607739ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:15.506738Z","caller":"traceutil/trace.go:171","msg":"trace[905574236] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"509.167501ms","start":"2026-05-06T01:18:14.997536Z","end":"2026-05-06T01:18:15.506703Z","steps":["trace[905574236] 'process raft request' (duration: 508.974927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:15.506779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:14.997903Z","time spent":"508.801095ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:18:15.50682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:14.996708Z","time spent":"510.026937ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:18:15.506843Z","caller":"traceutil/trace.go:171","msg":"trace[1951406052] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1331; }","duration":"447.143912ms","start":"2026-05-06T01:18:15.059655Z","end":"2026-05-06T01:18:15.506799Z","steps":["trace[1951406052] 'read index received' (duration: 446.661723ms)","trace[1951406052] 'applied index is now lower than readState.Index' (duration: 479.459µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:18:15.506892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:14.997524Z","time spent":"509.285173ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:18:15.506912Z","caller":"traceutil/trace.go:171","msg":"trace[1495457334] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"508.901126ms","start":"2026-05-06T01:18:14.997952Z","end":"2026-05-06T01:18:15.506853Z","steps":["trace[1495457334] 'process raft request' (duration: 508.628841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:15.507004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.097068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:15.507042Z","caller":"traceutil/trace.go:171","msg":"trace[457971860] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1280; }","duration":"484.1735ms","start":"2026-05-06T01:18:15.022858Z","end":"2026-05-06T01:18:15.507031Z","steps":["trace[457971860] 'agreement among raft nodes before linearized reading' (duration: 484.080078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:15.507041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:14.997948Z","time spent":"509.024068ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:18:15.507092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:15.02285Z","time spent":"484.23423ms","remote":"127.0.0.1:51960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T01:18:15.686988Z","caller":"traceutil/trace.go:171","msg":"trace[1434776289] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1335; }","duration":"180.057971ms","start":"2026-05-06T01:18:15.506907Z","end":"2026-05-06T01:18:15.686965Z","steps":["trace[1434776289] 'read index received' (duration: 106.328353ms)","trace[1434776289] 'applied index is now lower than readState.Index' (duration: 73.728568ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:15.68702Z","caller":"traceutil/trace.go:171","msg":"trace[266378795] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"423.774642ms","start":"2026-05-06T01:18:15.263213Z","end":"2026-05-06T01:18:15.686988Z","steps":["trace[266378795] 'process raft request' (duration: 350.074264ms)","trace[266378795] 'compare' (duration: 73.565325ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:18:15.687193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.796087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-06T01:18:15.687249Z","caller":"traceutil/trace.go:171","msg":"trace[175748947] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1281; }","duration":"625.911349ms","start":"2026-05-06T01:18:15.061326Z","end":"2026-05-06T01:18:15.687237Z","steps":["trace[175748947] 'agreement among raft nodes before linearized reading' (duration: 625.740646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:15.687286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:15.061311Z","time spent":"625.96628ms","remote":"127.0.0.1:52208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-05-06T01:18:15.687351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:15.263188Z","time spent":"423.905375ms","remote":"127.0.0.1:52024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:18:16.130685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.288953ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:18:16.130806Z","caller":"traceutil/trace.go:171","msg":"trace[820307131] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1337; }","duration":"149.25026ms","start":"2026-05-06T01:18:15.981523Z","end":"2026-05-06T01:18:16.130774Z","steps":["trace[820307131] 'read index received' (duration: 43.491µs)","trace[820307131] 'applied index is now lower than readState.Index' (duration: 149.198599ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:16.130887Z","caller":"traceutil/trace.go:171","msg":"trace[482453790] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"366.832609ms","start":"2026-05-06T01:18:15.764044Z","end":"2026-05-06T01:18:16.130877Z","steps":["trace[482453790] 'process raft request' (duration: 29.275321ms)","trace[482453790] 'compare' (duration: 336.993718ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:18:16.130945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:15.764016Z","time spent":"366.902852ms","remote":"127.0.0.1:52024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T01:18:16.131009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.463595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:16.131087Z","caller":"traceutil/trace.go:171","msg":"trace[931660757] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1283; }","duration":"149.595448ms","start":"2026-05-06T01:18:15.981473Z","end":"2026-05-06T01:18:16.131068Z","steps":["trace[931660757] 'agreement among raft nodes before linearized reading' (duration: 149.462055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:16.135865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.57408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:16.135918Z","caller":"traceutil/trace.go:171","msg":"trace[571305563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1284; }","duration":"112.671261ms","start":"2026-05-06T01:18:16.023236Z","end":"2026-05-06T01:18:16.135907Z","steps":["trace[571305563] 'agreement among raft nodes before linearized reading' (duration: 112.58116ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:16.561682Z","caller":"traceutil/trace.go:171","msg":"trace[1823466251] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"109.134146ms","start":"2026-05-06T01:18:16.452522Z","end":"2026-05-06T01:18:16.561656Z","steps":["trace[1823466251] 'process raft request' (duration: 108.783969ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:19.011639Z","caller":"traceutil/trace.go:171","msg":"trace[695534263] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"137.326926ms","start":"2026-05-06T01:18:18.874251Z","end":"2026-05-06T01:18:19.011578Z","steps":["trace[695534263] 'process raft request' (duration: 137.153243ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:19.097996Z","caller":"traceutil/trace.go:171","msg":"trace[2052869106] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"221.994972ms","start":"2026-05-06T01:18:18.875975Z","end":"2026-05-06T01:18:19.09797Z","steps":["trace[2052869106] 'process raft request' (duration: 221.435651ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:19.098282Z","caller":"traceutil/trace.go:171","msg":"trace[1374594007] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"222.283928ms","start":"2026-05-06T01:18:18.875954Z","end":"2026-05-06T01:18:19.098238Z","steps":["trace[1374594007] 'process raft request' (duration: 221.641845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:19.257441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.417719ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:18:19.25752Z","caller":"traceutil/trace.go:171","msg":"trace[2064906188] linearizableReadLoop","detail":"{readStateIndex:1369; appliedIndex:1365; }","duration":"187.281648ms","start":"2026-05-06T01:18:19.070226Z","end":"2026-05-06T01:18:19.257507Z","steps":["trace[2064906188] 'read index received' (duration: 27.261513ms)","trace[2064906188] 'applied index is now lower than readState.Index' (duration: 160.019395ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:18:19.257658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.436901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-06T01:18:19.257695Z","caller":"traceutil/trace.go:171","msg":"trace[1336272126] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1313; }","duration":"187.504732ms","start":"2026-05-06T01:18:19.070184Z","end":"2026-05-06T01:18:19.257689Z","steps":["trace[1336272126] 'agreement among raft nodes before linearized reading' (duration: 187.38696ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:19.258671Z","caller":"traceutil/trace.go:171","msg":"trace[188738582] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"194.497343ms","start":"2026-05-06T01:18:19.064137Z","end":"2026-05-06T01:18:19.258635Z","steps":["trace[188738582] 'process raft request' (duration: 73.82482ms)","trace[188738582] 'compare' (duration: 119.282657ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:19.271714Z","caller":"traceutil/trace.go:171","msg":"trace[20382736] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"169.044214ms","start":"2026-05-06T01:18:19.102657Z","end":"2026-05-06T01:18:19.271701Z","steps":["trace[20382736] 'process raft request' (duration: 168.959713ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:19.656322Z","caller":"traceutil/trace.go:171","msg":"trace[1597904064] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"132.584606ms","start":"2026-05-06T01:18:19.52371Z","end":"2026-05-06T01:18:19.656295Z","steps":["trace[1597904064] 'process raft request' (duration: 71.832542ms)","trace[1597904064] 'compare' (duration: 60.602351ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:19.656593Z","caller":"traceutil/trace.go:171","msg":"trace[581846976] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1372; }","duration":"121.715212ms","start":"2026-05-06T01:18:19.534857Z","end":"2026-05-06T01:18:19.656572Z","steps":["trace[581846976] 'read index received' (duration: 60.629041ms)","trace[581846976] 'applied index is now lower than readState.Index' (duration: 61.085001ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:19.656621Z","caller":"traceutil/trace.go:171","msg":"trace[332509216] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"132.450345ms","start":"2026-05-06T01:18:19.524128Z","end":"2026-05-06T01:18:19.656578Z","steps":["trace[332509216] 'process raft request' (duration: 132.129789ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:19.656676Z","caller":"traceutil/trace.go:171","msg":"trace[559644648] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"132.196891ms","start":"2026-05-06T01:18:19.524456Z","end":"2026-05-06T01:18:19.656653Z","steps":["trace[559644648] 'process raft request' (duration: 132.015657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:19.656728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.847595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:19.656763Z","caller":"traceutil/trace.go:171","msg":"trace[1798082801] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1319; }","duration":"121.925276ms","start":"2026-05-06T01:18:19.534828Z","end":"2026-05-06T01:18:19.656753Z","steps":["trace[1798082801] 'agreement among raft nodes before linearized reading' (duration: 121.852115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:21.778776Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102161800321083,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T01:18:22.234806Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.131625275s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T01:18:22.235468Z","caller":"traceutil/trace.go:171","msg":"trace[662065017] linearizableReadLoop","detail":"{readStateIndex:1376; appliedIndex:1375; }","duration":"957.452683ms","start":"2026-05-06T01:18:21.277992Z","end":"2026-05-06T01:18:22.235445Z","steps":["trace[662065017] 'read index received' (duration: 956.941254ms)","trace[662065017] 'applied index is now lower than readState.Index' (duration: 497.289µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:22.235559Z","caller":"traceutil/trace.go:171","msg":"trace[1707303354] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"1.132501701s","start":"2026-05-06T01:18:21.103047Z","end":"2026-05-06T01:18:22.235549Z","steps":["trace[1707303354] 'process raft request' (duration: 1.13194532s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:22.235669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:21.103018Z","time spent":"1.132569593s","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T01:18:22.235689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.524732ms","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-06T01:18:22.235749Z","caller":"traceutil/trace.go:171","msg":"trace[1886936279] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1320; }","duration":"702.579153ms","start":"2026-05-06T01:18:21.533151Z","end":"2026-05-06T01:18:22.23573Z","steps":["trace[1886936279] 'agreement among raft nodes before linearized reading' (duration: 702.431361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:22.235781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:21.53308Z","time spent":"702.693145ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T01:18:22.235816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.814801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-06T01:18:22.23586Z","caller":"traceutil/trace.go:171","msg":"trace[1460851026] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1320; }","duration":"957.891962ms","start":"2026-05-06T01:18:21.277955Z","end":"2026-05-06T01:18:22.235847Z","steps":["trace[1460851026] 'agreement among raft nodes before linearized reading' (duration: 957.772479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:22.235893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:21.277939Z","time spent":"957.946113ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T01:18:22.23591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.826269ms","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-06T01:18:22.235937Z","caller":"traceutil/trace.go:171","msg":"trace[790362951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1320; }","duration":"573.869839ms","start":"2026-05-06T01:18:21.66206Z","end":"2026-05-06T01:18:22.23593Z","steps":["trace[790362951] 'agreement among raft nodes before linearized reading' (duration: 573.799018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:22.235959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:21.662053Z","time spent":"573.901219ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T01:18:22.235961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.324328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:22.235998Z","caller":"traceutil/trace.go:171","msg":"trace[808494997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1320; }","duration":"213.359459ms","start":"2026-05-06T01:18:22.022629Z","end":"2026-05-06T01:18:22.235988Z","steps":["trace[808494997] 'agreement among raft nodes before linearized reading' (duration: 213.310008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:22.235683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.831498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T01:18:22.236123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.178833ms","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":"warn","ts":"2026-05-06T01:18:22.23614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.94806ms","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-06T01:18:22.236156Z","caller":"traceutil/trace.go:171","msg":"trace[144582138] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1320; }","duration":"575.252235ms","start":"2026-05-06T01:18:21.660895Z","end":"2026-05-06T01:18:22.236147Z","steps":["trace[144582138] 'agreement among raft nodes before linearized reading' (duration: 575.152433ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:18:22.236171Z","caller":"traceutil/trace.go:171","msg":"trace[462738034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1320; }","duration":"575.036041ms","start":"2026-05-06T01:18:21.661126Z","end":"2026-05-06T01:18:22.236162Z","steps":["trace[462738034] 'agreement among raft nodes before linearized reading' (duration: 574.936469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:22.236181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:21.660874Z","time spent":"575.300406ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T01:18:22.236195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:21.661113Z","time spent":"575.076022ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-06T01:18:22.236154Z","caller":"traceutil/trace.go:171","msg":"trace[1393056756] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1320; }","duration":"497.330438ms","start":"2026-05-06T01:18:21.738793Z","end":"2026-05-06T01:18:22.236123Z","steps":["trace[1393056756] 'agreement among raft nodes before linearized reading' (duration: 496.821058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:22.236269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:21.738781Z","time spent":"497.449059ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-05-06T01:18:24.601604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.401051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:18:24.601724Z","caller":"traceutil/trace.go:171","msg":"trace[774350959] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"331.499124ms","start":"2026-05-06T01:18:24.270206Z","end":"2026-05-06T01:18:24.601705Z","steps":["trace[774350959] 'process raft request' (duration: 187.91706ms)","trace[774350959] 'compare' (duration: 143.279418ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:18:24.60181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:18:24.27019Z","time spent":"331.568736ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:18:50.567052Z","caller":"traceutil/trace.go:171","msg":"trace[1006388247] linearizableReadLoop","detail":"{readStateIndex:1537; appliedIndex:1536; }","duration":"152.821737ms","start":"2026-05-06T01:18:50.414208Z","end":"2026-05-06T01:18:50.56703Z","steps":["trace[1006388247] 'read index received' (duration: 152.656544ms)","trace[1006388247] 'applied index is now lower than readState.Index' (duration: 164.153µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:18:50.567149Z","caller":"traceutil/trace.go:171","msg":"trace[349128330] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"174.275935ms","start":"2026-05-06T01:18:50.392852Z","end":"2026-05-06T01:18:50.567128Z","steps":["trace[349128330] 'process raft request' (duration: 174.026801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:50.567322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.080393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:18:50.567434Z","caller":"traceutil/trace.go:171","msg":"trace[1792899582] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1475; }","duration":"153.232275ms","start":"2026-05-06T01:18:50.414177Z","end":"2026-05-06T01:18:50.567409Z","steps":["trace[1792899582] 'agreement among raft nodes before linearized reading' (duration: 153.061322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:18:50.567365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.448365ms","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-06T01:18:50.567536Z","caller":"traceutil/trace.go:171","msg":"trace[1909764734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1475; }","duration":"100.627488ms","start":"2026-05-06T01:18:50.466892Z","end":"2026-05-06T01:18:50.567519Z","steps":["trace[1909764734] 'agreement among raft nodes before linearized reading' (duration: 100.350133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:02.911736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.846726ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:19:02.911827Z","caller":"traceutil/trace.go:171","msg":"trace[1033882451] linearizableReadLoop","detail":"{readStateIndex:1596; appliedIndex:1595; }","duration":"220.935122ms","start":"2026-05-06T01:19:02.690878Z","end":"2026-05-06T01:19:02.911813Z","steps":["trace[1033882451] 'read index received' (duration: 52.850631ms)","trace[1033882451] 'applied index is now lower than readState.Index' (duration: 168.083081ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:19:02.912082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.814687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-06T01:19:02.912124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.228658ms","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-06T01:19:02.912129Z","caller":"traceutil/trace.go:171","msg":"trace[364369239] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1531; }","duration":"214.911779ms","start":"2026-05-06T01:19:02.697204Z","end":"2026-05-06T01:19:02.912116Z","steps":["trace[364369239] 'agreement among raft nodes before linearized reading' (duration: 214.771037ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:02.912203Z","caller":"traceutil/trace.go:171","msg":"trace[1258081960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1531; }","duration":"221.361961ms","start":"2026-05-06T01:19:02.690824Z","end":"2026-05-06T01:19:02.912186Z","steps":["trace[1258081960] 'agreement among raft nodes before linearized reading' (duration: 221.107115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:02.91238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.620084ms","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-06T01:19:02.912411Z","caller":"traceutil/trace.go:171","msg":"trace[1297690095] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1531; }","duration":"124.669764ms","start":"2026-05-06T01:19:02.787732Z","end":"2026-05-06T01:19:02.912402Z","steps":["trace[1297690095] 'agreement among raft nodes before linearized reading' (duration: 124.610553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:02.912474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.819467ms","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-06T01:19:02.912438Z","caller":"traceutil/trace.go:171","msg":"trace[119960294] transaction","detail":"{read_only:false; response_revision:1531; number_of_response:1; }","duration":"277.217887ms","start":"2026-05-06T01:19:02.634623Z","end":"2026-05-06T01:19:02.911841Z","steps":["trace[119960294] 'process raft request' (duration: 109.195398ms)","trace[119960294] 'compare' (duration: 167.409468ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:19:02.912493Z","caller":"traceutil/trace.go:171","msg":"trace[823808539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1531; }","duration":"118.869467ms","start":"2026-05-06T01:19:02.793619Z","end":"2026-05-06T01:19:02.912489Z","steps":["trace[823808539] 'agreement among raft nodes before linearized reading' (duration: 118.826656ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:13.23351Z","caller":"traceutil/trace.go:171","msg":"trace[1565609702] linearizableReadLoop","detail":"{readStateIndex:1645; appliedIndex:1644; }","duration":"209.924188ms","start":"2026-05-06T01:19:13.023562Z","end":"2026-05-06T01:19:13.233486Z","steps":["trace[1565609702] 'read index received' (duration: 209.715784ms)","trace[1565609702] 'applied index is now lower than readState.Index' (duration: 207.484µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:19:13.233669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.097841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:19:13.233637Z","caller":"traceutil/trace.go:171","msg":"trace[242409750] transaction","detail":"{read_only:false; response_revision:1578; number_of_response:1; }","duration":"250.885988ms","start":"2026-05-06T01:19:12.982718Z","end":"2026-05-06T01:19:13.233604Z","steps":["trace[242409750] 'process raft request' (duration: 250.618453ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:13.233714Z","caller":"traceutil/trace.go:171","msg":"trace[327989471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1578; }","duration":"210.156612ms","start":"2026-05-06T01:19:13.023546Z","end":"2026-05-06T01:19:13.233702Z","steps":["trace[327989471] 'agreement among raft nodes before linearized reading' (duration: 210.07636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:13.233995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.527985ms","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-06T01:19:13.234087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.519926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T01:19:13.234174Z","caller":"traceutil/trace.go:171","msg":"trace[1329366107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1578; }","duration":"191.649669ms","start":"2026-05-06T01:19:13.042504Z","end":"2026-05-06T01:19:13.234154Z","steps":["trace[1329366107] 'agreement among raft nodes before linearized reading' (duration: 191.376913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:13.234144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.67373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T01:19:13.233997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.369913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-06T01:19:13.234055Z","caller":"traceutil/trace.go:171","msg":"trace[330887561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1578; }","duration":"193.630466ms","start":"2026-05-06T01:19:13.040409Z","end":"2026-05-06T01:19:13.23404Z","steps":["trace[330887561] 'agreement among raft nodes before linearized reading' (duration: 193.467822ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:13.234272Z","caller":"traceutil/trace.go:171","msg":"trace[252300498] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1578; }","duration":"107.853642ms","start":"2026-05-06T01:19:13.12639Z","end":"2026-05-06T01:19:13.234243Z","steps":["trace[252300498] 'agreement among raft nodes before linearized reading' (duration: 107.615568ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:13.234284Z","caller":"traceutil/trace.go:171","msg":"trace[884564854] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1578; }","duration":"199.684628ms","start":"2026-05-06T01:19:13.034579Z","end":"2026-05-06T01:19:13.234264Z","steps":["trace[884564854] 'agreement among raft nodes before linearized reading' (duration: 199.230849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:13.234802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.608197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-06T01:19:13.234846Z","caller":"traceutil/trace.go:171","msg":"trace[1237743489] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1578; }","duration":"198.670989ms","start":"2026-05-06T01:19:13.036164Z","end":"2026-05-06T01:19:13.234835Z","steps":["trace[1237743489] 'agreement among raft nodes before linearized reading' (duration: 197.867495ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:17.442972Z","caller":"traceutil/trace.go:171","msg":"trace[534791415] transaction","detail":"{read_only:false; response_revision:1595; number_of_response:1; }","duration":"135.774251ms","start":"2026-05-06T01:19:17.307171Z","end":"2026-05-06T01:19:17.442946Z","steps":["trace[534791415] 'process raft request' (duration: 135.529165ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:17.444548Z","caller":"traceutil/trace.go:171","msg":"trace[184206637] transaction","detail":"{read_only:false; response_revision:1596; number_of_response:1; }","duration":"136.76903ms","start":"2026-05-06T01:19:17.307749Z","end":"2026-05-06T01:19:17.444518Z","steps":["trace[184206637] 'process raft request' (duration: 136.544696ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:17.444851Z","caller":"traceutil/trace.go:171","msg":"trace[233471635] transaction","detail":"{read_only:false; response_revision:1597; number_of_response:1; }","duration":"136.953462ms","start":"2026-05-06T01:19:17.307884Z","end":"2026-05-06T01:19:17.444838Z","steps":["trace[233471635] 'process raft request' (duration: 136.587396ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:17.444928Z","caller":"traceutil/trace.go:171","msg":"trace[2006889128] transaction","detail":"{read_only:false; response_revision:1598; number_of_response:1; }","duration":"136.463493ms","start":"2026-05-06T01:19:17.308424Z","end":"2026-05-06T01:19:17.444887Z","steps":["trace[2006889128] 'process raft request' (duration: 136.225299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:19:18.035234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.621571ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-06T01:19:20.618753Z","caller":"traceutil/trace.go:171","msg":"trace[1075582775] transaction","detail":"{read_only:false; response_revision:1612; number_of_response:1; }","duration":"121.060278ms","start":"2026-05-06T01:19:20.49767Z","end":"2026-05-06T01:19:20.61873Z","steps":["trace[1075582775] 'process raft request' (duration: 120.907276ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:21.165747Z","caller":"traceutil/trace.go:171","msg":"trace[1840799633] transaction","detail":"{read_only:false; response_revision:1613; number_of_response:1; }","duration":"106.400946ms","start":"2026-05-06T01:19:21.059324Z","end":"2026-05-06T01:19:21.165725Z","steps":["trace[1840799633] 'process raft request' (duration: 106.259262ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:21.625552Z","caller":"traceutil/trace.go:171","msg":"trace[1276662240] transaction","detail":"{read_only:false; response_revision:1616; number_of_response:1; }","duration":"165.115446ms","start":"2026-05-06T01:19:21.46041Z","end":"2026-05-06T01:19:21.625525Z","steps":["trace[1276662240] 'process raft request' (duration: 164.690908ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:21.62638Z","caller":"traceutil/trace.go:171","msg":"trace[1974542876] transaction","detail":"{read_only:false; response_revision:1617; number_of_response:1; }","duration":"164.950853ms","start":"2026-05-06T01:19:21.461399Z","end":"2026-05-06T01:19:21.62635Z","steps":["trace[1974542876] 'process raft request' (duration: 164.251579ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:19:21.6265Z","caller":"traceutil/trace.go:171","msg":"trace[1966675736] transaction","detail":"{read_only:false; response_revision:1618; number_of_response:1; }","duration":"164.696308ms","start":"2026-05-06T01:19:21.461784Z","end":"2026-05-06T01:19:21.62648Z","steps":["trace[1966675736] 'process raft request' (duration: 164.413762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:20:06.077403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.279853ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T01:20:06.077691Z","caller":"traceutil/trace.go:171","msg":"trace[228225042] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"128.671927ms","start":"2026-05-06T01:20:05.948999Z","end":"2026-05-06T01:20:06.077671Z","steps":["trace[228225042] 'process raft request' (duration: 27.033527ms)","trace[228225042] 'compare' (duration: 101.043749ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:20:06.078036Z","caller":"traceutil/trace.go:171","msg":"trace[2059365796] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"128.024917ms","start":"2026-05-06T01:20:05.949975Z","end":"2026-05-06T01:20:06.078Z","steps":["trace[2059365796] 'process raft request' (duration: 127.551697ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:28.885464Z","caller":"traceutil/trace.go:171","msg":"trace[1575595498] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"101.980656ms","start":"2026-05-06T01:20:28.783465Z","end":"2026-05-06T01:20:28.885446Z","steps":["trace[1575595498] 'process raft request' (duration: 101.867474ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:42.367928Z","caller":"traceutil/trace.go:171","msg":"trace[340394594] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"102.395945ms","start":"2026-05-06T01:20:42.265513Z","end":"2026-05-06T01:20:42.367909Z","steps":["trace[340394594] 'process raft request' (duration: 102.273243ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:20:42.399255Z","caller":"traceutil/trace.go:171","msg":"trace[290129642] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"133.18296ms","start":"2026-05-06T01:20:42.266046Z","end":"2026-05-06T01:20:42.399229Z","steps":["trace[290129642] 'process raft request' (duration: 133.002427ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:21:15.763136Z","caller":"traceutil/trace.go:171","msg":"trace[865240717] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"117.207455ms","start":"2026-05-06T01:21:15.645894Z","end":"2026-05-06T01:21:15.763102Z","steps":["trace[865240717] 'process raft request' (duration: 117.070522ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:21:24.787228Z","caller":"traceutil/trace.go:171","msg":"trace[690379071] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"115.189327ms","start":"2026-05-06T01:21:24.672014Z","end":"2026-05-06T01:21:24.787203Z","steps":["trace[690379071] 'process raft request' (duration: 114.043606ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:21:24.787752Z","caller":"traceutil/trace.go:171","msg":"trace[1873117702] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"114.545555ms","start":"2026-05-06T01:21:24.673183Z","end":"2026-05-06T01:21:24.787728Z","steps":["trace[1873117702] 'process raft request' (duration: 114.365541ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:21:33.330973Z","caller":"traceutil/trace.go:171","msg":"trace[918015725] transaction","detail":"{read_only:false; response_revision:2246; number_of_response:1; }","duration":"490.157219ms","start":"2026-05-06T01:21:32.840796Z","end":"2026-05-06T01:21:33.330954Z","steps":["trace[918015725] 'process raft request' (duration: 490.034747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:21:33.331105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:21:32.840756Z","time spent":"490.283172ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:21:33.332457Z","caller":"traceutil/trace.go:171","msg":"trace[314832468] transaction","detail":"{read_only:false; response_revision:2247; number_of_response:1; }","duration":"491.463413ms","start":"2026-05-06T01:21:32.840957Z","end":"2026-05-06T01:21:33.33242Z","steps":["trace[314832468] 'process raft request' (duration: 491.217609ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:21:33.332626Z","caller":"traceutil/trace.go:171","msg":"trace[2041240436] linearizableReadLoop","detail":"{readStateIndex:2343; appliedIndex:2342; }","duration":"489.358035ms","start":"2026-05-06T01:21:32.843234Z","end":"2026-05-06T01:21:33.332592Z","steps":["trace[2041240436] 'read index received' (duration: 487.714444ms)","trace[2041240436] 'applied index is now lower than readState.Index' (duration: 1.641581ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:21:33.332698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:21:32.840934Z","time spent":"491.644347ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:21:33.332728Z","caller":"traceutil/trace.go:171","msg":"trace[562687665] transaction","detail":"{read_only:false; response_revision:2248; number_of_response:1; }","duration":"300.925962ms","start":"2026-05-06T01:21:33.031731Z","end":"2026-05-06T01:21:33.332657Z","steps":["trace[562687665] 'process raft request' (duration: 300.638425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:21:33.333013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.755132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-06T01:21:33.333022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:21:33.031702Z","time spent":"301.175305ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T01:21:33.333064Z","caller":"traceutil/trace.go:171","msg":"trace[2036910469] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2248; }","duration":"489.890094ms","start":"2026-05-06T01:21:32.843161Z","end":"2026-05-06T01:21:33.333051Z","steps":["trace[2036910469] 'agreement among raft nodes before linearized reading' (duration: 489.63607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:21:33.333108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:21:32.843139Z","time spent":"489.959616ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T01:21:33.333374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.419407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:21:33.333425Z","caller":"traceutil/trace.go:171","msg":"trace[991590725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2248; }","duration":"309.510529ms","start":"2026-05-06T01:21:33.023902Z","end":"2026-05-06T01:21:33.333412Z","steps":["trace[991590725] 'agreement among raft nodes before linearized reading' (duration: 309.426477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:21:33.333458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:21:33.023878Z","time spent":"309.573751ms","remote":"127.0.0.1:51960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T01:21:33.334547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.577279ms","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:535"} {"level":"info","ts":"2026-05-06T01:21:33.334606Z","caller":"traceutil/trace.go:171","msg":"trace[1680515473] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2248; }","duration":"366.67288ms","start":"2026-05-06T01:21:32.967918Z","end":"2026-05-06T01:21:33.334591Z","steps":["trace[1680515473] 'agreement among raft nodes before linearized reading' (duration: 364.938048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:21:33.334659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T01:21:32.967898Z","time spent":"366.752492ms","remote":"127.0.0.1:52200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-06T01:21:39.466969Z","caller":"traceutil/trace.go:171","msg":"trace[1593859853] linearizableReadLoop","detail":"{readStateIndex:2377; appliedIndex:2376; }","duration":"103.428011ms","start":"2026-05-06T01:21:39.363524Z","end":"2026-05-06T01:21:39.466952Z","steps":["trace[1593859853] 'read index received' (duration: 22.789758ms)","trace[1593859853] 'applied index is now lower than readState.Index' (duration: 80.637573ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:21:39.467094Z","caller":"traceutil/trace.go:171","msg":"trace[1888714467] transaction","detail":"{read_only:false; response_revision:2279; number_of_response:1; }","duration":"105.234304ms","start":"2026-05-06T01:21:39.361797Z","end":"2026-05-06T01:21:39.467031Z","steps":["trace[1888714467] 'process raft request' (duration: 24.580831ms)","trace[1888714467] 'compare' (duration: 80.48114ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:21:39.467204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.662845ms","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-06T01:21:39.467283Z","caller":"traceutil/trace.go:171","msg":"trace[1389999986] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2279; }","duration":"103.764577ms","start":"2026-05-06T01:21:39.363495Z","end":"2026-05-06T01:21:39.46726Z","steps":["trace[1389999986] 'agreement among raft nodes before linearized reading' (duration: 103.548503ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:21:39.808862Z","caller":"traceutil/trace.go:171","msg":"trace[915659124] transaction","detail":"{read_only:false; response_revision:2282; number_of_response:1; }","duration":"112.782694ms","start":"2026-05-06T01:21:39.69604Z","end":"2026-05-06T01:21:39.808822Z","steps":["trace[915659124] 'process raft request' (duration: 73.138606ms)","trace[915659124] 'compare' (duration: 39.421194ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T01:21:40.687264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.886645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-06T01:21:40.687664Z","caller":"traceutil/trace.go:171","msg":"trace[2028459017] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2286; }","duration":"137.355635ms","start":"2026-05-06T01:21:40.550286Z","end":"2026-05-06T01:21:40.687642Z","steps":["trace[2028459017] 'range keys from in-memory index tree' (duration: 136.628701ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:21:54.973658Z","caller":"traceutil/trace.go:171","msg":"trace[87959147] transaction","detail":"{read_only:false; response_revision:2356; number_of_response:1; }","duration":"103.188067ms","start":"2026-05-06T01:21:54.870445Z","end":"2026-05-06T01:21:54.973633Z","steps":["trace[87959147] 'process raft request' (duration: 93.300846ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:22:08.525057Z","caller":"traceutil/trace.go:171","msg":"trace[1323143534] linearizableReadLoop","detail":"{readStateIndex:2519; appliedIndex:2518; }","duration":"121.0999ms","start":"2026-05-06T01:22:08.403932Z","end":"2026-05-06T01:22:08.525032Z","steps":["trace[1323143534] 'read index received' (duration: 117.760138ms)","trace[1323143534] 'applied index is now lower than readState.Index' (duration: 3.338772ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:22:08.52518Z","caller":"traceutil/trace.go:171","msg":"trace[1692940859] transaction","detail":"{read_only:false; response_revision:2415; number_of_response:1; }","duration":"122.505936ms","start":"2026-05-06T01:22:08.402609Z","end":"2026-05-06T01:22:08.525115Z","steps":["trace[1692940859] 'process raft request' (duration: 119.153703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:22:08.52535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.892133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-dcz4q\" ","response":"range_response_count:1 size:5487"} {"level":"info","ts":"2026-05-06T01:22:08.525459Z","caller":"traceutil/trace.go:171","msg":"trace[1476263312] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-dcz4q; range_end:; response_count:1; response_revision:2415; }","duration":"117.029435ms","start":"2026-05-06T01:22:08.408413Z","end":"2026-05-06T01:22:08.525442Z","steps":["trace[1476263312] 'agreement among raft nodes before linearized reading' (duration: 116.799431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:22:08.525589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.391275ms","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:535"} {"level":"info","ts":"2026-05-06T01:22:08.525679Z","caller":"traceutil/trace.go:171","msg":"trace[433554987] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2415; }","duration":"121.754631ms","start":"2026-05-06T01:22:08.403903Z","end":"2026-05-06T01:22:08.525658Z","steps":["trace[433554987] 'agreement among raft nodes before linearized reading' (duration: 121.277283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:22:09.169805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.271561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T01:22:09.169884Z","caller":"traceutil/trace.go:171","msg":"trace[2096723365] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2418; }","duration":"140.407334ms","start":"2026-05-06T01:22:09.029458Z","end":"2026-05-06T01:22:09.169866Z","steps":["trace[2096723365] 'range keys from in-memory index tree' (duration: 140.18434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:22:09.170152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.730789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-z45jd\" ","response":"range_response_count:1 size:11390"} {"level":"info","ts":"2026-05-06T01:22:09.170222Z","caller":"traceutil/trace.go:171","msg":"trace[919265911] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-z45jd; range_end:; response_count:1; response_revision:2418; }","duration":"128.837802ms","start":"2026-05-06T01:22:09.041367Z","end":"2026-05-06T01:22:09.170205Z","steps":["trace[919265911] 'range keys from in-memory index tree' (duration: 128.408583ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T01:22:09.769768Z","caller":"traceutil/trace.go:171","msg":"trace[119143860] linearizableReadLoop","detail":"{readStateIndex:2524; appliedIndex:2523; }","duration":"162.318295ms","start":"2026-05-06T01:22:09.607424Z","end":"2026-05-06T01:22:09.769742Z","steps":["trace[119143860] 'read index received' (duration: 162.177982ms)","trace[119143860] 'applied index is now lower than readState.Index' (duration: 139.253µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T01:22:09.769897Z","caller":"traceutil/trace.go:171","msg":"trace[679639267] transaction","detail":"{read_only:false; response_revision:2420; number_of_response:1; }","duration":"164.638028ms","start":"2026-05-06T01:22:09.605225Z","end":"2026-05-06T01:22:09.769863Z","steps":["trace[679639267] 'process raft request' (duration: 164.385173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T01:22:09.770055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.61642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-rg296\" ","response":"range_response_count:1 size:13724"} {"level":"info","ts":"2026-05-06T01:22:09.770104Z","caller":"traceutil/trace.go:171","msg":"trace[197490414] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-rg296; range_end:; response_count:1; response_revision:2420; }","duration":"162.688241ms","start":"2026-05-06T01:22:09.607402Z","end":"2026-05-06T01:22:09.77009Z","steps":["trace[197490414] 'agreement among raft nodes before linearized reading' (duration: 162.434757ms)"],"step_count":1}