{"level":"warn","ts":"2026-02-02T18:02:18.890577Z","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-02T18:02:18.890825Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.70: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.70:2380","--initial-cluster=instance=https://162.253.55.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.70: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-02T18:02:18.891004Z","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-02T18:02:18.891023Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-02-02T18:02:18.891049Z","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-02T18:02:18.892041Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"]} {"level":"info","ts":"2026-02-02T18:02:18.892234Z","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":2,"max-cpu-available":2,"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.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.70: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-02T18:02:18.918302Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"22.988772ms"} {"level":"info","ts":"2026-02-02T18:02:18.968357Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"23887246632736aa","cluster-id":"62cfeca3ef46d3ef"} {"level":"info","ts":"2026-02-02T18:02:18.968432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=()"} {"level":"info","ts":"2026-02-02T18:02:18.968456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 0"} {"level":"info","ts":"2026-02-02T18:02:18.968471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 23887246632736aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-02T18:02:18.968495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 1"} {"level":"info","ts":"2026-02-02T18:02:18.968528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"warn","ts":"2026-02-02T18:02:19.047355Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-02T18:02:19.054052Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-02T18:02:19.082777Z","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-02T18:02:19.101058Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"23887246632736aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-02T18:02:19.101413Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"23887246632736aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-02T18:02:19.101532Z","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-02T18:02:19.101562Z","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-02T18:02:19.101571Z","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-02T18:02:19.107137Z","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-02T18:02:19.107342Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"23887246632736aa","initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-02T18:02:19.107365Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-02T18:02:19.107463Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-02-02T18:02:19.107472Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-02-02T18:02:19.117334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"info","ts":"2026-02-02T18:02:19.117474Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","added-peer-id":"23887246632736aa","added-peer-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-02-02T18:02:19.268919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa is starting a new election at term 1"} {"level":"info","ts":"2026-02-02T18:02:19.268967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became pre-candidate at term 1"} {"level":"info","ts":"2026-02-02T18:02:19.268981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgPreVoteResp from 23887246632736aa at term 1"} {"level":"info","ts":"2026-02-02T18:02:19.268992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became candidate at term 2"} {"level":"info","ts":"2026-02-02T18:02:19.269005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgVoteResp from 23887246632736aa at term 2"} {"level":"info","ts":"2026-02-02T18:02:19.269024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became leader at term 2"} {"level":"info","ts":"2026-02-02T18:02:19.269031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 23887246632736aa elected leader 23887246632736aa at term 2"} {"level":"info","ts":"2026-02-02T18:02:19.270828Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"23887246632736aa","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.70:2379]}","request-path":"/0/members/23887246632736aa/attributes","cluster-id":"62cfeca3ef46d3ef","publish-timeout":"7s"} {"level":"info","ts":"2026-02-02T18:02:19.270923Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T18:02:19.270978Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T18:02:19.271325Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T18:02:19.271381Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-02T18:02:19.271404Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-02T18:02:19.27586Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.70:2379"} {"level":"info","ts":"2026-02-02T18:02:19.276372Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-02T18:02:19.27676Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T18:02:19.276829Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T18:02:19.287346Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T18:02:31.607843Z","caller":"traceutil/trace.go:171","msg":"trace[1073755774] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"132.315667ms","start":"2026-02-02T18:02:31.475512Z","end":"2026-02-02T18:02:31.607827Z","steps":["trace[1073755774] 'process raft request' (duration: 132.04275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:37.333221Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132483278986148,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T18:02:38.277109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.409685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-02T18:02:38.277351Z","caller":"traceutil/trace.go:171","msg":"trace[859450275] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:434; }","duration":"1.445128944s","start":"2026-02-02T18:02:36.832207Z","end":"2026-02-02T18:02:38.277336Z","steps":["trace[859450275] 'read index received' (duration: 586.894891ms)","trace[859450275] 'applied index is now lower than readState.Index' (duration: 858.233153ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:02:38.282563Z","caller":"traceutil/trace.go:171","msg":"trace[1568680697] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"1.646396656s","start":"2026-02-02T18:02:36.636145Z","end":"2026-02-02T18:02:38.282542Z","steps":["trace[1568680697] 'process raft request' (duration: 782.95032ms)","trace[1568680697] 'compare' (duration: 857.288783ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:02:38.282735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:36.636123Z","time spent":"1.646495657s","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3541,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T18:02:38.28305Z","caller":"traceutil/trace.go:171","msg":"trace[53102787] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"1.646400755s","start":"2026-02-02T18:02:36.636637Z","end":"2026-02-02T18:02:38.283038Z","steps":["trace[53102787] 'process raft request' (duration: 1.64057994s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:38.283262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:36.63663Z","time spent":"1.646455506s","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:02:38.289117Z","caller":"traceutil/trace.go:171","msg":"trace[162245031] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"1.652317935s","start":"2026-02-02T18:02:36.636792Z","end":"2026-02-02T18:02:38.28911Z","steps":["trace[162245031] 'process raft request' (duration: 1.640505888s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:38.289263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:36.636786Z","time spent":"1.652356665s","remote":"127.0.0.1:34094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T18:02:38.289553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.457339829s","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-02T18:02:38.289577Z","caller":"traceutil/trace.go:171","msg":"trace[120182422] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:426; }","duration":"1.45739662s","start":"2026-02-02T18:02:36.832175Z","end":"2026-02-02T18:02:38.289572Z","steps":["trace[120182422] 'agreement among raft nodes before linearized reading' (duration: 1.457344359s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:38.289794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:36.83216Z","time spent":"1.457627215s","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-02T18:02:38.290035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.99606ms","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-02T18:02:38.290086Z","caller":"traceutil/trace.go:171","msg":"trace[2061244904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:426; }","duration":"594.081402ms","start":"2026-02-02T18:02:37.695999Z","end":"2026-02-02T18:02:38.29008Z","steps":["trace[2061244904] 'agreement among raft nodes before linearized reading' (duration: 593.96445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:38.290121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:37.695961Z","time spent":"594.153394ms","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-02T18:02:38.291036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.144331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-02T18:02:38.291061Z","caller":"traceutil/trace.go:171","msg":"trace[1678906986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:426; }","duration":"577.187052ms","start":"2026-02-02T18:02:37.713868Z","end":"2026-02-02T18:02:38.291055Z","steps":["trace[1678906986] 'agreement among raft nodes before linearized reading' (duration: 576.547668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:38.291075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:37.713859Z","time spent":"577.212533ms","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T18:02:38.29117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.563375ms","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-02T18:02:38.291184Z","caller":"traceutil/trace.go:171","msg":"trace[1368668710] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:426; }","duration":"585.600866ms","start":"2026-02-02T18:02:37.705579Z","end":"2026-02-02T18:02:38.29118Z","steps":["trace[1368668710] 'agreement among raft nodes before linearized reading' (duration: 585.573256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:38.291197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:37.705569Z","time spent":"585.623967ms","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-02T18:02:48.288494Z","caller":"traceutil/trace.go:171","msg":"trace[1568137567] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"332.717975ms","start":"2026-02-02T18:02:47.955757Z","end":"2026-02-02T18:02:48.288475Z","steps":["trace[1568137567] 'process raft request' (duration: 332.47128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:48.288606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:47.955727Z","time spent":"332.829767ms","remote":"127.0.0.1:34126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317276,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T18:02:53.59501Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132483278986373,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T18:02:54.095575Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132483278986373,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T18:02:54.530138Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.474590637s","expected-duration":"1s"} {"level":"info","ts":"2026-02-02T18:02:54.530489Z","caller":"traceutil/trace.go:171","msg":"trace[183723568] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.475007694s","start":"2026-02-02T18:02:53.055458Z","end":"2026-02-02T18:02:54.530465Z","steps":["trace[183723568] 'process raft request' (duration: 1.474879652s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:54.530608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:53.055443Z","time spent":"1.475099275s","remote":"127.0.0.1:34072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T18:02:54.634462Z","caller":"traceutil/trace.go:171","msg":"trace[64590413] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:561; }","duration":"1.540175925s","start":"2026-02-02T18:02:53.094262Z","end":"2026-02-02T18:02:54.634438Z","steps":["trace[64590413] 'read index received' (duration: 1.436611222s)","trace[64590413] 'applied index is now lower than readState.Index' (duration: 103.563293ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:02:54.635288Z","caller":"traceutil/trace.go:171","msg":"trace[1676611864] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"978.917785ms","start":"2026-02-02T18:02:53.656339Z","end":"2026-02-02T18:02:54.635257Z","steps":["trace[1676611864] 'process raft request' (duration: 977.943767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:54.635451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:53.656318Z","time spent":"979.067327ms","remote":"127.0.0.1:34072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T18:02:54.635601Z","caller":"traceutil/trace.go:171","msg":"trace[117365830] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"1.375926782s","start":"2026-02-02T18:02:53.259661Z","end":"2026-02-02T18:02:54.635588Z","steps":["trace[117365830] 'process raft request' (duration: 1.328992693s)","trace[117365830] 'compare' (duration: 44.401333ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:02:54.635652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:53.259634Z","time spent":"1.375996014s","remote":"127.0.0.1:34072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T18:02:54.635725Z","caller":"traceutil/trace.go:171","msg":"trace[527026643] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"1.145637862s","start":"2026-02-02T18:02:53.490079Z","end":"2026-02-02T18:02:54.635717Z","steps":["trace[527026643] 'process raft request' (duration: 1.144091444s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:54.635772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:53.490063Z","time spent":"1.145687783s","remote":"127.0.0.1:34072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T18:02:54.635841Z","caller":"traceutil/trace.go:171","msg":"trace[173622177] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"780.669219ms","start":"2026-02-02T18:02:53.855164Z","end":"2026-02-02T18:02:54.635833Z","steps":["trace[173622177] 'process raft request' (duration: 779.192772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:54.635885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:53.855147Z","time spent":"780.719491ms","remote":"127.0.0.1:34072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T18:02:54.636001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.541732314s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:02:54.636043Z","caller":"traceutil/trace.go:171","msg":"trace[1731408423] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:550; }","duration":"1.541799765s","start":"2026-02-02T18:02:53.09423Z","end":"2026-02-02T18:02:54.63603Z","steps":["trace[1731408423] 'agreement among raft nodes before linearized reading' (duration: 1.541709893s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:54.636073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:53.094214Z","time spent":"1.541851166s","remote":"127.0.0.1:34126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-02-02T18:02:54.644821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.541889465s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-02T18:02:54.645015Z","caller":"traceutil/trace.go:171","msg":"trace[167447593] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:551; }","duration":"1.542076028s","start":"2026-02-02T18:02:53.10288Z","end":"2026-02-02T18:02:54.644956Z","steps":["trace[167447593] 'agreement among raft nodes before linearized reading' (duration: 1.541823923s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:54.6451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:53.102863Z","time spent":"1.54222665s","remote":"127.0.0.1:34054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.70\" "} {"level":"info","ts":"2026-02-02T18:02:54.645348Z","caller":"traceutil/trace.go:171","msg":"trace[53422686] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"103.398341ms","start":"2026-02-02T18:02:54.54194Z","end":"2026-02-02T18:02:54.645339Z","steps":["trace[53422686] 'process raft request' (duration: 102.650067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:54.645451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.195368ms","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-02T18:02:54.645515Z","caller":"traceutil/trace.go:171","msg":"trace[1384676029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:551; }","duration":"191.317971ms","start":"2026-02-02T18:02:54.454189Z","end":"2026-02-02T18:02:54.645507Z","steps":["trace[1384676029] 'agreement among raft nodes before linearized reading' (duration: 191.206549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:54.645666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.508162ms","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-02T18:02:54.645823Z","caller":"traceutil/trace.go:171","msg":"trace[1026215271] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:551; }","duration":"218.714706ms","start":"2026-02-02T18:02:54.427101Z","end":"2026-02-02T18:02:54.645816Z","steps":["trace[1026215271] 'agreement among raft nodes before linearized reading' (duration: 218.464182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:54.646016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.83588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-02T18:02:54.646083Z","caller":"traceutil/trace.go:171","msg":"trace[1223966752] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:551; }","duration":"311.939353ms","start":"2026-02-02T18:02:54.334135Z","end":"2026-02-02T18:02:54.646074Z","steps":["trace[1223966752] 'agreement among raft nodes before linearized reading' (duration: 311.845261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:54.646153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:54.334115Z","time spent":"312.029914ms","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-02T18:02:54.646454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.181051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:02:54.646511Z","caller":"traceutil/trace.go:171","msg":"trace[1734497375] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:551; }","duration":"315.309784ms","start":"2026-02-02T18:02:54.33118Z","end":"2026-02-02T18:02:54.64649Z","steps":["trace[1734497375] 'agreement among raft nodes before linearized reading' (duration: 315.0892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:54.646579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:54.33115Z","time spent":"315.396395ms","remote":"127.0.0.1:34072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-02T18:02:54.646727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.624127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:02:54.646794Z","caller":"traceutil/trace.go:171","msg":"trace[1414705376] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:551; }","duration":"593.716128ms","start":"2026-02-02T18:02:54.053072Z","end":"2026-02-02T18:02:54.646788Z","steps":["trace[1414705376] 'agreement among raft nodes before linearized reading' (duration: 593.636877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:54.646902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:54.053058Z","time spent":"593.837931ms","remote":"127.0.0.1:34072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-02T18:02:54.647445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.157154ms","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-02T18:02:54.647526Z","caller":"traceutil/trace.go:171","msg":"trace[99408246] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:551; }","duration":"193.217355ms","start":"2026-02-02T18:02:54.454272Z","end":"2026-02-02T18:02:54.64749Z","steps":["trace[99408246] 'agreement among raft nodes before linearized reading' (duration: 193.136353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:54.6477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.294806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-02T18:02:54.647769Z","caller":"traceutil/trace.go:171","msg":"trace[1324689500] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:551; }","duration":"193.345627ms","start":"2026-02-02T18:02:54.454391Z","end":"2026-02-02T18:02:54.647737Z","steps":["trace[1324689500] 'agreement among raft nodes before linearized reading' (duration: 193.273266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:54.647808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.49991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-02T18:02:54.648019Z","caller":"traceutil/trace.go:171","msg":"trace[1629719239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:551; }","duration":"193.721124ms","start":"2026-02-02T18:02:54.454292Z","end":"2026-02-02T18:02:54.648013Z","steps":["trace[1629719239] 'agreement among raft nodes before linearized reading' (duration: 193.424999ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:02:54.877419Z","caller":"traceutil/trace.go:171","msg":"trace[1355646976] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"124.296218ms","start":"2026-02-02T18:02:54.753107Z","end":"2026-02-02T18:02:54.877403Z","steps":["trace[1355646976] 'process raft request' (duration: 123.864191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:56.353478Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132483278986418,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T18:02:57.357698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.675771594s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/local-path-storage/\" range_end:\"/registry/resourcequotas/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:02:57.357757Z","caller":"traceutil/trace.go:171","msg":"trace[1386589346] range","detail":"{range_begin:/registry/resourcequotas/local-path-storage/; range_end:/registry/resourcequotas/local-path-storage0; response_count:0; response_revision:569; }","duration":"1.675858566s","start":"2026-02-02T18:02:55.681886Z","end":"2026-02-02T18:02:57.357744Z","steps":["trace[1386589346] 'range keys from in-memory index tree' (duration: 1.675727644s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:57.35778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:55.681873Z","time spent":"1.675900287s","remote":"127.0.0.1:34110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/local-path-storage/\" range_end:\"/registry/resourcequotas/local-path-storage0\" "} {"level":"warn","ts":"2026-02-02T18:02:57.358155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.67608556s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-02T18:02:57.358213Z","caller":"traceutil/trace.go:171","msg":"trace[69346238] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:569; }","duration":"1.676160251s","start":"2026-02-02T18:02:55.682036Z","end":"2026-02-02T18:02:57.358196Z","steps":["trace[69346238] 'range keys from in-memory index tree' (duration: 1.675986848s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:57.358242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:55.682033Z","time spent":"1.676202582s","remote":"127.0.0.1:34072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13251,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-02T18:02:57.35876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.171287ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T18:02:57.358802Z","caller":"traceutil/trace.go:171","msg":"trace[1940976569] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"1.609748082s","start":"2026-02-02T18:02:55.749045Z","end":"2026-02-02T18:02:57.358794Z","steps":["trace[1940976569] 'process raft request' (duration: 655.502304ms)","trace[1940976569] 'compare' (duration: 953.862032ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:02:57.358834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:55.749028Z","time spent":"1.609790273s","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:02:57.373154Z","caller":"traceutil/trace.go:171","msg":"trace[1594350623] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"1.582715792s","start":"2026-02-02T18:02:55.79042Z","end":"2026-02-02T18:02:57.373136Z","steps":["trace[1594350623] 'process raft request' (duration: 1.579018148s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:57.373276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:55.790412Z","time spent":"1.582806504s","remote":"127.0.0.1:34072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:02:57.373951Z","caller":"traceutil/trace.go:171","msg":"trace[385103098] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"1.587778412s","start":"2026-02-02T18:02:55.786164Z","end":"2026-02-02T18:02:57.373943Z","steps":["trace[385103098] 'process raft request' (duration: 1.57565474s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:57.373997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:55.786145Z","time spent":"1.587826573s","remote":"127.0.0.1:34072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:02:57.374284Z","caller":"traceutil/trace.go:171","msg":"trace[636379919] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"1.236620364s","start":"2026-02-02T18:02:56.137657Z","end":"2026-02-02T18:02:57.374278Z","steps":["trace[636379919] 'process raft request' (duration: 1.232246148s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:57.37432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:56.137642Z","time spent":"1.236661855s","remote":"127.0.0.1:34204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4364,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:02:57.376585Z","caller":"traceutil/trace.go:171","msg":"trace[2014758495] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:585; }","duration":"1.523345089s","start":"2026-02-02T18:02:55.853214Z","end":"2026-02-02T18:02:57.376559Z","steps":["trace[2014758495] 'read index received' (duration: 551.34289ms)","trace[2014758495] 'applied index is now lower than readState.Index' (duration: 966.063205ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:02:57.376697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.523479081s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-02T18:02:57.376715Z","caller":"traceutil/trace.go:171","msg":"trace[1414709333] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:574; }","duration":"1.523539353s","start":"2026-02-02T18:02:55.853168Z","end":"2026-02-02T18:02:57.376708Z","steps":["trace[1414709333] 'agreement among raft nodes before linearized reading' (duration: 1.517454696s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:57.376729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:55.853156Z","time spent":"1.523569203s","remote":"127.0.0.1:34072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5199,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-02-02T18:02:57.376822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.322684658s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-02T18:02:57.376835Z","caller":"traceutil/trace.go:171","msg":"trace[2054076001] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:574; }","duration":"1.322697668s","start":"2026-02-02T18:02:56.054133Z","end":"2026-02-02T18:02:57.376831Z","steps":["trace[2054076001] 'agreement among raft nodes before linearized reading' (duration: 1.322658227s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:57.376847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:56.054082Z","time spent":"1.322762309s","remote":"127.0.0.1:34072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4355,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-02T18:02:57.376965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.704272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188243"} {"level":"info","ts":"2026-02-02T18:02:57.376978Z","caller":"traceutil/trace.go:171","msg":"trace[2139720848] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:574; }","duration":"523.761353ms","start":"2026-02-02T18:02:56.853212Z","end":"2026-02-02T18:02:57.376974Z","steps":["trace[2139720848] 'agreement among raft nodes before linearized reading' (duration: 523.695362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:57.37699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:56.853196Z","time spent":"523.791404ms","remote":"127.0.0.1:34072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188265,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-02T18:02:57.377093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.721457ms","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-02T18:02:57.377106Z","caller":"traceutil/trace.go:171","msg":"trace[1722322621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:574; }","duration":"647.743719ms","start":"2026-02-02T18:02:56.729358Z","end":"2026-02-02T18:02:57.377102Z","steps":["trace[1722322621] 'agreement among raft nodes before linearized reading' (duration: 647.705748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:57.377117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:56.729354Z","time spent":"647.761019ms","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-02T18:02:57.377192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.98437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-02T18:02:57.377203Z","caller":"traceutil/trace.go:171","msg":"trace[520483410] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:574; }","duration":"649.02004ms","start":"2026-02-02T18:02:56.72818Z","end":"2026-02-02T18:02:57.3772Z","steps":["trace[520483410] 'agreement among raft nodes before linearized reading' (duration: 648.98667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:57.377214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:56.728175Z","time spent":"649.03715ms","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-02T18:02:57.377281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.044288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-02T18:02:57.377293Z","caller":"traceutil/trace.go:171","msg":"trace[1072461583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:574; }","duration":"664.068338ms","start":"2026-02-02T18:02:56.713221Z","end":"2026-02-02T18:02:57.377289Z","steps":["trace[1072461583] 'agreement among raft nodes before linearized reading' (duration: 664.034507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:57.377305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:56.713215Z","time spent":"664.086509ms","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T18:02:57.37737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.83196ms","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-02T18:02:57.377383Z","caller":"traceutil/trace.go:171","msg":"trace[444007487] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:574; }","duration":"673.862681ms","start":"2026-02-02T18:02:56.703517Z","end":"2026-02-02T18:02:57.377379Z","steps":["trace[444007487] 'agreement among raft nodes before linearized reading' (duration: 673.829991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:57.377394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:56.703509Z","time spent":"673.881691ms","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-02T18:02:57.37746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.949504ms","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-02T18:02:57.377471Z","caller":"traceutil/trace.go:171","msg":"trace[188842021] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"694.980235ms","start":"2026-02-02T18:02:56.682487Z","end":"2026-02-02T18:02:57.377468Z","steps":["trace[188842021] 'agreement among raft nodes before linearized reading' (duration: 694.948584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:57.377482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:56.682477Z","time spent":"695.002595ms","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-02T18:02:57.377587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.348045ms","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-02T18:02:57.377601Z","caller":"traceutil/trace.go:171","msg":"trace[1185043289] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:574; }","duration":"724.393516ms","start":"2026-02-02T18:02:56.653203Z","end":"2026-02-02T18:02:57.377597Z","steps":["trace[1185043289] 'agreement among raft nodes before linearized reading' (duration: 724.347775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:57.377613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:56.653191Z","time spent":"724.419396ms","remote":"127.0.0.1:34072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188517,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-02T18:02:57.377697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.760376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:02:57.377708Z","caller":"traceutil/trace.go:171","msg":"trace[1175151836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"740.802976ms","start":"2026-02-02T18:02:56.636902Z","end":"2026-02-02T18:02:57.377705Z","steps":["trace[1175151836] 'agreement among raft nodes before linearized reading' (duration: 740.786186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:57.377721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:56.636889Z","time spent":"740.829177ms","remote":"127.0.0.1:34022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T18:02:57.377807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.311383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-02T18:02:57.377819Z","caller":"traceutil/trace.go:171","msg":"trace[879809509] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:574; }","duration":"925.323103ms","start":"2026-02-02T18:02:56.452492Z","end":"2026-02-02T18:02:57.377815Z","steps":["trace[879809509] 'agreement among raft nodes before linearized reading' (duration: 925.275282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:57.378287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:56.452463Z","time spent":"925.364814ms","remote":"127.0.0.1:34072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24266,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-02-02T18:02:57.378402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124958217s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-02T18:02:57.378416Z","caller":"traceutil/trace.go:171","msg":"trace[1731996395] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:574; }","duration":"1.125026218s","start":"2026-02-02T18:02:56.253385Z","end":"2026-02-02T18:02:57.378411Z","steps":["trace[1731996395] 'agreement among raft nodes before linearized reading' (duration: 1.124979287s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:02:57.378429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:02:56.253365Z","time spent":"1.125061819s","remote":"127.0.0.1:34072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9342,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-02T18:03:02.904774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.67795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/kube-system/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:03:02.904824Z","caller":"traceutil/trace.go:171","msg":"trace[812601185] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/kube-system/; range_end:/registry/cilium.io/ciliumnodeconfigs/kube-system0; response_count:0; response_revision:615; }","duration":"128.746651ms","start":"2026-02-02T18:03:02.776066Z","end":"2026-02-02T18:03:02.904813Z","steps":["trace[812601185] 'range keys from in-memory index tree' (duration: 128.6504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:03.603672Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132483278986524,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T18:03:04.089166Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.17938513s","expected-duration":"1s"} {"level":"info","ts":"2026-02-02T18:03:04.089407Z","caller":"traceutil/trace.go:171","msg":"trace[632506434] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"986.108224ms","start":"2026-02-02T18:03:03.103282Z","end":"2026-02-02T18:03:04.089391Z","steps":["trace[632506434] 'read index received' (duration: 985.977821ms)","trace[632506434] 'applied index is now lower than readState.Index' (duration: 129.643µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:03:04.089463Z","caller":"traceutil/trace.go:171","msg":"trace[518639246] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"1.179737047s","start":"2026-02-02T18:03:02.909683Z","end":"2026-02-02T18:03:04.08942Z","steps":["trace[518639246] 'process raft request' (duration: 1.179621416s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:04.089564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.285346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-02T18:03:04.089587Z","caller":"traceutil/trace.go:171","msg":"trace[2065722792] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:616; }","duration":"986.349118ms","start":"2026-02-02T18:03:03.103231Z","end":"2026-02-02T18:03:04.08958Z","steps":["trace[2065722792] 'agreement among raft nodes before linearized reading' (duration: 986.219866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:04.089611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:03.103216Z","time spent":"986.389858ms","remote":"127.0.0.1:34054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.70\" "} {"level":"warn","ts":"2026-02-02T18:03:04.089611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:02.909661Z","time spent":"1.179879359s","remote":"127.0.0.1:34094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T18:03:04.0898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.492942ms","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-02T18:03:04.08982Z","caller":"traceutil/trace.go:171","msg":"trace[1716797557] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"596.539953ms","start":"2026-02-02T18:03:03.493274Z","end":"2026-02-02T18:03:04.089814Z","steps":["trace[1716797557] 'agreement among raft nodes before linearized reading' (duration: 596.484892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:04.089837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:03.493259Z","time spent":"596.574463ms","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-02T18:03:04.089991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.252274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6slsd\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-02T18:03:04.090018Z","caller":"traceutil/trace.go:171","msg":"trace[1468133484] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6slsd; range_end:; response_count:1; response_revision:616; }","duration":"439.276165ms","start":"2026-02-02T18:03:03.650733Z","end":"2026-02-02T18:03:04.090009Z","steps":["trace[1468133484] 'agreement among raft nodes before linearized reading' (duration: 439.206694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:04.090039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:03.650702Z","time spent":"439.331175ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16674,"request content":"key:\"/registry/pods/kube-system/cilium-6slsd\" "} {"level":"warn","ts":"2026-02-02T18:03:04.090119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.276358ms","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-02T18:03:04.09015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.675391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:03:04.090169Z","caller":"traceutil/trace.go:171","msg":"trace[1069892380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"532.713742ms","start":"2026-02-02T18:03:03.55745Z","end":"2026-02-02T18:03:04.090163Z","steps":["trace[1069892380] 'agreement among raft nodes before linearized reading' (duration: 532.683952ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:03:04.090163Z","caller":"traceutil/trace.go:171","msg":"trace[608702653] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"517.34985ms","start":"2026-02-02T18:03:03.572801Z","end":"2026-02-02T18:03:04.090151Z","steps":["trace[608702653] 'agreement among raft nodes before linearized reading' (duration: 517.180257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:04.090186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:03.557436Z","time spent":"532.746003ms","remote":"127.0.0.1:34030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T18:03:04.090201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:03.572788Z","time spent":"517.404151ms","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-02T18:03:04.090465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.371011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-02T18:03:04.090512Z","caller":"traceutil/trace.go:171","msg":"trace[979602315] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:616; }","duration":"477.425052ms","start":"2026-02-02T18:03:03.613072Z","end":"2026-02-02T18:03:04.090497Z","steps":["trace[979602315] 'agreement among raft nodes before linearized reading' (duration: 477.30073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:04.090534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.632014ms","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-02T18:03:04.090555Z","caller":"traceutil/trace.go:171","msg":"trace[524414908] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"486.652194ms","start":"2026-02-02T18:03:03.603896Z","end":"2026-02-02T18:03:04.090548Z","steps":["trace[524414908] 'agreement among raft nodes before linearized reading' (duration: 486.595614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:04.090554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:03.613067Z","time spent":"477.472642ms","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-02T18:03:04.090572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:03.603865Z","time spent":"486.703735ms","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-02T18:03:04.090762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.437106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-02T18:03:04.090793Z","caller":"traceutil/trace.go:171","msg":"trace[1595343048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"479.497966ms","start":"2026-02-02T18:03:03.611286Z","end":"2026-02-02T18:03:04.090784Z","steps":["trace[1595343048] 'agreement among raft nodes before linearized reading' (duration: 479.344554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:04.090821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:03.611275Z","time spent":"479.539197ms","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T18:03:05.060299Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132483278986527,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T18:03:05.560982Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132483278986527,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T18:03:06.061762Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132483278986527,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T18:03:06.275249Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.16602191s","expected-duration":"1s"} {"level":"info","ts":"2026-02-02T18:03:06.275586Z","caller":"traceutil/trace.go:171","msg":"trace[1600656065] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"2.166473567s","start":"2026-02-02T18:03:04.109098Z","end":"2026-02-02T18:03:06.275571Z","steps":["trace[1600656065] 'process raft request' (duration: 2.166368335s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:06.275667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:04.109081Z","time spent":"2.166533307s","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T18:03:06.560204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000088863s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-02T18:03:06.560263Z","caller":"traceutil/trace.go:171","msg":"trace[1517381797] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000189425s","start":"2026-02-02T18:03:04.560063Z","end":"2026-02-02T18:03:06.560252Z","steps":["trace[1517381797] 'agreement among raft nodes before linearized reading' (duration: 2.000113924s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:06.560294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:04.560035Z","time spent":"2.000252296s","remote":"127.0.0.1:34022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/02 18:03:06 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T18:03:07.450601Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.175213888s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-02T18:03:07.450867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:04.122375Z","time spent":"3.328489531s","remote":"127.0.0.1:34054","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-02T18:03:07.450991Z","caller":"traceutil/trace.go:171","msg":"trace[345996065] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"3.339584913s","start":"2026-02-02T18:03:04.111391Z","end":"2026-02-02T18:03:07.450976Z","steps":["trace[345996065] 'process raft request' (duration: 3.339314278s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:07.451052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:04.111375Z","time spent":"3.339633484s","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:03:07.451058Z","caller":"traceutil/trace.go:171","msg":"trace[1360935110] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"3.328570023s","start":"2026-02-02T18:03:04.122472Z","end":"2026-02-02T18:03:07.451042Z","steps":["trace[1360935110] 'process raft request' (duration: 3.328360559s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:07.451167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:04.122464Z","time spent":"3.328634494s","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:03:07.451316Z","caller":"traceutil/trace.go:171","msg":"trace[828226440] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"3.328796486s","start":"2026-02-02T18:03:04.122514Z","end":"2026-02-02T18:03:07.45131Z","steps":["trace[828226440] 'process raft request' (duration: 3.328475581s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:07.451348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:04.122511Z","time spent":"3.328819727s","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:03:07.451527Z","caller":"traceutil/trace.go:171","msg":"trace[1271682330] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"3.328976759s","start":"2026-02-02T18:03:04.122542Z","end":"2026-02-02T18:03:07.451519Z","steps":["trace[1271682330] 'process raft request' (duration: 3.328745096s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:07.451577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:04.122538Z","time spent":"3.32901092s","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:03:07.451592Z","caller":"traceutil/trace.go:171","msg":"trace[1996637814] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:634; }","duration":"2.891484201s","start":"2026-02-02T18:03:04.560102Z","end":"2026-02-02T18:03:07.451586Z","steps":["trace[1996637814] 'read index received' (duration: 1.715932557s)","trace[1996637814] 'applied index is now lower than readState.Index' (duration: 1.175551034s)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:03:07.451746Z","caller":"traceutil/trace.go:171","msg":"trace[2037567159] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"3.329149932s","start":"2026-02-02T18:03:04.12259Z","end":"2026-02-02T18:03:07.45174Z","steps":["trace[2037567159] 'process raft request' (duration: 3.328869638s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:07.451756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171353647s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6slsd\" ","response":"range_response_count:1 size:16814"} {"level":"warn","ts":"2026-02-02T18:03:07.451775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:04.122587Z","time spent":"3.329171542s","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:03:07.451779Z","caller":"traceutil/trace.go:171","msg":"trace[1062275884] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6slsd; range_end:; response_count:1; response_revision:622; }","duration":"1.171405137s","start":"2026-02-02T18:03:06.280367Z","end":"2026-02-02T18:03:07.451772Z","steps":["trace[1062275884] 'agreement among raft nodes before linearized reading' (duration: 1.171318206s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:07.451799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:06.280351Z","time spent":"1.171443209s","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16836,"request content":"key:\"/registry/pods/kube-system/cilium-6slsd\" "} {"level":"warn","ts":"2026-02-02T18:03:07.451877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.55411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:03:07.451895Z","caller":"traceutil/trace.go:171","msg":"trace[1467730950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"891.596341ms","start":"2026-02-02T18:03:06.560293Z","end":"2026-02-02T18:03:07.451889Z","steps":["trace[1467730950] 'agreement among raft nodes before linearized reading' (duration: 891.567951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:07.451908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:06.560279Z","time spent":"891.626411ms","remote":"127.0.0.1:34030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T18:03:08.290599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.907007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-02T18:03:08.290901Z","caller":"traceutil/trace.go:171","msg":"trace[1356076836] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:641; }","duration":"826.05846ms","start":"2026-02-02T18:03:07.464823Z","end":"2026-02-02T18:03:08.290881Z","steps":["trace[1356076836] 'read index received' (duration: 15.472735ms)","trace[1356076836] 'applied index is now lower than readState.Index' (duration: 810.584415ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:03:08.290984Z","caller":"traceutil/trace.go:171","msg":"trace[1545808603] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"827.857878ms","start":"2026-02-02T18:03:07.463116Z","end":"2026-02-02T18:03:08.290974Z","steps":["trace[1545808603] 'process raft request' (duration: 120.466573ms)","trace[1545808603] 'compare' (duration: 706.714274ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:03:08.291032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:07.463106Z","time spent":"827.900068ms","remote":"127.0.0.1:34094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T18:03:08.291369Z","caller":"traceutil/trace.go:171","msg":"trace[1977916715] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"826.57482ms","start":"2026-02-02T18:03:07.464785Z","end":"2026-02-02T18:03:08.29136Z","steps":["trace[1977916715] 'process raft request' (duration: 825.971519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:08.291436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:07.464779Z","time spent":"826.60804ms","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T18:03:08.291538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.711821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:03:08.291559Z","caller":"traceutil/trace.go:171","msg":"trace[2053535212] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"826.744052ms","start":"2026-02-02T18:03:07.464806Z","end":"2026-02-02T18:03:08.291551Z","steps":["trace[2053535212] 'agreement among raft nodes before linearized reading' (duration: 826.705891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:08.291578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:07.464803Z","time spent":"826.771242ms","remote":"127.0.0.1:34022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T18:03:08.291732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.693951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-02T18:03:08.29175Z","caller":"traceutil/trace.go:171","msg":"trace[1788237926] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:625; }","duration":"809.735543ms","start":"2026-02-02T18:03:07.482008Z","end":"2026-02-02T18:03:08.291744Z","steps":["trace[1788237926] 'agreement among raft nodes before linearized reading' (duration: 809.692442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:08.298793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:07.481986Z","time spent":"816.778302ms","remote":"127.0.0.1:34202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":443,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-02T18:03:08.960406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.555577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:03:08.960464Z","caller":"traceutil/trace.go:171","msg":"trace[2134447122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"400.664478ms","start":"2026-02-02T18:03:08.559788Z","end":"2026-02-02T18:03:08.960453Z","steps":["trace[2134447122] 'range keys from in-memory index tree' (duration: 400.481436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:08.960485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:08.559763Z","time spent":"400.71727ms","remote":"127.0.0.1:34022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T18:03:09.969008Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132483278986551,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T18:03:10.469919Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132483278986551,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T18:03:10.970035Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132483278986551,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T18:03:11.470248Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132483278986551,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T18:03:11.63781Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.173033131s","expected-duration":"1s"} {"level":"info","ts":"2026-02-02T18:03:11.638199Z","caller":"traceutil/trace.go:171","msg":"trace[583563725] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"2.173463528s","start":"2026-02-02T18:03:09.464714Z","end":"2026-02-02T18:03:11.638177Z","steps":["trace[583563725] 'process raft request' (duration: 2.173324026s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:11.638321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:09.464703Z","time spent":"2.173545009s","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T18:03:11.68411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000638748s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T18:03:11.684185Z","caller":"traceutil/trace.go:171","msg":"trace[1127914477] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00076609s","start":"2026-02-02T18:03:09.683404Z","end":"2026-02-02T18:03:11.68417Z","steps":["trace[1127914477] 'agreement among raft nodes before linearized reading' (duration: 2.000667679s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:11.684237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:09.683391Z","time spent":"2.000826141s","remote":"127.0.0.1:34022","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-02T18:03:13.038474Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.365155148s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-02T18:03:13.13229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246700782s","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-02T18:03:13.132379Z","caller":"traceutil/trace.go:171","msg":"trace[1423221338] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:626; }","duration":"1.246798373s","start":"2026-02-02T18:03:11.885568Z","end":"2026-02-02T18:03:13.132367Z","steps":["trace[1423221338] 'range keys from in-memory index tree' (duration: 1.246687232s)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:03:13.132986Z","caller":"traceutil/trace.go:171","msg":"trace[265275836] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"3.664161273s","start":"2026-02-02T18:03:09.468816Z","end":"2026-02-02T18:03:13.132977Z","steps":["trace[265275836] 'process raft request' (duration: 3.664091932s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:13.133043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:09.468806Z","time spent":"3.664197955s","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:03:13.133102Z","caller":"traceutil/trace.go:171","msg":"trace[1766250190] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"3.666020463s","start":"2026-02-02T18:03:09.467075Z","end":"2026-02-02T18:03:13.133096Z","steps":["trace[1766250190] 'process raft request' (duration: 3.571577376s)","trace[1766250190] 'compare' (duration: 94.004021ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:03:13.133128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:09.467066Z","time spent":"3.666044433s","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:03:13.133315Z","caller":"traceutil/trace.go:171","msg":"trace[1893710410] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"3.664354826s","start":"2026-02-02T18:03:09.468953Z","end":"2026-02-02T18:03:13.133308Z","steps":["trace[1893710410] 'process raft request' (duration: 3.663984461s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:13.133346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:09.468939Z","time spent":"3.664389057s","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:03:13.192691Z","caller":"traceutil/trace.go:171","msg":"trace[1944201243] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:644; }","duration":"3.723815512s","start":"2026-02-02T18:03:09.468859Z","end":"2026-02-02T18:03:13.192674Z","steps":["trace[1944201243] 'read index received' (duration: 2.170088515s)","trace[1944201243] 'applied index is now lower than readState.Index' (duration: 1.553726297s)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:03:13.193054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.724180628s","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-02T18:03:13.193085Z","caller":"traceutil/trace.go:171","msg":"trace[1132313668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:629; }","duration":"3.724232018s","start":"2026-02-02T18:03:09.468846Z","end":"2026-02-02T18:03:13.193078Z","steps":["trace[1132313668] 'agreement among raft nodes before linearized reading' (duration: 3.724132857s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:13.19311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:09.468842Z","time spent":"3.724263299s","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-02T18:03:13.193298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.508142435s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:03:13.193339Z","caller":"traceutil/trace.go:171","msg":"trace[322711505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"1.508196556s","start":"2026-02-02T18:03:11.685124Z","end":"2026-02-02T18:03:13.193321Z","steps":["trace[322711505] 'agreement among raft nodes before linearized reading' (duration: 1.508147225s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:13.193356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:11.685107Z","time spent":"1.508244736s","remote":"127.0.0.1:34030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-02T18:03:13.193533Z","caller":"traceutil/trace.go:171","msg":"trace[158496156] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"713.607666ms","start":"2026-02-02T18:03:12.479918Z","end":"2026-02-02T18:03:13.193526Z","steps":["trace[158496156] 'process raft request' (duration: 712.674562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:13.193602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:03:12.479904Z","time spent":"713.653236ms","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:03:27.060089Z","caller":"traceutil/trace.go:171","msg":"trace[180865168] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"124.630848ms","start":"2026-02-02T18:03:26.935445Z","end":"2026-02-02T18:03:27.060075Z","steps":["trace[180865168] 'process raft request' (duration: 124.600398ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:03:27.060314Z","caller":"traceutil/trace.go:171","msg":"trace[654718842] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"135.599553ms","start":"2026-02-02T18:03:26.924708Z","end":"2026-02-02T18:03:27.060307Z","steps":["trace[654718842] 'process raft request' (duration: 135.30927ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:03:27.060534Z","caller":"traceutil/trace.go:171","msg":"trace[290402057] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"136.198032ms","start":"2026-02-02T18:03:26.924328Z","end":"2026-02-02T18:03:27.060526Z","steps":["trace[290402057] 'process raft request' (duration: 64.771655ms)","trace[290402057] 'compare' (duration: 70.829628ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:03:29.367729Z","caller":"traceutil/trace.go:171","msg":"trace[670817933] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"100.631714ms","start":"2026-02-02T18:03:29.267006Z","end":"2026-02-02T18:03:29.367637Z","steps":["trace[670817933] 'process raft request' (duration: 96.509971ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:03:57.714919Z","caller":"traceutil/trace.go:171","msg":"trace[1143472851] linearizableReadLoop","detail":"{readStateIndex:892; appliedIndex:891; }","duration":"154.692017ms","start":"2026-02-02T18:03:57.560202Z","end":"2026-02-02T18:03:57.714894Z","steps":["trace[1143472851] 'read index received' (duration: 150.285543ms)","trace[1143472851] 'applied index is now lower than readState.Index' (duration: 4.405264ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:03:57.715152Z","caller":"traceutil/trace.go:171","msg":"trace[1024401861] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"191.061683ms","start":"2026-02-02T18:03:57.524079Z","end":"2026-02-02T18:03:57.715141Z","steps":["trace[1024401861] 'process raft request' (duration: 186.328195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:03:57.715354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.136582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:03:57.715389Z","caller":"traceutil/trace.go:171","msg":"trace[1552990740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:862; }","duration":"155.204362ms","start":"2026-02-02T18:03:57.560174Z","end":"2026-02-02T18:03:57.715378Z","steps":["trace[1552990740] 'agreement among raft nodes before linearized reading' (duration: 155.139892ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:04:29.143209Z","caller":"traceutil/trace.go:171","msg":"trace[1629361347] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"135.950708ms","start":"2026-02-02T18:04:29.007242Z","end":"2026-02-02T18:04:29.143192Z","steps":["trace[1629361347] 'process raft request' (duration: 135.830697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:04:36.278118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.374628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:04:36.278169Z","caller":"traceutil/trace.go:171","msg":"trace[1804316321] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1007; }","duration":"257.464228ms","start":"2026-02-02T18:04:36.020693Z","end":"2026-02-02T18:04:36.278157Z","steps":["trace[1804316321] 'range keys from in-memory index tree' (duration: 257.316227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:04:36.278214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.118652ms","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-02T18:04:36.278269Z","caller":"traceutil/trace.go:171","msg":"trace[1979826258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1007; }","duration":"316.216534ms","start":"2026-02-02T18:04:35.962036Z","end":"2026-02-02T18:04:36.278253Z","steps":["trace[1979826258] 'range keys from in-memory index tree' (duration: 315.89308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:04:36.278306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:04:35.962009Z","time spent":"316.288134ms","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-02T18:04:36.278448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.317167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-02T18:04:36.278469Z","caller":"traceutil/trace.go:171","msg":"trace[1030955347] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1007; }","duration":"303.375337ms","start":"2026-02-02T18:04:35.975086Z","end":"2026-02-02T18:04:36.278462Z","steps":["trace[1030955347] 'range keys from in-memory index tree' (duration: 302.613131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:04:36.278487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:04:35.975068Z","time spent":"303.414268ms","remote":"127.0.0.1:34298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}