{"level":"warn","ts":"2026-02-27T16:44:01.144294Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-27T16:44:01.144397Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.36: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://162.253.55.36:2380","--initial-cluster=instance=https://162.253.55.36:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.36:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.36: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-02-27T16:44:01.144482Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-27T16:44:01.144496Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-02-27T16:44:01.144514Z","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-02-27T16:44:01.145247Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"]} {"level":"info","ts":"2026-02-27T16:44:01.145454Z","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://162.253.55.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.36: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-02-27T16:44:01.149648Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.965497ms"} {"level":"info","ts":"2026-02-27T16:44:01.158209Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cb89ec34a9687f0b","cluster-id":"d8e0d5e222e856c9"} {"level":"info","ts":"2026-02-27T16:44:01.158305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=()"} {"level":"info","ts":"2026-02-27T16:44:01.158332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 0"} {"level":"info","ts":"2026-02-27T16:44:01.158346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cb89ec34a9687f0b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-27T16:44:01.158353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 1"} {"level":"info","ts":"2026-02-27T16:44:01.158394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"warn","ts":"2026-02-27T16:44:01.164827Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-27T16:44:01.166131Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-27T16:44:01.167023Z","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-02-27T16:44:01.169252Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cb89ec34a9687f0b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-27T16:44:01.172457Z","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-02-27T16:44:01.172597Z","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-02-27T16:44:01.172615Z","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-02-27T16:44:01.173662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"info","ts":"2026-02-27T16:44:01.17266Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cb89ec34a9687f0b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-27T16:44:01.178866Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","added-peer-id":"cb89ec34a9687f0b","added-peer-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-02-27T16:44:01.179404Z","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-02-27T16:44:01.179626Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-02-27T16:44:01.179651Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-02-27T16:44:01.179686Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cb89ec34a9687f0b","initial-advertise-peer-urls":["https://162.253.55.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-27T16:44:01.179728Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-27T16:44:01.759263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b is starting a new election at term 1"} {"level":"info","ts":"2026-02-27T16:44:01.759364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-27T16:44:01.759408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgPreVoteResp from cb89ec34a9687f0b at term 1"} {"level":"info","ts":"2026-02-27T16:44:01.759431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became candidate at term 2"} {"level":"info","ts":"2026-02-27T16:44:01.759443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgVoteResp from cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-02-27T16:44:01.759461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became leader at term 2"} {"level":"info","ts":"2026-02-27T16:44:01.759477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cb89ec34a9687f0b elected leader cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-02-27T16:44:01.851582Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cb89ec34a9687f0b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.36:2379]}","request-path":"/0/members/cb89ec34a9687f0b/attributes","cluster-id":"d8e0d5e222e856c9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-27T16:44:01.851648Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T16:44:01.851752Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T16:44:01.851823Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T16:44:01.852153Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-27T16:44:01.852201Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-27T16:44:01.85394Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-27T16:44:01.856788Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.36:2379"} {"level":"info","ts":"2026-02-27T16:44:01.860448Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T16:44:01.860992Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T16:44:01.863347Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-27T16:44:08.684237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.198382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-27T16:44:08.684422Z","caller":"traceutil/trace.go:171","msg":"trace[1726569614] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:319; }","duration":"138.416954ms","start":"2026-02-27T16:44:08.545983Z","end":"2026-02-27T16:44:08.6844Z","steps":["trace[1726569614] 'range keys from in-memory index tree' (duration: 138.119631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:08.684339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.417421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-27T16:44:08.684842Z","caller":"traceutil/trace.go:171","msg":"trace[1743972248] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:319; }","duration":"153.969425ms","start":"2026-02-27T16:44:08.53086Z","end":"2026-02-27T16:44:08.684829Z","steps":["trace[1743972248] 'range keys from in-memory index tree' (duration: 153.33406ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:44:29.655744Z","caller":"traceutil/trace.go:171","msg":"trace[740045414] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"128.562528ms","start":"2026-02-27T16:44:29.527139Z","end":"2026-02-27T16:44:29.655701Z","steps":["trace[740045414] 'process raft request' (duration: 128.173706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:30.385524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.504555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-27T16:44:30.385616Z","caller":"traceutil/trace.go:171","msg":"trace[231107507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:471; }","duration":"670.65702ms","start":"2026-02-27T16:44:29.71494Z","end":"2026-02-27T16:44:30.385597Z","steps":["trace[231107507] 'range keys from in-memory index tree' (duration: 670.391391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:30.385626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.491744ms","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-02-27T16:44:30.385655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:29.714922Z","time spent":"670.723751ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T16:44:30.385731Z","caller":"traceutil/trace.go:171","msg":"trace[265371643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:471; }","duration":"533.641219ms","start":"2026-02-27T16:44:29.852056Z","end":"2026-02-27T16:44:30.385698Z","steps":["trace[265371643] 'range keys from in-memory index tree' (duration: 533.304609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:30.385778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:29.852033Z","time spent":"533.736012ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T16:44:31.071654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.749358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-27T16:44:31.071842Z","caller":"traceutil/trace.go:171","msg":"trace[894249855] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:473; }","duration":"165.985205ms","start":"2026-02-27T16:44:30.905834Z","end":"2026-02-27T16:44:31.071819Z","steps":["trace[894249855] 'range keys from in-memory index tree' (duration: 165.649675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:35.388193Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582878508091962,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-27T16:44:35.658315Z","caller":"traceutil/trace.go:171","msg":"trace[193950513] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:496; }","duration":"770.569226ms","start":"2026-02-27T16:44:34.887721Z","end":"2026-02-27T16:44:35.65829Z","steps":["trace[193950513] 'read index received' (duration: 770.047822ms)","trace[193950513] 'applied index is now lower than readState.Index' (duration: 520.404µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T16:44:35.658452Z","caller":"traceutil/trace.go:171","msg":"trace[1815347272] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"897.240976ms","start":"2026-02-27T16:44:34.761155Z","end":"2026-02-27T16:44:35.658396Z","steps":["trace[1815347272] 'process raft request' (duration: 896.882306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:35.658602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.863776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.36\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-27T16:44:35.658675Z","caller":"traceutil/trace.go:171","msg":"trace[755160] range","detail":"{range_begin:/registry/masterleases/162.253.55.36; range_end:; response_count:1; response_revision:483; }","duration":"770.971708ms","start":"2026-02-27T16:44:34.887684Z","end":"2026-02-27T16:44:35.658656Z","steps":["trace[755160] 'agreement among raft nodes before linearized reading' (duration: 770.793553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:35.658624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.470409ms","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-02-27T16:44:35.658721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:34.887669Z","time spent":"771.03884ms","remote":"127.0.0.1:59344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.36\" "} {"level":"info","ts":"2026-02-27T16:44:35.658817Z","caller":"traceutil/trace.go:171","msg":"trace[1568289540] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:483; }","duration":"564.651954ms","start":"2026-02-27T16:44:35.094103Z","end":"2026-02-27T16:44:35.658755Z","steps":["trace[1568289540] 'agreement among raft nodes before linearized reading' (duration: 564.470138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:35.658911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:35.094082Z","time spent":"564.811809ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T16:44:35.65924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:34.761144Z","time spent":"897.390841ms","remote":"127.0.0.1:59394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317296,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T16:44:35.803255Z","caller":"traceutil/trace.go:171","msg":"trace[643774245] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"137.966756ms","start":"2026-02-27T16:44:35.665251Z","end":"2026-02-27T16:44:35.803218Z","steps":["trace[643774245] 'process raft request' (duration: 127.499932ms)","trace[643774245] 'compare' (duration: 10.286669ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T16:44:35.803276Z","caller":"traceutil/trace.go:171","msg":"trace[403320221] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"129.029507ms","start":"2026-02-27T16:44:35.674214Z","end":"2026-02-27T16:44:35.803244Z","steps":["trace[403320221] 'process raft request' (duration: 128.913973ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:44:35.80343Z","caller":"traceutil/trace.go:171","msg":"trace[402878796] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"109.674326ms","start":"2026-02-27T16:44:35.69373Z","end":"2026-02-27T16:44:35.803404Z","steps":["trace[402878796] 'process raft request' (duration: 109.562523ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:44:35.803334Z","caller":"traceutil/trace.go:171","msg":"trace[382150393] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:498; }","duration":"123.478796ms","start":"2026-02-27T16:44:35.679841Z","end":"2026-02-27T16:44:35.80332Z","steps":["trace[382150393] 'read index received' (duration: 112.898809ms)","trace[382150393] 'applied index is now lower than readState.Index' (duration: 10.579357ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T16:44:35.803502Z","caller":"traceutil/trace.go:171","msg":"trace[756737890] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"109.916893ms","start":"2026-02-27T16:44:35.69356Z","end":"2026-02-27T16:44:35.803477Z","steps":["trace[756737890] 'process raft request' (duration: 109.598384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:35.803437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.58161ms","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-02-27T16:44:35.804068Z","caller":"traceutil/trace.go:171","msg":"trace[895720172] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:487; }","duration":"124.220888ms","start":"2026-02-27T16:44:35.679836Z","end":"2026-02-27T16:44:35.804057Z","steps":["trace[895720172] 'agreement among raft nodes before linearized reading' (duration: 123.531108ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:44:37.150867Z","caller":"traceutil/trace.go:171","msg":"trace[120845520] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"193.177595ms","start":"2026-02-27T16:44:36.957673Z","end":"2026-02-27T16:44:37.15085Z","steps":["trace[120845520] 'process raft request' (duration: 193.087143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:38.318003Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582878508092025,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-27T16:44:38.334525Z","caller":"traceutil/trace.go:171","msg":"trace[1480095822] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:537; }","duration":"517.252663ms","start":"2026-02-27T16:44:37.817249Z","end":"2026-02-27T16:44:38.334502Z","steps":["trace[1480095822] 'read index received' (duration: 517.024427ms)","trace[1480095822] 'applied index is now lower than readState.Index' (duration: 227.056µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T16:44:38.334605Z","caller":"traceutil/trace.go:171","msg":"trace[2034893522] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"522.698927ms","start":"2026-02-27T16:44:37.811878Z","end":"2026-02-27T16:44:38.334577Z","steps":["trace[2034893522] 'process raft request' (duration: 522.482271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:38.334679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.404097ms","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-02-27T16:44:38.334725Z","caller":"traceutil/trace.go:171","msg":"trace[1860090213] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:523; }","duration":"517.49125ms","start":"2026-02-27T16:44:37.817222Z","end":"2026-02-27T16:44:38.334713Z","steps":["trace[1860090213] 'agreement among raft nodes before linearized reading' (duration: 517.376517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:38.334769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:37.817214Z","time spent":"517.546331ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T16:44:38.334867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:37.811857Z","time spent":"522.7974ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T16:44:45.932683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.017042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T16:44:45.932893Z","caller":"traceutil/trace.go:171","msg":"trace[1479851709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"344.211087ms","start":"2026-02-27T16:44:45.588603Z","end":"2026-02-27T16:44:45.932814Z","steps":["trace[1479851709] 'range keys from in-memory index tree' (duration: 343.951641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:45.932982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:45.588589Z","time spent":"344.375781ms","remote":"127.0.0.1:60214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T16:44:45.932739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.044340278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.36\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-27T16:44:45.933132Z","caller":"traceutil/trace.go:171","msg":"trace[595809365] range","detail":"{range_begin:/registry/masterleases/162.253.55.36; range_end:; response_count:1; response_revision:566; }","duration":"1.044768739s","start":"2026-02-27T16:44:44.888328Z","end":"2026-02-27T16:44:45.933096Z","steps":["trace[595809365] 'range keys from in-memory index tree' (duration: 1.044167913s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:45.933207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:44.888306Z","time spent":"1.044880161s","remote":"127.0.0.1:59344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.36\" "} {"level":"warn","ts":"2026-02-27T16:44:46.794181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.894269ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T16:44:46.7943Z","caller":"traceutil/trace.go:171","msg":"trace[1287764035] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"429.595452ms","start":"2026-02-27T16:44:46.364683Z","end":"2026-02-27T16:44:46.794279Z","steps":["trace[1287764035] 'read index received' (duration: 190.315683ms)","trace[1287764035] 'applied index is now lower than readState.Index' (duration: 239.278339ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T16:44:46.794332Z","caller":"traceutil/trace.go:171","msg":"trace[546093838] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"711.910316ms","start":"2026-02-27T16:44:46.082404Z","end":"2026-02-27T16:44:46.794314Z","steps":["trace[546093838] 'process raft request' (duration: 472.638267ms)","trace[546093838] 'compare' (duration: 238.772296ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T16:44:46.794451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:46.082378Z","time spent":"711.989108ms","remote":"127.0.0.1:59344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T16:44:46.794465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.786857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-27T16:44:46.794507Z","caller":"traceutil/trace.go:171","msg":"trace[323026719] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:567; }","duration":"429.856968ms","start":"2026-02-27T16:44:46.364628Z","end":"2026-02-27T16:44:46.794485Z","steps":["trace[323026719] 'agreement among raft nodes before linearized reading' (duration: 429.742465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:46.794552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:46.364607Z","time spent":"429.937321ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T16:44:46.794571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.62775ms","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-02-27T16:44:46.794567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.926331ms","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-02-27T16:44:46.794637Z","caller":"traceutil/trace.go:171","msg":"trace[1901243825] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:567; }","duration":"310.747763ms","start":"2026-02-27T16:44:46.483872Z","end":"2026-02-27T16:44:46.794619Z","steps":["trace[1901243825] 'agreement among raft nodes before linearized reading' (duration: 310.521977ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:44:46.794649Z","caller":"traceutil/trace.go:171","msg":"trace[1075448794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:567; }","duration":"179.050074ms","start":"2026-02-27T16:44:46.615579Z","end":"2026-02-27T16:44:46.794629Z","steps":["trace[1075448794] 'agreement among raft nodes before linearized reading' (duration: 178.88949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:46.794674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:46.483848Z","time spent":"310.821315ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T16:44:46.794634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.878774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-27T16:44:46.794742Z","caller":"traceutil/trace.go:171","msg":"trace[1332978545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:567; }","duration":"171.020398ms","start":"2026-02-27T16:44:46.623697Z","end":"2026-02-27T16:44:46.794717Z","steps":["trace[1332978545] 'agreement among raft nodes before linearized reading' (duration: 170.795833ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:46.795199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.262015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T16:44:46.795255Z","caller":"traceutil/trace.go:171","msg":"trace[928928130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"210.358187ms","start":"2026-02-27T16:44:46.584882Z","end":"2026-02-27T16:44:46.795241Z","steps":["trace[928928130] 'agreement among raft nodes before linearized reading' (duration: 210.233304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:47.452538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.049342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T16:44:47.45267Z","caller":"traceutil/trace.go:171","msg":"trace[312929961] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"654.691316ms","start":"2026-02-27T16:44:46.797963Z","end":"2026-02-27T16:44:47.452655Z","steps":["trace[312929961] 'process raft request' (duration: 116.462769ms)","trace[312929961] 'compare' (duration: 537.95247ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T16:44:47.452722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:46.797953Z","time spent":"654.743357ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T16:44:47.452968Z","caller":"traceutil/trace.go:171","msg":"trace[1331691214] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"653.887163ms","start":"2026-02-27T16:44:46.799068Z","end":"2026-02-27T16:44:47.452955Z","steps":["trace[1331691214] 'process raft request' (duration: 653.552355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:47.453016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:46.79906Z","time spent":"653.929905ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T16:44:47.45308Z","caller":"traceutil/trace.go:171","msg":"trace[612741096] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"653.666218ms","start":"2026-02-27T16:44:46.799405Z","end":"2026-02-27T16:44:47.453071Z","steps":["trace[612741096] 'process raft request' (duration: 653.502294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:47.453118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:46.799398Z","time spent":"653.698589ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T16:44:47.453083Z","caller":"traceutil/trace.go:171","msg":"trace[244444598] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:584; }","duration":"653.948015ms","start":"2026-02-27T16:44:46.799104Z","end":"2026-02-27T16:44:47.453052Z","steps":["trace[244444598] 'read index received' (duration: 115.324359ms)","trace[244444598] 'applied index is now lower than readState.Index' (duration: 538.622056ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T16:44:47.453109Z","caller":"traceutil/trace.go:171","msg":"trace[82749845] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"653.495663ms","start":"2026-02-27T16:44:46.799596Z","end":"2026-02-27T16:44:47.453091Z","steps":["trace[82749845] 'process raft request' (duration: 653.411161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:47.453267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:46.799589Z","time spent":"653.578365ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T16:44:47.453355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.238033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-27T16:44:47.453396Z","caller":"traceutil/trace.go:171","msg":"trace[966412816] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:571; }","duration":"654.288794ms","start":"2026-02-27T16:44:46.799094Z","end":"2026-02-27T16:44:47.453383Z","steps":["trace[966412816] 'agreement among raft nodes before linearized reading' (duration: 654.060548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:47.453494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:46.799091Z","time spent":"654.337785ms","remote":"127.0.0.1:59344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-02-27T16:44:48.54742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.060983ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T16:44:48.547516Z","caller":"traceutil/trace.go:171","msg":"trace[1739428645] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:588; }","duration":"1.091896821s","start":"2026-02-27T16:44:47.455604Z","end":"2026-02-27T16:44:48.547501Z","steps":["trace[1739428645] 'read index received' (duration: 265.590202ms)","trace[1739428645] 'applied index is now lower than readState.Index' (duration: 826.305649ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T16:44:48.54758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091988453s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-27T16:44:48.547639Z","caller":"traceutil/trace.go:171","msg":"trace[82415895] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:572; }","duration":"1.092117646s","start":"2026-02-27T16:44:47.455513Z","end":"2026-02-27T16:44:48.547631Z","steps":["trace[82415895] 'agreement among raft nodes before linearized reading' (duration: 1.092030084s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:48.547671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:47.455493Z","time spent":"1.092169827s","remote":"127.0.0.1:59574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-02-27T16:44:48.547815Z","caller":"traceutil/trace.go:171","msg":"trace[1158766617] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"1.205283619s","start":"2026-02-27T16:44:47.342492Z","end":"2026-02-27T16:44:48.547776Z","steps":["trace[1158766617] 'process raft request' (duration: 378.794145ms)","trace[1158766617] 'compare' (duration: 825.900909ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T16:44:48.548102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:47.342445Z","time spent":"1.205446602s","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T16:44:50.008285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.722819ms","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-02-27T16:44:50.008367Z","caller":"traceutil/trace.go:171","msg":"trace[705816364] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:572; }","duration":"549.874183ms","start":"2026-02-27T16:44:49.458475Z","end":"2026-02-27T16:44:50.008349Z","steps":["trace[705816364] 'range keys from in-memory index tree' (duration: 549.578045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:50.008411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:49.458446Z","time spent":"549.956295ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T16:44:50.008529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.829965ms","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-02-27T16:44:50.008641Z","caller":"traceutil/trace.go:171","msg":"trace[464363846] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:572; }","duration":"797.959479ms","start":"2026-02-27T16:44:49.210667Z","end":"2026-02-27T16:44:50.008627Z","steps":["trace[464363846] 'range keys from in-memory index tree' (duration: 797.816425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:50.008755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.468323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-27T16:44:50.008755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.370196ms","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-02-27T16:44:50.008824Z","caller":"traceutil/trace.go:171","msg":"trace[1830360203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:572; }","duration":"551.544375ms","start":"2026-02-27T16:44:49.457261Z","end":"2026-02-27T16:44:50.008805Z","steps":["trace[1830360203] 'range keys from in-memory index tree' (duration: 551.405661ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:44:50.008835Z","caller":"traceutil/trace.go:171","msg":"trace[360608404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:572; }","duration":"550.506189ms","start":"2026-02-27T16:44:49.45831Z","end":"2026-02-27T16:44:50.008816Z","steps":["trace[360608404] 'range keys from in-memory index tree' (duration: 550.270353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:50.008864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:49.45724Z","time spent":"551.616036ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-27T16:44:50.008881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:49.458284Z","time spent":"550.584811ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T16:44:50.009506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.711173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-27T16:44:50.009563Z","caller":"traceutil/trace.go:171","msg":"trace[712190497] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:572; }","duration":"552.834706ms","start":"2026-02-27T16:44:49.456714Z","end":"2026-02-27T16:44:50.009549Z","steps":["trace[712190497] 'range keys from in-memory index tree' (duration: 551.744429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:50.009599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:49.456689Z","time spent":"552.900778ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-27T16:44:50.810798Z","caller":"traceutil/trace.go:171","msg":"trace[269534388] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"797.48808ms","start":"2026-02-27T16:44:50.013284Z","end":"2026-02-27T16:44:50.810772Z","steps":["trace[269534388] 'process raft request' (duration: 797.313826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:50.81098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:50.013261Z","time spent":"797.629594ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T16:44:52.586352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00132933s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-27T16:44:52.586436Z","caller":"traceutil/trace.go:171","msg":"trace[421264801] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001434603s","start":"2026-02-27T16:44:50.584985Z","end":"2026-02-27T16:44:52.58642Z","steps":["trace[421264801] 'agreement among raft nodes before linearized reading' (duration: 2.000840779s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:52.586478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:50.584938Z","time spent":"2.001532345s","remote":"127.0.0.1:60204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T16:44:53.4092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.878496025s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T16:44:53.409744Z","caller":"traceutil/trace.go:171","msg":"trace[1677661323] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"3.396396625s","start":"2026-02-27T16:44:50.013325Z","end":"2026-02-27T16:44:53.409722Z","steps":["trace[1677661323] 'process raft request' (duration: 1.517267834s)","trace[1677661323] 'compare' (duration: 1.878392232s)"],"step_count":2} {"level":"info","ts":"2026-02-27T16:44:53.40976Z","caller":"traceutil/trace.go:171","msg":"trace[985108637] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"3.394737864s","start":"2026-02-27T16:44:50.014994Z","end":"2026-02-27T16:44:53.409731Z","steps":["trace[985108637] 'process raft request' (duration: 3.394315263s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:53.40986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:50.013303Z","time spent":"3.396490137s","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T16:44:53.409908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:50.014982Z","time spent":"3.394843966s","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T16:44:53.409984Z","caller":"traceutil/trace.go:171","msg":"trace[2144487892] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"3.394756584s","start":"2026-02-27T16:44:50.015161Z","end":"2026-02-27T16:44:53.409917Z","steps":["trace[2144487892] 'process raft request' (duration: 3.394216052s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:53.41012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:50.015127Z","time spent":"3.394933889s","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T16:44:53.474611Z","caller":"traceutil/trace.go:171","msg":"trace[2066298468] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:590; }","duration":"2.889596954s","start":"2026-02-27T16:44:50.584991Z","end":"2026-02-27T16:44:53.474588Z","steps":["trace[2066298468] 'read index received' (duration: 226.386307ms)","trace[2066298468] 'applied index is now lower than readState.Index' (duration: 2.663209576s)"],"step_count":2} {"level":"warn","ts":"2026-02-27T16:44:53.474763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.954345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T16:44:53.474794Z","caller":"traceutil/trace.go:171","msg":"trace[233472664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"888.026747ms","start":"2026-02-27T16:44:52.586759Z","end":"2026-02-27T16:44:53.474785Z","steps":["trace[233472664] 'agreement among raft nodes before linearized reading' (duration: 887.953145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:53.474823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:52.586746Z","time spent":"888.071518ms","remote":"127.0.0.1:60214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T16:44:53.474882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.759368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T16:44:53.474967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.364171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-27T16:44:53.475049Z","caller":"traceutil/trace.go:171","msg":"trace[1928392274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"499.925772ms","start":"2026-02-27T16:44:52.975057Z","end":"2026-02-27T16:44:53.474983Z","steps":["trace[1928392274] 'agreement among raft nodes before linearized reading' (duration: 499.688216ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:44:53.475084Z","caller":"traceutil/trace.go:171","msg":"trace[44530085] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:576; }","duration":"659.523585ms","start":"2026-02-27T16:44:52.815536Z","end":"2026-02-27T16:44:53.475059Z","steps":["trace[44530085] 'agreement among raft nodes before linearized reading' (duration: 659.363791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:53.47514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:52.815515Z","time spent":"659.613996ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T16:44:53.475155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:52.975038Z","time spent":"500.050354ms","remote":"127.0.0.1:60204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T16:44:55.012411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.311098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T16:44:55.012517Z","caller":"traceutil/trace.go:171","msg":"trace[803473829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"405.506461ms","start":"2026-02-27T16:44:54.606984Z","end":"2026-02-27T16:44:55.01249Z","steps":["trace[803473829] 'range keys from in-memory index tree' (duration: 405.119943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:55.012564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:54.606951Z","time spent":"405.600834ms","remote":"127.0.0.1:60204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T16:44:55.012626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.869127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.36\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-27T16:44:55.012663Z","caller":"traceutil/trace.go:171","msg":"trace[1800757924] range","detail":"{range_begin:/registry/masterleases/162.253.55.36; range_end:; response_count:1; response_revision:581; }","duration":"123.906908ms","start":"2026-02-27T16:44:54.888745Z","end":"2026-02-27T16:44:55.012652Z","steps":["trace[1800757924] 'range keys from in-memory index tree' (duration: 123.721714ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:44:55.765885Z","caller":"traceutil/trace.go:171","msg":"trace[598982908] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"351.62766ms","start":"2026-02-27T16:44:55.41424Z","end":"2026-02-27T16:44:55.765868Z","steps":["trace[598982908] 'read index received' (duration: 351.465296ms)","trace[598982908] 'applied index is now lower than readState.Index' (duration: 161.764µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T16:44:55.765943Z","caller":"traceutil/trace.go:171","msg":"trace[976760351] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"460.346158ms","start":"2026-02-27T16:44:55.305554Z","end":"2026-02-27T16:44:55.765901Z","steps":["trace[976760351] 'process raft request' (duration: 460.190835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:55.766027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.769124ms","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-02-27T16:44:55.76605Z","caller":"traceutil/trace.go:171","msg":"trace[451856440] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:582; }","duration":"351.845515ms","start":"2026-02-27T16:44:55.414198Z","end":"2026-02-27T16:44:55.766043Z","steps":["trace[451856440] 'agreement among raft nodes before linearized reading' (duration: 351.759963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:55.766069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:55.414175Z","time spent":"351.890176ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T16:44:55.766119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:55.305519Z","time spent":"460.485272ms","remote":"127.0.0.1:59344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T16:44:55.766127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.322135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-27T16:44:55.76613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.487557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-27T16:44:55.766176Z","caller":"traceutil/trace.go:171","msg":"trace[1399571664] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"256.421657ms","start":"2026-02-27T16:44:55.509742Z","end":"2026-02-27T16:44:55.766164Z","steps":["trace[1399571664] 'agreement among raft nodes before linearized reading' (duration: 256.310625ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:44:55.766191Z","caller":"traceutil/trace.go:171","msg":"trace[1634567407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"351.569719ms","start":"2026-02-27T16:44:55.414606Z","end":"2026-02-27T16:44:55.766175Z","steps":["trace[1634567407] 'agreement among raft nodes before linearized reading' (duration: 351.442106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:55.766237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:55.414595Z","time spent":"351.63242ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-27T16:44:55.769576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.243935ms","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-02-27T16:44:55.769627Z","caller":"traceutil/trace.go:171","msg":"trace[2092316232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"355.338277ms","start":"2026-02-27T16:44:55.414277Z","end":"2026-02-27T16:44:55.769615Z","steps":["trace[2092316232] 'agreement among raft nodes before linearized reading' (duration: 351.733313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:44:55.769709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:44:55.414253Z","time spent":"355.445969ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-27T16:45:03.046601Z","caller":"traceutil/trace.go:171","msg":"trace[1550491558] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"274.764621ms","start":"2026-02-27T16:45:02.771824Z","end":"2026-02-27T16:45:03.046589Z","steps":["trace[1550491558] 'process raft request' (duration: 274.642258ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:45:03.046732Z","caller":"traceutil/trace.go:171","msg":"trace[395617025] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"188.401702ms","start":"2026-02-27T16:45:02.858297Z","end":"2026-02-27T16:45:03.046699Z","steps":["trace[395617025] 'process raft request' (duration: 188.2942ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:45:03.046873Z","caller":"traceutil/trace.go:171","msg":"trace[1221634525] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"274.776562ms","start":"2026-02-27T16:45:02.772053Z","end":"2026-02-27T16:45:03.04683Z","steps":["trace[1221634525] 'process raft request' (duration: 274.447615ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:45:03.046984Z","caller":"traceutil/trace.go:171","msg":"trace[1687928539] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:690; }","duration":"240.70152ms","start":"2026-02-27T16:45:02.806274Z","end":"2026-02-27T16:45:03.046975Z","steps":["trace[1687928539] 'read index received' (duration: 35.599955ms)","trace[1687928539] 'applied index is now lower than readState.Index' (duration: 205.098955ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T16:45:03.047168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.882364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-79zkq\" ","response":"range_response_count:1 size:18512"} {"level":"warn","ts":"2026-02-27T16:45:03.047184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.290505ms","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-02-27T16:45:03.047215Z","caller":"traceutil/trace.go:171","msg":"trace[568449109] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:675; }","duration":"146.380077ms","start":"2026-02-27T16:45:02.900826Z","end":"2026-02-27T16:45:03.047206Z","steps":["trace[568449109] 'agreement among raft nodes before linearized reading' (duration: 146.300065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:45:03.047463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.469518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-02-27T16:45:03.047194Z","caller":"traceutil/trace.go:171","msg":"trace[682297302] range","detail":"{range_begin:/registry/pods/kube-system/cilium-79zkq; range_end:; response_count:1; response_revision:675; }","duration":"240.953395ms","start":"2026-02-27T16:45:02.806233Z","end":"2026-02-27T16:45:03.047187Z","steps":["trace[682297302] 'agreement among raft nodes before linearized reading' (duration: 240.853263ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:45:03.047485Z","caller":"traceutil/trace.go:171","msg":"trace[35850511] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:675; }","duration":"146.52647ms","start":"2026-02-27T16:45:02.900953Z","end":"2026-02-27T16:45:03.047479Z","steps":["trace[35850511] 'agreement among raft nodes before linearized reading' (duration: 146.341066ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:45:03.046549Z","caller":"traceutil/trace.go:171","msg":"trace[1724249289] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"274.853273ms","start":"2026-02-27T16:45:02.771673Z","end":"2026-02-27T16:45:03.046526Z","steps":["trace[1724249289] 'process raft request' (duration: 190.845264ms)","trace[1724249289] 'compare' (duration: 83.819775ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T16:45:03.355668Z","caller":"traceutil/trace.go:171","msg":"trace[806261771] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:696; }","duration":"247.523504ms","start":"2026-02-27T16:45:03.108125Z","end":"2026-02-27T16:45:03.355649Z","steps":["trace[806261771] 'read index received' (duration: 242.839253ms)","trace[806261771] 'applied index is now lower than readState.Index' (duration: 4.683531ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T16:45:03.355982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.334369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-27T16:45:03.356013Z","caller":"traceutil/trace.go:171","msg":"trace[1500836578] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:678; }","duration":"137.445332ms","start":"2026-02-27T16:45:03.21856Z","end":"2026-02-27T16:45:03.356006Z","steps":["trace[1500836578] 'agreement among raft nodes before linearized reading' (duration: 137.360591ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:45:03.35611Z","caller":"traceutil/trace.go:171","msg":"trace[1221301247] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"288.62106ms","start":"2026-02-27T16:45:03.067459Z","end":"2026-02-27T16:45:03.356081Z","steps":["trace[1221301247] 'process raft request' (duration: 288.13678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:45:03.356202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.171857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-02-27T16:45:03.356225Z","caller":"traceutil/trace.go:171","msg":"trace[72282053] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:678; }","duration":"137.205457ms","start":"2026-02-27T16:45:03.219011Z","end":"2026-02-27T16:45:03.356217Z","steps":["trace[72282053] 'agreement among raft nodes before linearized reading' (duration: 137.123345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:45:03.356334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.619526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"warn","ts":"2026-02-27T16:45:03.356363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.196688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-02-27T16:45:03.356417Z","caller":"traceutil/trace.go:171","msg":"trace[565607184] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:678; }","duration":"137.715708ms","start":"2026-02-27T16:45:03.218681Z","end":"2026-02-27T16:45:03.356397Z","steps":["trace[565607184] 'agreement among raft nodes before linearized reading' (duration: 137.539324ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:45:03.356454Z","caller":"traceutil/trace.go:171","msg":"trace[1210888745] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"295.094619ms","start":"2026-02-27T16:45:03.060758Z","end":"2026-02-27T16:45:03.355853Z","steps":["trace[1210888745] 'process raft request' (duration: 290.127122ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:45:03.356464Z","caller":"traceutil/trace.go:171","msg":"trace[1446981882] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:678; }","duration":"248.369332ms","start":"2026-02-27T16:45:03.10807Z","end":"2026-02-27T16:45:03.356439Z","steps":["trace[1446981882] 'agreement among raft nodes before linearized reading' (duration: 247.993874ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:45:03.523248Z","caller":"traceutil/trace.go:171","msg":"trace[1295690101] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"147.659803ms","start":"2026-02-27T16:45:03.375555Z","end":"2026-02-27T16:45:03.523214Z","steps":["trace[1295690101] 'process raft request' (duration: 124.31648ms)","trace[1295690101] 'compare' (duration: 23.192999ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T16:45:03.660975Z","caller":"traceutil/trace.go:171","msg":"trace[104496330] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:700; }","duration":"146.097419ms","start":"2026-02-27T16:45:03.514856Z","end":"2026-02-27T16:45:03.660954Z","steps":["trace[104496330] 'read index received' (duration: 139.308963ms)","trace[104496330] 'applied index is now lower than readState.Index' (duration: 6.787856ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T16:45:03.661061Z","caller":"traceutil/trace.go:171","msg":"trace[1609755274] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"274.499456ms","start":"2026-02-27T16:45:03.386554Z","end":"2026-02-27T16:45:03.661054Z","steps":["trace[1609755274] 'process raft request' (duration: 267.259679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:45:03.661275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.375984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T16:45:03.661332Z","caller":"traceutil/trace.go:171","msg":"trace[77578725] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:680; }","duration":"146.520327ms","start":"2026-02-27T16:45:03.514797Z","end":"2026-02-27T16:45:03.661317Z","steps":["trace[77578725] 'agreement among raft nodes before linearized reading' (duration: 146.352944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:45:03.661314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.406775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T16:45:03.661427Z","caller":"traceutil/trace.go:171","msg":"trace[581924150] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:680; }","duration":"146.605019ms","start":"2026-02-27T16:45:03.514798Z","end":"2026-02-27T16:45:03.661403Z","steps":["trace[581924150] 'agreement among raft nodes before linearized reading' (duration: 146.319073ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:45:03.863062Z","caller":"traceutil/trace.go:171","msg":"trace[654409754] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:701; }","duration":"202.010124ms","start":"2026-02-27T16:45:03.661029Z","end":"2026-02-27T16:45:03.863039Z","steps":["trace[654409754] 'read index received' (duration: 176.092365ms)","trace[654409754] 'applied index is now lower than readState.Index' (duration: 25.916579ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T16:45:03.863245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.102743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T16:45:03.863245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.391317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T16:45:03.863278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.336355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T16:45:03.863362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.277186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T16:45:03.863375Z","caller":"traceutil/trace.go:171","msg":"trace[124338255] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"276.503119ms","start":"2026-02-27T16:45:03.586846Z","end":"2026-02-27T16:45:03.863349Z","steps":["trace[124338255] 'agreement among raft nodes before linearized reading' (duration: 276.314925ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:45:03.863375Z","caller":"traceutil/trace.go:171","msg":"trace[1256207305] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"254.019834ms","start":"2026-02-27T16:45:03.609331Z","end":"2026-02-27T16:45:03.863351Z","steps":["trace[1256207305] 'process raft request' (duration: 227.84716ms)","trace[1256207305] 'compare' (duration: 25.733124ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T16:45:03.863419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.016113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T16:45:03.863356Z","caller":"traceutil/trace.go:171","msg":"trace[1121119732] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:681; }","duration":"328.53325ms","start":"2026-02-27T16:45:03.534796Z","end":"2026-02-27T16:45:03.863329Z","steps":["trace[1121119732] 'agreement among raft nodes before linearized reading' (duration: 328.306725ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:45:03.863461Z","caller":"traceutil/trace.go:171","msg":"trace[842534684] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:681; }","duration":"208.088535ms","start":"2026-02-27T16:45:03.655361Z","end":"2026-02-27T16:45:03.863449Z","steps":["trace[842534684] 'agreement among raft nodes before linearized reading' (duration: 208.029363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:45:03.863487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:45:03.534789Z","time spent":"328.684023ms","remote":"127.0.0.1:48860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 "} {"level":"warn","ts":"2026-02-27T16:45:03.863507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.169937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-rjtnl\" ","response":"range_response_count:1 size:3871"} {"level":"info","ts":"2026-02-27T16:45:03.863536Z","caller":"traceutil/trace.go:171","msg":"trace[1218937351] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-rjtnl; range_end:; response_count:1; response_revision:681; }","duration":"331.247368ms","start":"2026-02-27T16:45:03.53228Z","end":"2026-02-27T16:45:03.863527Z","steps":["trace[1218937351] 'agreement among raft nodes before linearized reading' (duration: 331.137406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:45:03.863573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:45:03.532248Z","time spent":"331.31844ms","remote":"127.0.0.1:59488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":3895,"request content":"key:\"/registry/pods/kube-system/coredns-67659f764b-rjtnl\" "} {"level":"warn","ts":"2026-02-27T16:45:03.863279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.37864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T16:45:03.863645Z","caller":"traceutil/trace.go:171","msg":"trace[527236290] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:681; }","duration":"207.785348ms","start":"2026-02-27T16:45:03.655843Z","end":"2026-02-27T16:45:03.863629Z","steps":["trace[527236290] 'agreement among raft nodes before linearized reading' (duration: 207.338319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:45:03.863711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.073074ms","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":"info","ts":"2026-02-27T16:45:03.863309Z","caller":"traceutil/trace.go:171","msg":"trace[82401169] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:681; }","duration":"329.199424ms","start":"2026-02-27T16:45:03.534099Z","end":"2026-02-27T16:45:03.863298Z","steps":["trace[82401169] 'agreement among raft nodes before linearized reading' (duration: 329.086402ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:45:03.863753Z","caller":"traceutil/trace.go:171","msg":"trace[1441859620] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:681; }","duration":"208.146526ms","start":"2026-02-27T16:45:03.655595Z","end":"2026-02-27T16:45:03.863742Z","steps":["trace[1441859620] 'agreement among raft nodes before linearized reading' (duration: 208.081144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:45:03.863772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.751325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T16:45:03.86379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:45:03.534091Z","time spent":"329.669764ms","remote":"127.0.0.1:48860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-02-27T16:45:03.863802Z","caller":"traceutil/trace.go:171","msg":"trace[1896605107] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:681; }","duration":"348.795097ms","start":"2026-02-27T16:45:03.514998Z","end":"2026-02-27T16:45:03.863793Z","steps":["trace[1896605107] 'agreement among raft nodes before linearized reading' (duration: 348.745906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:45:03.863831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:45:03.514989Z","time spent":"348.834578ms","remote":"127.0.0.1:48858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 "} {"level":"info","ts":"2026-02-27T16:45:03.863399Z","caller":"traceutil/trace.go:171","msg":"trace[1313169155] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:681; }","duration":"329.333377ms","start":"2026-02-27T16:45:03.534058Z","end":"2026-02-27T16:45:03.863391Z","steps":["trace[1313169155] 'agreement among raft nodes before linearized reading' (duration: 329.279226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:45:03.863881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.10568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T16:45:03.863999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.643908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T16:45:03.864034Z","caller":"traceutil/trace.go:171","msg":"trace[1560431176] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:681; }","duration":"100.71401ms","start":"2026-02-27T16:45:03.763306Z","end":"2026-02-27T16:45:03.86402Z","steps":["trace[1560431176] 'agreement among raft nodes before linearized reading' (duration: 100.633308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:45:03.863895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:45:03.534045Z","time spent":"329.839998ms","remote":"127.0.0.1:48860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 "} {"level":"warn","ts":"2026-02-27T16:45:03.864219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.453829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T16:45:03.863938Z","caller":"traceutil/trace.go:171","msg":"trace[1774604344] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:681; }","duration":"231.193243ms","start":"2026-02-27T16:45:03.632728Z","end":"2026-02-27T16:45:03.863922Z","steps":["trace[1774604344] 'agreement among raft nodes before linearized reading' (duration: 231.117701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:45:03.863267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.6117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T16:45:03.864306Z","caller":"traceutil/trace.go:171","msg":"trace[989389273] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:681; }","duration":"231.611162ms","start":"2026-02-27T16:45:03.632674Z","end":"2026-02-27T16:45:03.864285Z","steps":["trace[989389273] 'agreement among raft nodes before linearized reading' (duration: 230.413446ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:45:03.864336Z","caller":"traceutil/trace.go:171","msg":"trace[1780193694] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:681; }","duration":"231.744495ms","start":"2026-02-27T16:45:03.632583Z","end":"2026-02-27T16:45:03.864327Z","steps":["trace[1780193694] 'agreement among raft nodes before linearized reading' (duration: 230.552699ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:45:04.205526Z","caller":"traceutil/trace.go:171","msg":"trace[800745656] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"334.295553ms","start":"2026-02-27T16:45:03.871204Z","end":"2026-02-27T16:45:04.205499Z","steps":["trace[800745656] 'process raft request' (duration: 334.19293ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:45:04.205613Z","caller":"traceutil/trace.go:171","msg":"trace[295721411] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"334.384184ms","start":"2026-02-27T16:45:03.871172Z","end":"2026-02-27T16:45:04.205556Z","steps":["trace[295721411] 'process raft request' (duration: 260.922346ms)","trace[295721411] 'compare' (duration: 73.153271ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T16:45:04.205695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:45:03.871185Z","time spent":"334.418365ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T16:45:04.20595Z","caller":"traceutil/trace.go:171","msg":"trace[1431476184] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"333.074387ms","start":"2026-02-27T16:45:03.872853Z","end":"2026-02-27T16:45:04.205928Z","steps":["trace[1431476184] 'process raft request' (duration: 332.604306ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:45:04.20601Z","caller":"traceutil/trace.go:171","msg":"trace[1463697182] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:703; }","duration":"187.996314ms","start":"2026-02-27T16:45:04.017981Z","end":"2026-02-27T16:45:04.205977Z","steps":["trace[1463697182] 'read index received' (duration: 114.108266ms)","trace[1463697182] 'applied index is now lower than readState.Index' (duration: 73.883068ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T16:45:04.20608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:45:03.872845Z","time spent":"333.158608ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T16:45:04.206067Z","caller":"traceutil/trace.go:171","msg":"trace[1997415371] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"332.597116ms","start":"2026-02-27T16:45:03.873456Z","end":"2026-02-27T16:45:04.206053Z","steps":["trace[1997415371] 'process raft request' (duration: 332.35458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:45:04.206152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.161857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T16:45:04.206228Z","caller":"traceutil/trace.go:171","msg":"trace[1017318950] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:686; }","duration":"188.209568ms","start":"2026-02-27T16:45:04.017973Z","end":"2026-02-27T16:45:04.206182Z","steps":["trace[1017318950] 'agreement among raft nodes before linearized reading' (duration: 188.131776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:45:04.206302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:45:03.871145Z","time spent":"334.578098ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T16:45:04.206319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:45:03.873446Z","time spent":"332.727389ms","remote":"127.0.0.1:59488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4761,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T16:45:04.257687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.370709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T16:45:04.257722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.489471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T16:45:04.25779Z","caller":"traceutil/trace.go:171","msg":"trace[889638438] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:687; }","duration":"239.557263ms","start":"2026-02-27T16:45:04.018211Z","end":"2026-02-27T16:45:04.257768Z","steps":["trace[889638438] 'agreement among raft nodes before linearized reading' (duration: 239.339708ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:45:04.257805Z","caller":"traceutil/trace.go:171","msg":"trace[941444901] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:687; }","duration":"239.648174ms","start":"2026-02-27T16:45:04.018138Z","end":"2026-02-27T16:45:04.257786Z","steps":["trace[941444901] 'agreement among raft nodes before linearized reading' (duration: 239.47624ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:45:04.373536Z","caller":"traceutil/trace.go:171","msg":"trace[1359326930] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"108.74942ms","start":"2026-02-27T16:45:04.264754Z","end":"2026-02-27T16:45:04.373504Z","steps":["trace[1359326930] 'process raft request' (duration: 81.432468ms)","trace[1359326930] 'compare' (duration: 27.054267ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T16:45:05.108215Z","caller":"traceutil/trace.go:171","msg":"trace[2075711614] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"123.70684ms","start":"2026-02-27T16:45:04.984468Z","end":"2026-02-27T16:45:05.108175Z","steps":["trace[2075711614] 'process raft request' (duration: 66.846787ms)","trace[2075711614] 'compare' (duration: 56.590357ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T16:45:16.050727Z","caller":"traceutil/trace.go:171","msg":"trace[731683992] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"129.079557ms","start":"2026-02-27T16:45:15.92162Z","end":"2026-02-27T16:45:16.0507Z","steps":["trace[731683992] 'process raft request' (duration: 128.889343ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:45:16.17079Z","caller":"traceutil/trace.go:171","msg":"trace[1101788559] transaction","detail":"{read_only:false; number_of_response:1; response_revision:797; }","duration":"114.942742ms","start":"2026-02-27T16:45:16.055818Z","end":"2026-02-27T16:45:16.170761Z","steps":["trace[1101788559] 'process raft request' (duration: 68.980835ms)","trace[1101788559] 'compare' (duration: 45.695432ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T16:45:16.565997Z","caller":"traceutil/trace.go:171","msg":"trace[120716293] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"130.206624ms","start":"2026-02-27T16:45:16.435756Z","end":"2026-02-27T16:45:16.565963Z","steps":["trace[120716293] 'process raft request' (duration: 98.993569ms)","trace[120716293] 'compare' (duration: 31.067532ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T16:45:36.952902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.315402ms","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-02-27T16:45:36.952982Z","caller":"traceutil/trace.go:171","msg":"trace[907462828] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:857; }","duration":"112.470874ms","start":"2026-02-27T16:45:36.840496Z","end":"2026-02-27T16:45:36.952966Z","steps":["trace[907462828] 'range keys from in-memory index tree' (duration: 112.135249ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:45:57.299285Z","caller":"traceutil/trace.go:171","msg":"trace[2138553374] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"126.145172ms","start":"2026-02-27T16:45:57.173126Z","end":"2026-02-27T16:45:57.299272Z","steps":["trace[2138553374] 'process raft request' (duration: 126.056011ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:46:07.366926Z","caller":"traceutil/trace.go:171","msg":"trace[1474258438] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"134.181913ms","start":"2026-02-27T16:46:07.232719Z","end":"2026-02-27T16:46:07.366901Z","steps":["trace[1474258438] 'process raft request' (duration: 133.9983ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:46:08.238423Z","caller":"traceutil/trace.go:171","msg":"trace[1257731781] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"145.645781ms","start":"2026-02-27T16:46:08.092754Z","end":"2026-02-27T16:46:08.2384Z","steps":["trace[1257731781] 'process raft request' (duration: 145.50627ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:46:11.262266Z","caller":"traceutil/trace.go:171","msg":"trace[1842464278] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"123.776271ms","start":"2026-02-27T16:46:11.138465Z","end":"2026-02-27T16:46:11.262241Z","steps":["trace[1842464278] 'process raft request' (duration: 122.566635ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:46:14.892437Z","caller":"traceutil/trace.go:171","msg":"trace[213299228] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"114.189664ms","start":"2026-02-27T16:46:14.778221Z","end":"2026-02-27T16:46:14.892411Z","steps":["trace[213299228] 'process raft request' (duration: 114.027961ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:46:15.057107Z","caller":"traceutil/trace.go:171","msg":"trace[668746284] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"140.959015ms","start":"2026-02-27T16:46:14.916118Z","end":"2026-02-27T16:46:15.057077Z","steps":["trace[668746284] 'read index received' (duration: 66.09165ms)","trace[668746284] 'applied index is now lower than readState.Index' (duration: 74.865765ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T16:46:15.05739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.244868ms","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-02-27T16:46:15.057457Z","caller":"traceutil/trace.go:171","msg":"trace[1926993648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:992; }","duration":"141.34314ms","start":"2026-02-27T16:46:14.916095Z","end":"2026-02-27T16:46:15.057438Z","steps":["trace[1926993648] 'agreement among raft nodes before linearized reading' (duration: 141.121307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:46:21.645108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.084787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2568"} {"level":"info","ts":"2026-02-27T16:46:21.645212Z","caller":"traceutil/trace.go:171","msg":"trace[22343335] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1017; }","duration":"113.263499ms","start":"2026-02-27T16:46:21.531931Z","end":"2026-02-27T16:46:21.645194Z","steps":["trace[22343335] 'range keys from in-memory index tree' (duration: 112.928206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:46:21.645211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.938149ms","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-02-27T16:46:21.645507Z","caller":"traceutil/trace.go:171","msg":"trace[746571445] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1017; }","duration":"114.689877ms","start":"2026-02-27T16:46:21.530577Z","end":"2026-02-27T16:46:21.645267Z","steps":["trace[746571445] 'range keys from in-memory index tree' (duration: 113.750457ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:46:21.818825Z","caller":"traceutil/trace.go:171","msg":"trace[2121024513] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"161.894049ms","start":"2026-02-27T16:46:21.6569Z","end":"2026-02-27T16:46:21.818794Z","steps":["trace[2121024513] 'process raft request' (duration: 124.140193ms)","trace[2121024513] 'compare' (duration: 37.634724ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T16:46:22.690146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.955023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T16:46:22.69032Z","caller":"traceutil/trace.go:171","msg":"trace[1465627442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1020; }","duration":"105.179185ms","start":"2026-02-27T16:46:22.585115Z","end":"2026-02-27T16:46:22.690294Z","steps":["trace[1465627442] 'range keys from in-memory index tree' (duration: 104.818691ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:46:23.288855Z","caller":"traceutil/trace.go:171","msg":"trace[1580126936] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"149.811164ms","start":"2026-02-27T16:46:23.139022Z","end":"2026-02-27T16:46:23.288834Z","steps":["trace[1580126936] 'read index received' (duration: 149.700053ms)","trace[1580126936] 'applied index is now lower than readState.Index' (duration: 110.101µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T16:46:23.2889Z","caller":"traceutil/trace.go:171","msg":"trace[386660754] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"322.326941ms","start":"2026-02-27T16:46:22.966545Z","end":"2026-02-27T16:46:23.288872Z","steps":["trace[386660754] 'process raft request' (duration: 322.1814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:46:23.289023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:46:22.966524Z","time spent":"322.428843ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T16:46:23.289129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.147109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T16:46:23.289164Z","caller":"traceutil/trace.go:171","msg":"trace[2119716036] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1021; }","duration":"110.381352ms","start":"2026-02-27T16:46:23.178772Z","end":"2026-02-27T16:46:23.289154Z","steps":["trace[2119716036] 'agreement among raft nodes before linearized reading' (duration: 110.175179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:46:23.289162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.125618ms","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-02-27T16:46:23.289258Z","caller":"traceutil/trace.go:171","msg":"trace[1717280687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1021; }","duration":"150.29221ms","start":"2026-02-27T16:46:23.138945Z","end":"2026-02-27T16:46:23.289237Z","steps":["trace[1717280687] 'agreement among raft nodes before linearized reading' (duration: 149.991576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:46:23.745195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.031619ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T16:46:23.745518Z","caller":"traceutil/trace.go:171","msg":"trace[1139018064] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"453.103815ms","start":"2026-02-27T16:46:23.292259Z","end":"2026-02-27T16:46:23.745363Z","steps":["trace[1139018064] 'process raft request' (duration: 235.948755ms)","trace[1139018064] 'compare' (duration: 216.695765ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T16:46:23.74571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:46:23.292237Z","time spent":"453.363759ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T16:46:23.74624Z","caller":"traceutil/trace.go:171","msg":"trace[1225223368] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1061; }","duration":"346.970065ms","start":"2026-02-27T16:46:23.399245Z","end":"2026-02-27T16:46:23.746215Z","steps":["trace[1225223368] 'read index received' (duration: 128.989895ms)","trace[1225223368] 'applied index is now lower than readState.Index' (duration: 217.97731ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T16:46:23.746408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.505051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T16:46:23.746467Z","caller":"traceutil/trace.go:171","msg":"trace[867790185] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"160.611373ms","start":"2026-02-27T16:46:23.585829Z","end":"2026-02-27T16:46:23.746441Z","steps":["trace[867790185] 'agreement among raft nodes before linearized reading' (duration: 160.531812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:46:23.746455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.191047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-27T16:46:23.746525Z","caller":"traceutil/trace.go:171","msg":"trace[1322454363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1023; }","duration":"347.315038ms","start":"2026-02-27T16:46:23.399197Z","end":"2026-02-27T16:46:23.746512Z","steps":["trace[1322454363] 'agreement among raft nodes before linearized reading' (duration: 347.094206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:46:23.746557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:46:23.399174Z","time spent":"347.376899ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T16:46:23.746367Z","caller":"traceutil/trace.go:171","msg":"trace[1466826487] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"453.075595ms","start":"2026-02-27T16:46:23.293195Z","end":"2026-02-27T16:46:23.746271Z","steps":["trace[1466826487] 'process raft request' (duration: 452.109063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:46:23.746773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:46:23.293162Z","time spent":"453.49423ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T16:46:24.295474Z","caller":"traceutil/trace.go:171","msg":"trace[2093448592] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"543.845844ms","start":"2026-02-27T16:46:23.751579Z","end":"2026-02-27T16:46:24.295425Z","steps":["trace[2093448592] 'process raft request' (duration: 511.57984ms)","trace[2093448592] 'compare' (duration: 32.087532ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T16:46:24.295784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:46:23.75156Z","time spent":"544.036526ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T16:46:24.317265Z","caller":"traceutil/trace.go:171","msg":"trace[181354408] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"564.45179ms","start":"2026-02-27T16:46:23.752787Z","end":"2026-02-27T16:46:24.317239Z","steps":["trace[181354408] 'process raft request' (duration: 564.288348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:46:24.317454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:46:23.752766Z","time spent":"564.572641ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T16:46:25.493955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.872442ms","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-02-27T16:46:25.494059Z","caller":"traceutil/trace.go:171","msg":"trace[817036027] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1025; }","duration":"201.028573ms","start":"2026-02-27T16:46:25.293007Z","end":"2026-02-27T16:46:25.494036Z","steps":["trace[817036027] 'range keys from in-memory index tree' (duration: 200.677739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:46:25.494132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.766817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T16:46:25.494202Z","caller":"traceutil/trace.go:171","msg":"trace[52424395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1025; }","duration":"907.867438ms","start":"2026-02-27T16:46:24.586319Z","end":"2026-02-27T16:46:25.494187Z","steps":["trace[52424395] 'range keys from in-memory index tree' (duration: 907.643175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:46:25.494241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:46:24.58626Z","time spent":"907.974279ms","remote":"127.0.0.1:60204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T16:46:25.494611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.333894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.36\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-27T16:46:25.495001Z","caller":"traceutil/trace.go:171","msg":"trace[745571666] range","detail":"{range_begin:/registry/masterleases/162.253.55.36; range_end:; response_count:1; response_revision:1025; }","duration":"600.782659ms","start":"2026-02-27T16:46:24.89418Z","end":"2026-02-27T16:46:25.494962Z","steps":["trace[745571666] 'range keys from in-memory index tree' (duration: 600.119082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:46:25.495101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:46:24.89416Z","time spent":"600.92889ms","remote":"127.0.0.1:59344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.36\" "} {"level":"info","ts":"2026-02-27T16:46:27.286233Z","caller":"traceutil/trace.go:171","msg":"trace[133716488] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"261.47169ms","start":"2026-02-27T16:46:27.024737Z","end":"2026-02-27T16:46:27.286208Z","steps":["trace[133716488] 'process raft request' (duration: 261.321169ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:46:27.874863Z","caller":"traceutil/trace.go:171","msg":"trace[1539191660] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"112.471641ms","start":"2026-02-27T16:46:27.762371Z","end":"2026-02-27T16:46:27.874843Z","steps":["trace[1539191660] 'process raft request' (duration: 112.36859ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:46:27.88734Z","caller":"traceutil/trace.go:171","msg":"trace[1971336336] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1079; }","duration":"117.359429ms","start":"2026-02-27T16:46:27.769963Z","end":"2026-02-27T16:46:27.887322Z","steps":["trace[1971336336] 'read index received' (duration: 104.857042ms)","trace[1971336336] 'applied index is now lower than readState.Index' (duration: 12.501457ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T16:46:27.887411Z","caller":"traceutil/trace.go:171","msg":"trace[2003566156] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"124.840876ms","start":"2026-02-27T16:46:27.762547Z","end":"2026-02-27T16:46:27.887388Z","steps":["trace[2003566156] 'process raft request' (duration: 124.695615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:46:27.887861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.883605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-node-lease/\" range_end:\"/registry/secrets/kube-node-lease0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T16:46:27.887916Z","caller":"traceutil/trace.go:171","msg":"trace[1115405762] range","detail":"{range_begin:/registry/secrets/kube-node-lease/; range_end:/registry/secrets/kube-node-lease0; response_count:0; response_revision:1039; }","duration":"117.989687ms","start":"2026-02-27T16:46:27.769911Z","end":"2026-02-27T16:46:27.887901Z","steps":["trace[1115405762] 'agreement among raft nodes before linearized reading' (duration: 117.535281ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:46:29.736727Z","caller":"traceutil/trace.go:171","msg":"trace[560448377] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"122.152831ms","start":"2026-02-27T16:46:29.614531Z","end":"2026-02-27T16:46:29.736684Z","steps":["trace[560448377] 'process raft request' (duration: 121.912158ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:46:30.547397Z","caller":"traceutil/trace.go:171","msg":"trace[1623420] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1088; }","duration":"146.78993ms","start":"2026-02-27T16:46:30.400584Z","end":"2026-02-27T16:46:30.547374Z","steps":["trace[1623420] 'read index received' (duration: 49.763196ms)","trace[1623420] 'applied index is now lower than readState.Index' (duration: 97.024983ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T16:46:30.547793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.387288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cluster-admin\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-02-27T16:46:30.547833Z","caller":"traceutil/trace.go:171","msg":"trace[1077356857] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:1; response_revision:1046; }","duration":"148.479809ms","start":"2026-02-27T16:46:30.399344Z","end":"2026-02-27T16:46:30.547824Z","steps":["trace[1077356857] 'agreement among raft nodes before linearized reading' (duration: 148.368467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:46:44.531694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.850238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T16:46:44.531817Z","caller":"traceutil/trace.go:171","msg":"trace[1575263669] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1128; }","duration":"163.701356ms","start":"2026-02-27T16:46:44.368096Z","end":"2026-02-27T16:46:44.531797Z","steps":["trace[1575263669] 'read index received' (duration: 31.573045ms)","trace[1575263669] 'applied index is now lower than readState.Index' (duration: 132.126661ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T16:46:44.531922Z","caller":"traceutil/trace.go:171","msg":"trace[1219628212] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"164.887188ms","start":"2026-02-27T16:46:44.367017Z","end":"2026-02-27T16:46:44.531905Z","steps":["trace[1219628212] 'process raft request' (duration: 32.738897ms)","trace[1219628212] 'compare' (duration: 131.741537ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T16:46:44.532142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.024799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/\" range_end:\"/registry/daemonsets/kube-system0\" limit:500 ","response":"range_response_count:2 size:17391"} {"level":"info","ts":"2026-02-27T16:46:44.532208Z","caller":"traceutil/trace.go:171","msg":"trace[434182250] range","detail":"{range_begin:/registry/daemonsets/kube-system/; range_end:/registry/daemonsets/kube-system0; response_count:2; response_revision:1084; }","duration":"164.10367ms","start":"2026-02-27T16:46:44.368081Z","end":"2026-02-27T16:46:44.532185Z","steps":["trace[434182250] 'agreement among raft nodes before linearized reading' (duration: 163.914258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:46:44.532654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.289281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:91 size:67568"} {"level":"info","ts":"2026-02-27T16:46:44.532733Z","caller":"traceutil/trace.go:171","msg":"trace[562526199] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:91; response_revision:1084; }","duration":"164.387292ms","start":"2026-02-27T16:46:44.368323Z","end":"2026-02-27T16:46:44.532711Z","steps":["trace[562526199] 'agreement among raft nodes before linearized reading' (duration: 163.604163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:46:44.532778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.185817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:91 size:67568"} {"level":"info","ts":"2026-02-27T16:46:44.532889Z","caller":"traceutil/trace.go:171","msg":"trace[587222572] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:91; response_revision:1084; }","duration":"161.320538ms","start":"2026-02-27T16:46:44.371542Z","end":"2026-02-27T16:46:44.532863Z","steps":["trace[587222572] 'agreement among raft nodes before linearized reading' (duration: 160.53891ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:46:52.541571Z","caller":"traceutil/trace.go:171","msg":"trace[500507951] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"142.101064ms","start":"2026-02-27T16:46:52.399429Z","end":"2026-02-27T16:46:52.54153Z","steps":["trace[500507951] 'process raft request' (duration: 141.854731ms)"],"step_count":1}