{"level":"warn","ts":"2026-02-07T04:12:06.074239Z","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-07T04:12:06.074333Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.217: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.217:2380","--initial-cluster=instance=https://162.253.55.217:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.217:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.217: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-07T04:12:06.074462Z","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-07T04:12:06.074476Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-02-07T04:12:06.074492Z","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-07T04:12:06.075377Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"]} {"level":"info","ts":"2026-02-07T04:12:06.077028Z","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.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.217: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-07T04:12:06.084813Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.108804ms"} {"level":"info","ts":"2026-02-07T04:12:06.095105Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e368e6818bd6f7","cluster-id":"289e52865b7eaeb1"} {"level":"info","ts":"2026-02-07T04:12:06.09518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=()"} {"level":"info","ts":"2026-02-07T04:12:06.095199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 0"} {"level":"info","ts":"2026-02-07T04:12:06.095239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e368e6818bd6f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-07T04:12:06.095257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 1"} {"level":"info","ts":"2026-02-07T04:12:06.095307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"warn","ts":"2026-02-07T04:12:06.106296Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-07T04:12:06.114271Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-07T04:12:06.118143Z","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-07T04:12:06.120656Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e368e6818bd6f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-07T04:12:06.121711Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e368e6818bd6f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-07T04:12:06.12258Z","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-07T04:12:06.122652Z","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-07T04:12:06.122659Z","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-07T04:12:06.124317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"info","ts":"2026-02-07T04:12:06.124562Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","added-peer-id":"f1e368e6818bd6f7","added-peer-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-02-07T04:12:06.12875Z","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-07T04:12:06.129311Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e368e6818bd6f7","initial-advertise-peer-urls":["https://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-07T04:12:06.129334Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-07T04:12:06.130254Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-02-07T04:12:06.130325Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-02-07T04:12:06.696287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 is starting a new election at term 1"} {"level":"info","ts":"2026-02-07T04:12:06.696357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-07T04:12:06.696402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgPreVoteResp from f1e368e6818bd6f7 at term 1"} {"level":"info","ts":"2026-02-07T04:12:06.696419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became candidate at term 2"} {"level":"info","ts":"2026-02-07T04:12:06.696425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgVoteResp from f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-02-07T04:12:06.696432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became leader at term 2"} {"level":"info","ts":"2026-02-07T04:12:06.696439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e368e6818bd6f7 elected leader f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-02-07T04:12:06.697682Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e368e6818bd6f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.217:2379]}","request-path":"/0/members/f1e368e6818bd6f7/attributes","cluster-id":"289e52865b7eaeb1","publish-timeout":"7s"} {"level":"info","ts":"2026-02-07T04:12:06.697728Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T04:12:06.697823Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T04:12:06.698004Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T04:12:06.698099Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-07T04:12:06.698109Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-07T04:12:06.702512Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.217:2379"} {"level":"info","ts":"2026-02-07T04:12:06.702578Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-07T04:12:06.702807Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T04:12:06.702864Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T04:12:06.702881Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-07T04:12:20.351456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.554492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-07T04:12:20.351554Z","caller":"traceutil/trace.go:171","msg":"trace[1750710497] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:405; }","duration":"237.711566ms","start":"2026-02-07T04:12:20.113823Z","end":"2026-02-07T04:12:20.351534Z","steps":["trace[1750710497] 'range keys from in-memory index tree' (duration: 237.46324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:20.351878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.21592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.217\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-07T04:12:20.351926Z","caller":"traceutil/trace.go:171","msg":"trace[1610989161] range","detail":"{range_begin:/registry/masterleases/162.253.55.217; range_end:; response_count:1; response_revision:405; }","duration":"330.302742ms","start":"2026-02-07T04:12:20.021614Z","end":"2026-02-07T04:12:20.351916Z","steps":["trace[1610989161] 'range keys from in-memory index tree' (duration: 330.136999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:20.351954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:12:20.021591Z","time spent":"330.355333ms","remote":"127.0.0.1:56662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.217\" "} {"level":"info","ts":"2026-02-07T04:12:26.494798Z","caller":"traceutil/trace.go:171","msg":"trace[165492729] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"114.704825ms","start":"2026-02-07T04:12:26.380076Z","end":"2026-02-07T04:12:26.494781Z","steps":["trace[165492729] 'process raft request' (duration: 114.600553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:36.928337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.362968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:12:36.9284Z","caller":"traceutil/trace.go:171","msg":"trace[1812671802] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"151.502641ms","start":"2026-02-07T04:12:36.776886Z","end":"2026-02-07T04:12:36.928389Z","steps":["trace[1812671802] 'range keys from in-memory index tree' (duration: 151.306187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:36.928627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.623933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-07T04:12:36.92864Z","caller":"traceutil/trace.go:171","msg":"trace[1364441187] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:509; }","duration":"395.659374ms","start":"2026-02-07T04:12:36.532977Z","end":"2026-02-07T04:12:36.928636Z","steps":["trace[1364441187] 'range keys from in-memory index tree' (duration: 395.534461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:36.928657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:12:36.532962Z","time spent":"395.687014ms","remote":"127.0.0.1:56874","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-07T04:12:36.928752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.909332ms","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-07T04:12:36.928764Z","caller":"traceutil/trace.go:171","msg":"trace[394972803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:509; }","duration":"307.943494ms","start":"2026-02-07T04:12:36.620817Z","end":"2026-02-07T04:12:36.92876Z","steps":["trace[394972803] 'range keys from in-memory index tree' (duration: 307.833081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:36.928775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:12:36.620802Z","time spent":"307.970284ms","remote":"127.0.0.1:56874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T04:12:36.928833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.717957ms","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-07T04:12:36.928844Z","caller":"traceutil/trace.go:171","msg":"trace[2105278158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:509; }","duration":"282.768168ms","start":"2026-02-07T04:12:36.646072Z","end":"2026-02-07T04:12:36.92884Z","steps":["trace[2105278158] 'range keys from in-memory index tree' (duration: 282.652556ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:12:37.05075Z","caller":"traceutil/trace.go:171","msg":"trace[1601924537] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"110.90022ms","start":"2026-02-07T04:12:36.939833Z","end":"2026-02-07T04:12:37.050733Z","steps":["trace[1601924537] 'process raft request' (duration: 110.782437ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:12:37.056844Z","caller":"traceutil/trace.go:171","msg":"trace[1982122318] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"116.590017ms","start":"2026-02-07T04:12:36.940237Z","end":"2026-02-07T04:12:37.056827Z","steps":["trace[1982122318] 'process raft request' (duration: 116.558667ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:12:37.05704Z","caller":"traceutil/trace.go:171","msg":"trace[1040225338] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"116.838933ms","start":"2026-02-07T04:12:36.940196Z","end":"2026-02-07T04:12:37.057035Z","steps":["trace[1040225338] 'process raft request' (duration: 116.535216ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:12:45.270511Z","caller":"traceutil/trace.go:171","msg":"trace[1581857622] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"118.495521ms","start":"2026-02-07T04:12:45.151987Z","end":"2026-02-07T04:12:45.270483Z","steps":["trace[1581857622] 'process raft request' (duration: 114.849509ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:12:45.271175Z","caller":"traceutil/trace.go:171","msg":"trace[717718272] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:557; }","duration":"112.270822ms","start":"2026-02-07T04:12:45.158891Z","end":"2026-02-07T04:12:45.271162Z","steps":["trace[717718272] 'read index received' (duration: 108.024936ms)","trace[717718272] 'applied index is now lower than readState.Index' (duration: 4.243846ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:12:45.272178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.274194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-07T04:12:45.272228Z","caller":"traceutil/trace.go:171","msg":"trace[2050924097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:538; }","duration":"113.349516ms","start":"2026-02-07T04:12:45.158868Z","end":"2026-02-07T04:12:45.272217Z","steps":["trace[2050924097] 'agreement among raft nodes before linearized reading' (duration: 112.337564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:46.268846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.240988ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:12:46.268979Z","caller":"traceutil/trace.go:171","msg":"trace[680247369] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"298.8321ms","start":"2026-02-07T04:12:45.97013Z","end":"2026-02-07T04:12:46.268962Z","steps":["trace[680247369] 'process raft request' (duration: 104.965678ms)","trace[680247369] 'compare' (duration: 193.153515ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:12:46.269829Z","caller":"traceutil/trace.go:171","msg":"trace[986833918] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:566; }","duration":"299.364111ms","start":"2026-02-07T04:12:45.970442Z","end":"2026-02-07T04:12:46.269806Z","steps":["trace[986833918] 'read index received' (duration: 104.665611ms)","trace[986833918] 'applied index is now lower than readState.Index' (duration: 194.69748ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:12:46.270078Z","caller":"traceutil/trace.go:171","msg":"trace[45361341] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"299.698158ms","start":"2026-02-07T04:12:45.970367Z","end":"2026-02-07T04:12:46.270065Z","steps":["trace[45361341] 'process raft request' (duration: 299.280509ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:12:46.270281Z","caller":"traceutil/trace.go:171","msg":"trace[2074845694] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"283.199188ms","start":"2026-02-07T04:12:45.987071Z","end":"2026-02-07T04:12:46.270271Z","steps":["trace[2074845694] 'process raft request' (duration: 282.694077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:46.270467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.002765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:12:46.270504Z","caller":"traceutil/trace.go:171","msg":"trace[462384520] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:548; }","duration":"300.069456ms","start":"2026-02-07T04:12:45.970422Z","end":"2026-02-07T04:12:46.270491Z","steps":["trace[462384520] 'agreement among raft nodes before linearized reading' (duration: 299.974754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:46.270535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:12:45.970415Z","time spent":"300.111417ms","remote":"127.0.0.1:56680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-07T04:12:46.273471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.865062ms","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-07T04:12:46.273553Z","caller":"traceutil/trace.go:171","msg":"trace[2026198436] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:545; }","duration":"109.216392ms","start":"2026-02-07T04:12:46.164324Z","end":"2026-02-07T04:12:46.273541Z","steps":["trace[2026198436] 'range keys from in-memory index tree' (duration: 103.850551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:46.278201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.192933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:12:46.27823Z","caller":"traceutil/trace.go:171","msg":"trace[1877991830] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:549; }","duration":"306.231335ms","start":"2026-02-07T04:12:45.97199Z","end":"2026-02-07T04:12:46.278221Z","steps":["trace[1877991830] 'agreement among raft nodes before linearized reading' (duration: 306.177634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:46.278901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:12:45.971985Z","time spent":"306.9081ms","remote":"127.0.0.1:56680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"info","ts":"2026-02-07T04:12:46.279043Z","caller":"traceutil/trace.go:171","msg":"trace[956383227] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"133.626459ms","start":"2026-02-07T04:12:46.14541Z","end":"2026-02-07T04:12:46.279037Z","steps":["trace[956383227] 'process raft request' (duration: 132.717629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:46.279173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.432936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-07T04:12:46.279187Z","caller":"traceutil/trace.go:171","msg":"trace[721056221] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:549; }","duration":"117.475727ms","start":"2026-02-07T04:12:46.161707Z","end":"2026-02-07T04:12:46.279183Z","steps":["trace[721056221] 'agreement among raft nodes before linearized reading' (duration: 117.404165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:46.279259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.967067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:12:46.279294Z","caller":"traceutil/trace.go:171","msg":"trace[1729500294] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:549; }","duration":"285.006678ms","start":"2026-02-07T04:12:45.99426Z","end":"2026-02-07T04:12:46.279267Z","steps":["trace[1729500294] 'agreement among raft nodes before linearized reading' (duration: 284.987867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:46.279351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.289024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:12:46.279364Z","caller":"traceutil/trace.go:171","msg":"trace[607622271] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:549; }","duration":"285.306614ms","start":"2026-02-07T04:12:45.994053Z","end":"2026-02-07T04:12:46.27936Z","steps":["trace[607622271] 'agreement among raft nodes before linearized reading' (duration: 285.286043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:46.279419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.368375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:12:46.27943Z","caller":"traceutil/trace.go:171","msg":"trace[921830943] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:549; }","duration":"285.392435ms","start":"2026-02-07T04:12:45.994035Z","end":"2026-02-07T04:12:46.279427Z","steps":["trace[921830943] 'agreement among raft nodes before linearized reading' (duration: 285.373065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:46.279479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.455687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:12:46.279493Z","caller":"traceutil/trace.go:171","msg":"trace[726262092] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:549; }","duration":"285.482969ms","start":"2026-02-07T04:12:45.994005Z","end":"2026-02-07T04:12:46.279488Z","steps":["trace[726262092] 'agreement among raft nodes before linearized reading' (duration: 285.465077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:46.279566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.806743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2lnl5\" ","response":"range_response_count:1 size:16916"} {"level":"info","ts":"2026-02-07T04:12:46.279579Z","caller":"traceutil/trace.go:171","msg":"trace[215608450] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2lnl5; range_end:; response_count:1; response_revision:549; }","duration":"292.847035ms","start":"2026-02-07T04:12:45.986729Z","end":"2026-02-07T04:12:46.279576Z","steps":["trace[215608450] 'agreement among raft nodes before linearized reading' (duration: 292.806474ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:12:49.267232Z","caller":"traceutil/trace.go:171","msg":"trace[300273047] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"111.874663ms","start":"2026-02-07T04:12:49.155336Z","end":"2026-02-07T04:12:49.26721Z","steps":["trace[300273047] 'process raft request' (duration: 111.75725ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:12:58.598889Z","caller":"traceutil/trace.go:171","msg":"trace[694204714] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:717; }","duration":"208.894469ms","start":"2026-02-07T04:12:58.389944Z","end":"2026-02-07T04:12:58.598838Z","steps":["trace[694204714] 'read index received' (duration: 152.379661ms)","trace[694204714] 'applied index is now lower than readState.Index' (duration: 56.514208ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:12:58.599011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.056583ms","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-07T04:12:58.599027Z","caller":"traceutil/trace.go:171","msg":"trace[1309227225] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:694; }","duration":"209.118014ms","start":"2026-02-07T04:12:58.389904Z","end":"2026-02-07T04:12:58.599022Z","steps":["trace[1309227225] 'agreement among raft nodes before linearized reading' (duration: 209.025892ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:12:58.599188Z","caller":"traceutil/trace.go:171","msg":"trace[437282835] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"382.299895ms","start":"2026-02-07T04:12:58.216882Z","end":"2026-02-07T04:12:58.599182Z","steps":["trace[437282835] 'process raft request' (duration: 325.476319ms)","trace[437282835] 'compare' (duration: 56.251873ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:12:58.599234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:12:58.216864Z","time spent":"382.337285ms","remote":"127.0.0.1:56798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4830,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:12:59.619964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.830227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:12:59.620057Z","caller":"traceutil/trace.go:171","msg":"trace[410155596] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"1.015958499s","start":"2026-02-07T04:12:58.604081Z","end":"2026-02-07T04:12:59.620039Z","steps":["trace[410155596] 'process raft request' (duration: 480.983758ms)","trace[410155596] 'compare' (duration: 534.575932ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:12:59.620112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:12:58.604066Z","time spent":"1.0160195s","remote":"127.0.0.1:56874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:12:59.626995Z","caller":"traceutil/trace.go:171","msg":"trace[1743690668] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"1.02270557s","start":"2026-02-07T04:12:58.604268Z","end":"2026-02-07T04:12:59.626973Z","steps":["trace[1743690668] 'process raft request' (duration: 1.019683492s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:59.627044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:12:58.604264Z","time spent":"1.022756471s","remote":"127.0.0.1:56774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":900,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:12:59.627178Z","caller":"traceutil/trace.go:171","msg":"trace[2136224359] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"1.02097755s","start":"2026-02-07T04:12:58.606194Z","end":"2026-02-07T04:12:59.627172Z","steps":["trace[2136224359] 'process raft request' (duration: 1.02049801s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:59.627209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:12:58.606188Z","time spent":"1.021003771s","remote":"127.0.0.1:56886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1613,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:12:59.627361Z","caller":"traceutil/trace.go:171","msg":"trace[1160836281] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"1.020126991s","start":"2026-02-07T04:12:58.607227Z","end":"2026-02-07T04:12:59.627354Z","steps":["trace[1160836281] 'process raft request' (duration: 1.019497047s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:59.627394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:12:58.607221Z","time spent":"1.020154813s","remote":"127.0.0.1:57112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3866,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:12:59.627467Z","caller":"traceutil/trace.go:171","msg":"trace[1890146217] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"548.599007ms","start":"2026-02-07T04:12:59.078862Z","end":"2026-02-07T04:12:59.627461Z","steps":["trace[1890146217] 'process raft request' (duration: 547.886531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:59.627494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:12:59.078846Z","time spent":"548.634328ms","remote":"127.0.0.1:56708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T04:12:59.627572Z","caller":"traceutil/trace.go:171","msg":"trace[1492371171] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:718; }","duration":"849.995302ms","start":"2026-02-07T04:12:58.777571Z","end":"2026-02-07T04:12:59.627566Z","steps":["trace[1492371171] 'read index received' (duration: 307.504003ms)","trace[1492371171] 'applied index is now lower than readState.Index' (duration: 542.490609ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:12:59.627866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.293968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:12:59.627888Z","caller":"traceutil/trace.go:171","msg":"trace[1384469521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:700; }","duration":"850.377521ms","start":"2026-02-07T04:12:58.777505Z","end":"2026-02-07T04:12:59.627882Z","steps":["trace[1384469521] 'agreement among raft nodes before linearized reading' (duration: 850.32771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:59.627906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:12:58.777487Z","time spent":"850.415061ms","remote":"127.0.0.1:56626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T04:12:59.628143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.313603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-7dzvk\" ","response":"range_response_count:1 size:4824"} {"level":"info","ts":"2026-02-07T04:12:59.628177Z","caller":"traceutil/trace.go:171","msg":"trace[357837647] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-7dzvk; range_end:; response_count:1; response_revision:700; }","duration":"553.396364ms","start":"2026-02-07T04:12:59.074774Z","end":"2026-02-07T04:12:59.62817Z","steps":["trace[357837647] 'agreement among raft nodes before linearized reading' (duration: 553.253791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:59.628245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:12:59.074754Z","time spent":"553.484867ms","remote":"127.0.0.1:56798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4847,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-7dzvk\" "} {"level":"warn","ts":"2026-02-07T04:12:59.628493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.781719ms","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-07T04:12:59.628513Z","caller":"traceutil/trace.go:171","msg":"trace[732671383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:700; }","duration":"282.82302ms","start":"2026-02-07T04:12:59.345684Z","end":"2026-02-07T04:12:59.628508Z","steps":["trace[732671383] 'agreement among raft nodes before linearized reading' (duration: 282.763609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:59.62862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.234549ms","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-07T04:12:59.628636Z","caller":"traceutil/trace.go:171","msg":"trace[1306087591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:700; }","duration":"291.27393ms","start":"2026-02-07T04:12:59.337358Z","end":"2026-02-07T04:12:59.628632Z","steps":["trace[1306087591] 'agreement among raft nodes before linearized reading' (duration: 291.226999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:59.628743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.724444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-07T04:12:59.628759Z","caller":"traceutil/trace.go:171","msg":"trace[555063408] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:700; }","duration":"313.760724ms","start":"2026-02-07T04:12:59.314993Z","end":"2026-02-07T04:12:59.628754Z","steps":["trace[555063408] 'agreement among raft nodes before linearized reading' (duration: 313.713693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:12:59.628775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:12:59.314979Z","time spent":"313.792785ms","remote":"127.0.0.1:56874","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-07T04:12:59.953978Z","caller":"traceutil/trace.go:171","msg":"trace[1647361862] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"256.911569ms","start":"2026-02-07T04:12:59.69696Z","end":"2026-02-07T04:12:59.953872Z","steps":["trace[1647361862] 'process raft request' (duration: 232.672665ms)","trace[1647361862] 'compare' (duration: 23.861086ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:13:00.4415Z","caller":"traceutil/trace.go:171","msg":"trace[1758305441] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:724; }","duration":"660.677893ms","start":"2026-02-07T04:12:59.780806Z","end":"2026-02-07T04:13:00.441484Z","steps":["trace[1758305441] 'read index received' (duration: 148.852492ms)","trace[1758305441] 'applied index is now lower than readState.Index' (duration: 511.824551ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:13:00.441759Z","caller":"traceutil/trace.go:171","msg":"trace[1069880006] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"744.654989ms","start":"2026-02-07T04:12:59.697098Z","end":"2026-02-07T04:13:00.441753Z","steps":["trace[1069880006] 'process raft request' (duration: 744.257371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:00.441834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:12:59.697062Z","time spent":"744.71644ms","remote":"127.0.0.1:56874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:13:00.441934Z","caller":"traceutil/trace.go:171","msg":"trace[75570271] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"735.235576ms","start":"2026-02-07T04:12:59.706694Z","end":"2026-02-07T04:13:00.441929Z","steps":["trace[75570271] 'process raft request' (duration: 734.741725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:00.441963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:12:59.706681Z","time spent":"735.267638ms","remote":"127.0.0.1:56874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:13:00.442095Z","caller":"traceutil/trace.go:171","msg":"trace[132889176] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"730.380459ms","start":"2026-02-07T04:12:59.71171Z","end":"2026-02-07T04:13:00.442091Z","steps":["trace[132889176] 'process raft request' (duration: 729.750495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:00.442125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:12:59.711575Z","time spent":"730.536652ms","remote":"127.0.0.1:57086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4182,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:13:00.442205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.41021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:13:00.442224Z","caller":"traceutil/trace.go:171","msg":"trace[1899329091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:704; }","duration":"661.452991ms","start":"2026-02-07T04:12:59.780767Z","end":"2026-02-07T04:13:00.44222Z","steps":["trace[1899329091] 'agreement among raft nodes before linearized reading' (duration: 661.41489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:00.442237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:12:59.780714Z","time spent":"661.520112ms","remote":"127.0.0.1:56626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T04:13:00.442362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.434907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.217\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-07T04:13:00.442391Z","caller":"traceutil/trace.go:171","msg":"trace[1414844898] range","detail":"{range_begin:/registry/masterleases/162.253.55.217; range_end:; response_count:1; response_revision:704; }","duration":"419.464987ms","start":"2026-02-07T04:13:00.022922Z","end":"2026-02-07T04:13:00.442387Z","steps":["trace[1414844898] 'agreement among raft nodes before linearized reading' (duration: 419.393516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:00.442404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:13:00.022879Z","time spent":"419.522239ms","remote":"127.0.0.1:56662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.217\" "} {"level":"warn","ts":"2026-02-07T04:13:00.442588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.946577ms","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-07T04:13:00.442606Z","caller":"traceutil/trace.go:171","msg":"trace[1685984462] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:704; }","duration":"289.000868ms","start":"2026-02-07T04:13:00.153602Z","end":"2026-02-07T04:13:00.442602Z","steps":["trace[1685984462] 'agreement among raft nodes before linearized reading' (duration: 288.957167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:04.915881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.798871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:13:04.916616Z","caller":"traceutil/trace.go:171","msg":"trace[2135249] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"427.236722ms","start":"2026-02-07T04:13:04.489364Z","end":"2026-02-07T04:13:04.9166Z","steps":["trace[2135249] 'process raft request' (duration: 163.633813ms)","trace[2135249] 'compare' (duration: 262.636568ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:13:04.916695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:13:04.489354Z","time spent":"427.302343ms","remote":"127.0.0.1:56874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:13:04.917005Z","caller":"traceutil/trace.go:171","msg":"trace[733739717] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:741; }","duration":"136.815863ms","start":"2026-02-07T04:13:04.779143Z","end":"2026-02-07T04:13:04.915959Z","steps":["trace[733739717] 'read index received' (duration: 33.511µs)","trace[733739717] 'applied index is now lower than readState.Index' (duration: 136.781352ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:13:04.917185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.02391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:13:04.917236Z","caller":"traceutil/trace.go:171","msg":"trace[1659919397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:717; }","duration":"138.121303ms","start":"2026-02-07T04:13:04.779095Z","end":"2026-02-07T04:13:04.917216Z","steps":["trace[1659919397] 'agreement among raft nodes before linearized reading' (duration: 138.01708ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:13:17.405588Z","caller":"traceutil/trace.go:171","msg":"trace[2138013999] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"430.184618ms","start":"2026-02-07T04:13:16.975387Z","end":"2026-02-07T04:13:17.405572Z","steps":["trace[2138013999] 'process raft request' (duration: 430.100035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:17.405701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:13:16.975378Z","time spent":"430.259919ms","remote":"127.0.0.1:56874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:13:22.021539Z","caller":"traceutil/trace.go:171","msg":"trace[620862825] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"428.661724ms","start":"2026-02-07T04:13:21.592861Z","end":"2026-02-07T04:13:22.021522Z","steps":["trace[620862825] 'read index received' (duration: 428.441719ms)","trace[620862825] 'applied index is now lower than readState.Index' (duration: 219.355µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:13:22.021619Z","caller":"traceutil/trace.go:171","msg":"trace[1021351606] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"595.724964ms","start":"2026-02-07T04:13:21.425887Z","end":"2026-02-07T04:13:22.021612Z","steps":["trace[1021351606] 'process raft request' (duration: 595.484849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:22.021697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:13:21.425875Z","time spent":"595.762166ms","remote":"127.0.0.1:56874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:13:22.021803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.93652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:13:22.021837Z","caller":"traceutil/trace.go:171","msg":"trace[1404787058] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:768; }","duration":"428.981422ms","start":"2026-02-07T04:13:21.592836Z","end":"2026-02-07T04:13:22.021817Z","steps":["trace[1404787058] 'agreement among raft nodes before linearized reading' (duration: 428.937321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:22.021858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:13:21.592824Z","time spent":"429.028622ms","remote":"127.0.0.1:56680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-02-07T04:13:22.022031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.274773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-07T04:13:22.022078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.158317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:13:22.022087Z","caller":"traceutil/trace.go:171","msg":"trace[2087307302] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:768; }","duration":"260.359695ms","start":"2026-02-07T04:13:21.76171Z","end":"2026-02-07T04:13:22.02207Z","steps":["trace[2087307302] 'agreement among raft nodes before linearized reading' (duration: 260.210452ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:13:22.0221Z","caller":"traceutil/trace.go:171","msg":"trace[1437574943] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:768; }","duration":"242.205868ms","start":"2026-02-07T04:13:21.779888Z","end":"2026-02-07T04:13:22.022094Z","steps":["trace[1437574943] 'agreement among raft nodes before linearized reading' (duration: 242.172647ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:13:22.211469Z","caller":"traceutil/trace.go:171","msg":"trace[712864703] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:797; }","duration":"171.434709ms","start":"2026-02-07T04:13:22.040014Z","end":"2026-02-07T04:13:22.211449Z","steps":["trace[712864703] 'read index received' (duration: 132.556526ms)","trace[712864703] 'applied index is now lower than readState.Index' (duration: 38.877253ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:13:22.211627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.600373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-07T04:13:22.211644Z","caller":"traceutil/trace.go:171","msg":"trace[1984620604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:769; }","duration":"171.645043ms","start":"2026-02-07T04:13:22.039993Z","end":"2026-02-07T04:13:22.211638Z","steps":["trace[1984620604] 'agreement among raft nodes before linearized reading' (duration: 171.51424ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:13:22.211642Z","caller":"traceutil/trace.go:171","msg":"trace[1302283258] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"183.207703ms","start":"2026-02-07T04:13:22.028415Z","end":"2026-02-07T04:13:22.211623Z","steps":["trace[1302283258] 'process raft request' (duration: 144.227828ms)","trace[1302283258] 'compare' (duration: 38.622487ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:13:26.147122Z","caller":"traceutil/trace.go:171","msg":"trace[1900143606] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"102.233075ms","start":"2026-02-07T04:13:26.044873Z","end":"2026-02-07T04:13:26.147106Z","steps":["trace[1900143606] 'process raft request' (duration: 102.137013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:27.066139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.013168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-07T04:13:27.06687Z","caller":"traceutil/trace.go:171","msg":"trace[406111002] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:779; }","duration":"844.791016ms","start":"2026-02-07T04:13:26.222066Z","end":"2026-02-07T04:13:27.066857Z","steps":["trace[406111002] 'range keys from in-memory index tree' (duration: 843.808044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:27.06704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:13:26.222045Z","time spent":"844.98479ms","remote":"127.0.0.1:56874","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-07T04:13:27.066292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.955536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-07T04:13:27.067442Z","caller":"traceutil/trace.go:171","msg":"trace[1665214906] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:779; }","duration":"538.151192ms","start":"2026-02-07T04:13:26.529283Z","end":"2026-02-07T04:13:27.067434Z","steps":["trace[1665214906] 'range keys from in-memory index tree' (duration: 536.819493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:27.067522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:13:26.529263Z","time spent":"538.250655ms","remote":"127.0.0.1:56874","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-07T04:13:27.066483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.114102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-07T04:13:27.067587Z","caller":"traceutil/trace.go:171","msg":"trace[1060291422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:779; }","duration":"817.27497ms","start":"2026-02-07T04:13:26.250306Z","end":"2026-02-07T04:13:27.067581Z","steps":["trace[1060291422] 'range keys from in-memory index tree' (duration: 815.98603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:27.06762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:13:26.250288Z","time spent":"817.32609ms","remote":"127.0.0.1:56874","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-07T04:13:27.06681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.391729ms","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-07T04:13:27.067791Z","caller":"traceutil/trace.go:171","msg":"trace[421686265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:779; }","duration":"499.388063ms","start":"2026-02-07T04:13:26.568394Z","end":"2026-02-07T04:13:27.067782Z","steps":["trace[421686265] 'range keys from in-memory index tree' (duration: 498.299588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:27.067829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:13:26.568384Z","time spent":"499.437174ms","remote":"127.0.0.1:56874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T04:13:40.351459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.826283ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:13:40.351529Z","caller":"traceutil/trace.go:171","msg":"trace[2053301287] linearizableReadLoop","detail":"{readStateIndex:903; appliedIndex:902; }","duration":"127.322804ms","start":"2026-02-07T04:13:40.224197Z","end":"2026-02-07T04:13:40.351519Z","steps":["trace[2053301287] 'read index received' (duration: 19.33µs)","trace[2053301287] 'applied index is now lower than readState.Index' (duration: 127.302484ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:13:40.351658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.459828ms","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-07T04:13:40.35167Z","caller":"traceutil/trace.go:171","msg":"trace[898008624] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:868; }","duration":"127.496499ms","start":"2026-02-07T04:13:40.22417Z","end":"2026-02-07T04:13:40.351666Z","steps":["trace[898008624] 'agreement among raft nodes before linearized reading' (duration: 127.374785ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:13:40.351784Z","caller":"traceutil/trace.go:171","msg":"trace[1573282149] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"233.895267ms","start":"2026-02-07T04:13:40.117872Z","end":"2026-02-07T04:13:40.351767Z","steps":["trace[1573282149] 'process raft request' (duration: 95.701195ms)","trace[1573282149] 'compare' (duration: 137.612758ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:13:40.839852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.854298ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:13:40.845399Z","caller":"traceutil/trace.go:171","msg":"trace[561269038] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"488.213987ms","start":"2026-02-07T04:13:40.357165Z","end":"2026-02-07T04:13:40.845379Z","steps":["trace[561269038] 'process raft request' (duration: 118.779897ms)","trace[561269038] 'compare' (duration: 363.672164ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:13:40.845467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:13:40.357155Z","time spent":"488.274359ms","remote":"127.0.0.1:56874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:13:40.845549Z","caller":"traceutil/trace.go:171","msg":"trace[203860790] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"291.505511ms","start":"2026-02-07T04:13:40.554035Z","end":"2026-02-07T04:13:40.845541Z","steps":["trace[203860790] 'process raft request' (duration: 285.876949ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:13:49.009528Z","caller":"traceutil/trace.go:171","msg":"trace[1032455307] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"128.456239ms","start":"2026-02-07T04:13:48.881057Z","end":"2026-02-07T04:13:49.009513Z","steps":["trace[1032455307] 'process raft request' (duration: 128.355317ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:13:51.090431Z","caller":"traceutil/trace.go:171","msg":"trace[1974892193] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"195.598062ms","start":"2026-02-07T04:13:50.894816Z","end":"2026-02-07T04:13:51.090414Z","steps":["trace[1974892193] 'process raft request' (duration: 195.168503ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:13:53.862561Z","caller":"traceutil/trace.go:171","msg":"trace[1963792455] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"219.010661ms","start":"2026-02-07T04:13:53.643506Z","end":"2026-02-07T04:13:53.862517Z","steps":["trace[1963792455] 'process raft request' (duration: 218.710915ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:13:57.4344Z","caller":"traceutil/trace.go:171","msg":"trace[405099745] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:959; }","duration":"303.490803ms","start":"2026-02-07T04:13:57.130829Z","end":"2026-02-07T04:13:57.43432Z","steps":["trace[405099745] 'read index received' (duration: 303.322239ms)","trace[405099745] 'applied index is now lower than readState.Index' (duration: 167.664µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:13:57.434646Z","caller":"traceutil/trace.go:171","msg":"trace[718128999] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"316.21899ms","start":"2026-02-07T04:13:57.118416Z","end":"2026-02-07T04:13:57.434635Z","steps":["trace[718128999] 'process raft request' (duration: 315.78514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:57.434751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:13:57.1184Z","time spent":"316.283071ms","remote":"127.0.0.1:56874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:13:57.434947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.111617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:13:57.435008Z","caller":"traceutil/trace.go:171","msg":"trace[1194262248] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:922; }","duration":"304.171618ms","start":"2026-02-07T04:13:57.130824Z","end":"2026-02-07T04:13:57.434996Z","steps":["trace[1194262248] 'agreement among raft nodes before linearized reading' (duration: 304.077326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:57.435044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:13:57.130813Z","time spent":"304.221659ms","remote":"127.0.0.1:37852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-07T04:13:57.435289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.934958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-07T04:13:57.435325Z","caller":"traceutil/trace.go:171","msg":"trace[1925417873] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:922; }","duration":"173.01628ms","start":"2026-02-07T04:13:57.2623Z","end":"2026-02-07T04:13:57.435317Z","steps":["trace[1925417873] 'agreement among raft nodes before linearized reading' (duration: 172.905227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:57.435529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.11912ms","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-07T04:13:57.435554Z","caller":"traceutil/trace.go:171","msg":"trace[215977820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:922; }","duration":"116.172122ms","start":"2026-02-07T04:13:57.319377Z","end":"2026-02-07T04:13:57.435549Z","steps":["trace[215977820] 'agreement among raft nodes before linearized reading' (duration: 116.11387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:57.435649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.330841ms","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-07T04:13:57.435669Z","caller":"traceutil/trace.go:171","msg":"trace[1553066630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:922; }","duration":"147.371701ms","start":"2026-02-07T04:13:57.288292Z","end":"2026-02-07T04:13:57.435664Z","steps":["trace[1553066630] 'agreement among raft nodes before linearized reading' (duration: 147.32269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:57.435769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.201203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-07T04:13:57.435789Z","caller":"traceutil/trace.go:171","msg":"trace[1171505901] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:922; }","duration":"173.245215ms","start":"2026-02-07T04:13:57.262539Z","end":"2026-02-07T04:13:57.435785Z","steps":["trace[1171505901] 'agreement among raft nodes before linearized reading' (duration: 173.191164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:57.773952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.845632ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:13:57.776498Z","caller":"traceutil/trace.go:171","msg":"trace[1957366765] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:960; }","duration":"244.360455ms","start":"2026-02-07T04:13:57.532126Z","end":"2026-02-07T04:13:57.776486Z","steps":["trace[1957366765] 'read index received' (duration: 75.290481ms)","trace[1957366765] 'applied index is now lower than readState.Index' (duration: 169.069194ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:13:57.77662Z","caller":"traceutil/trace.go:171","msg":"trace[285286040] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"333.086137ms","start":"2026-02-07T04:13:57.443525Z","end":"2026-02-07T04:13:57.776611Z","steps":["trace[285286040] 'process raft request' (duration: 332.855922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:57.776778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:13:57.443519Z","time spent":"333.196179ms","remote":"127.0.0.1:56874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:13:57.776998Z","caller":"traceutil/trace.go:171","msg":"trace[789498073] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"333.70128ms","start":"2026-02-07T04:13:57.443288Z","end":"2026-02-07T04:13:57.776989Z","steps":["trace[789498073] 'process raft request' (duration: 164.123815ms)","trace[789498073] 'compare' (duration: 164.77575ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:13:57.777117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:13:57.44327Z","time spent":"333.753102ms","remote":"127.0.0.1:56874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:13:57.777535Z","caller":"traceutil/trace.go:171","msg":"trace[596549708] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"333.961026ms","start":"2026-02-07T04:13:57.443564Z","end":"2026-02-07T04:13:57.777525Z","steps":["trace[596549708] 'process raft request' (duration: 332.872712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:57.779413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:13:57.443561Z","time spent":"333.998188ms","remote":"127.0.0.1:56874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:13:57.779878Z","caller":"traceutil/trace.go:171","msg":"trace[160691716] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"336.051622ms","start":"2026-02-07T04:13:57.443817Z","end":"2026-02-07T04:13:57.779869Z","steps":["trace[160691716] 'process raft request' (duration: 332.645408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:13:57.779992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:13:57.443812Z","time spent":"336.093583ms","remote":"127.0.0.1:56874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:13:57.780939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.803992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-07T04:13:57.781894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.548996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:13:57.78199Z","caller":"traceutil/trace.go:171","msg":"trace[716471418] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:926; }","duration":"192.587307ms","start":"2026-02-07T04:13:57.589334Z","end":"2026-02-07T04:13:57.781921Z","steps":["trace[716471418] 'agreement among raft nodes before linearized reading' (duration: 191.705747ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:13:57.782569Z","caller":"traceutil/trace.go:171","msg":"trace[1599897057] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:926; }","duration":"248.864023ms","start":"2026-02-07T04:13:57.532099Z","end":"2026-02-07T04:13:57.780963Z","steps":["trace[1599897057] 'agreement among raft nodes before linearized reading' (duration: 248.74686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:14.014334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.25486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-07T04:14:14.014543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.095625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-07T04:14:14.014569Z","caller":"traceutil/trace.go:171","msg":"trace[2053039798] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1024; }","duration":"108.135105ms","start":"2026-02-07T04:14:13.906421Z","end":"2026-02-07T04:14:14.014556Z","steps":["trace[2053039798] 'range keys from in-memory index tree' (duration: 108.008753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:14.014769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.729181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-07T04:14:14.014784Z","caller":"traceutil/trace.go:171","msg":"trace[1165305460] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1024; }","duration":"472.780242ms","start":"2026-02-07T04:14:13.541998Z","end":"2026-02-07T04:14:14.014779Z","steps":["trace[1165305460] 'range keys from in-memory index tree' (duration: 472.649589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:14.014797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:14:13.541979Z","time spent":"472.813662ms","remote":"127.0.0.1:56874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-07T04:14:14.014899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.047552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:14:14.031045Z","caller":"traceutil/trace.go:171","msg":"trace[1497338782] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1024; }","duration":"573.379073ms","start":"2026-02-07T04:14:13.440993Z","end":"2026-02-07T04:14:14.014372Z","steps":["trace[1497338782] 'count revisions from in-memory index tree' (duration: 573.164629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:14.031098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:14:13.440973Z","time spent":"590.110458ms","remote":"127.0.0.1:56844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-02-07T04:14:14.032278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.855116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:14:14.032325Z","caller":"traceutil/trace.go:171","msg":"trace[1217775384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1024; }","duration":"216.917078ms","start":"2026-02-07T04:14:13.815397Z","end":"2026-02-07T04:14:14.032314Z","steps":["trace[1217775384] 'range keys from in-memory index tree' (duration: 216.818786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:14.032528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.884731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-07T04:14:14.032562Z","caller":"traceutil/trace.go:171","msg":"trace[657390891] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1024; }","duration":"196.940043ms","start":"2026-02-07T04:14:13.835614Z","end":"2026-02-07T04:14:14.032554Z","steps":["trace[657390891] 'range keys from in-memory index tree' (duration: 196.78016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:14.032778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.761407ms","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-07T04:14:14.032896Z","caller":"traceutil/trace.go:171","msg":"trace[127538518] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1024; }","duration":"195.888119ms","start":"2026-02-07T04:14:13.836997Z","end":"2026-02-07T04:14:14.032885Z","steps":["trace[127538518] 'range keys from in-memory index tree' (duration: 195.701365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:14.0335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.588416ms","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-07T04:14:14.033537Z","caller":"traceutil/trace.go:171","msg":"trace[1619481184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1024; }","duration":"191.639337ms","start":"2026-02-07T04:14:13.841888Z","end":"2026-02-07T04:14:14.033528Z","steps":["trace[1619481184] 'range keys from in-memory index tree' (duration: 191.520905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:14.033689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.394ms","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-07T04:14:14.033723Z","caller":"traceutil/trace.go:171","msg":"trace[345608209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1024; }","duration":"174.447231ms","start":"2026-02-07T04:14:13.859266Z","end":"2026-02-07T04:14:14.033714Z","steps":["trace[345608209] 'range keys from in-memory index tree' (duration: 174.324718ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:14:14.034907Z","caller":"traceutil/trace.go:171","msg":"trace[1640444816] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1024; }","duration":"370.098124ms","start":"2026-02-07T04:14:13.64481Z","end":"2026-02-07T04:14:14.014908Z","steps":["trace[1640444816] 'count revisions from in-memory index tree' (duration: 369.994881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:14:14.03497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:14:13.644792Z","time spent":"390.160251ms","remote":"127.0.0.1:56798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-02-07T04:14:14.185779Z","caller":"traceutil/trace.go:171","msg":"trace[1672110255] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1065; }","duration":"144.55995ms","start":"2026-02-07T04:14:14.040569Z","end":"2026-02-07T04:14:14.185129Z","steps":["trace[1672110255] 'read index received' (duration: 144.263033ms)","trace[1672110255] 'applied index is now lower than readState.Index' (duration: 296.037µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:14:14.186258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.663794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:14:14.186688Z","caller":"traceutil/trace.go:171","msg":"trace[2107565075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1025; }","duration":"146.096024ms","start":"2026-02-07T04:14:14.040543Z","end":"2026-02-07T04:14:14.186639Z","steps":["trace[2107565075] 'agreement among raft nodes before linearized reading' (duration: 145.585202ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:14:14.187771Z","caller":"traceutil/trace.go:171","msg":"trace[1150682757] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"156.193423ms","start":"2026-02-07T04:14:14.031562Z","end":"2026-02-07T04:14:14.187756Z","steps":["trace[1150682757] 'process raft request' (duration: 153.357851ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:14:14.195273Z","caller":"traceutil/trace.go:171","msg":"trace[1724625862] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"154.63014ms","start":"2026-02-07T04:14:14.04063Z","end":"2026-02-07T04:14:14.195261Z","steps":["trace[1724625862] 'process raft request' (duration: 154.097108ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:14:14.195931Z","caller":"traceutil/trace.go:171","msg":"trace[582282720] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"141.723328ms","start":"2026-02-07T04:14:14.054197Z","end":"2026-02-07T04:14:14.19592Z","steps":["trace[582282720] 'process raft request' (duration: 140.592933ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:14:14.196268Z","caller":"traceutil/trace.go:171","msg":"trace[454517016] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"140.502082ms","start":"2026-02-07T04:14:14.055663Z","end":"2026-02-07T04:14:14.196166Z","steps":["trace[454517016] 'process raft request' (duration: 139.151902ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:14:14.196556Z","caller":"traceutil/trace.go:171","msg":"trace[1752370031] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"138.61084ms","start":"2026-02-07T04:14:14.057933Z","end":"2026-02-07T04:14:14.196544Z","steps":["trace[1752370031] 'process raft request' (duration: 136.907532ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:14:14.196739Z","caller":"traceutil/trace.go:171","msg":"trace[1271852365] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"137.539407ms","start":"2026-02-07T04:14:14.059191Z","end":"2026-02-07T04:14:14.19673Z","steps":["trace[1271852365] 'process raft request' (duration: 135.710797ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:14:14.196991Z","caller":"traceutil/trace.go:171","msg":"trace[755207326] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"137.726261ms","start":"2026-02-07T04:14:14.059252Z","end":"2026-02-07T04:14:14.196979Z","steps":["trace[755207326] 'process raft request' (duration: 135.682227ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:17:10.059202Z","caller":"traceutil/trace.go:171","msg":"trace[1922799646] transaction","detail":"{read_only:false; response_revision:1771; number_of_response:1; }","duration":"119.727893ms","start":"2026-02-07T04:17:09.939455Z","end":"2026-02-07T04:17:10.059183Z","steps":["trace[1922799646] 'process raft request' (duration: 119.289054ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:17:10.26318Z","caller":"traceutil/trace.go:171","msg":"trace[1147485978] transaction","detail":"{read_only:false; response_revision:1772; number_of_response:1; }","duration":"131.824913ms","start":"2026-02-07T04:17:10.131329Z","end":"2026-02-07T04:17:10.263154Z","steps":["trace[1147485978] 'process raft request' (duration: 85.81964ms)","trace[1147485978] 'compare' (duration: 45.8658ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:17:15.229092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.150286ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:17:15.229524Z","caller":"traceutil/trace.go:171","msg":"trace[1143505715] linearizableReadLoop","detail":"{readStateIndex:1874; appliedIndex:1873; }","duration":"198.457255ms","start":"2026-02-07T04:17:15.031041Z","end":"2026-02-07T04:17:15.229498Z","steps":["trace[1143505715] 'read index received' (duration: 4.828798ms)","trace[1143505715] 'applied index is now lower than readState.Index' (duration: 193.626587ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:17:15.229698Z","caller":"traceutil/trace.go:171","msg":"trace[2045440171] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"199.753603ms","start":"2026-02-07T04:17:15.029931Z","end":"2026-02-07T04:17:15.229684Z","steps":["trace[2045440171] 'compare' (duration: 193.025384ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:17:15.230213Z","caller":"traceutil/trace.go:171","msg":"trace[1671171708] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"196.189135ms","start":"2026-02-07T04:17:15.034011Z","end":"2026-02-07T04:17:15.230201Z","steps":["trace[1671171708] 'process raft request' (duration: 195.173422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:17:15.365858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.793829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-07T04:17:15.365933Z","caller":"traceutil/trace.go:171","msg":"trace[1279186054] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1797; }","duration":"334.88322ms","start":"2026-02-07T04:17:15.031027Z","end":"2026-02-07T04:17:15.36591Z","steps":["trace[1279186054] 'agreement among raft nodes before linearized reading' (duration: 198.981096ms)","trace[1279186054] 'range keys from in-memory index tree' (duration: 135.73477ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:17:15.366356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:17:15.031021Z","time spent":"335.3199ms","remote":"127.0.0.1:56874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-07T04:17:15.378191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.703669ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-07T04:17:15.378549Z","caller":"traceutil/trace.go:171","msg":"trace[1938057076] linearizableReadLoop","detail":"{readStateIndex:1876; appliedIndex:1875; }","duration":"148.927033ms","start":"2026-02-07T04:17:15.229613Z","end":"2026-02-07T04:17:15.37854Z","steps":["trace[1938057076] 'read index received' (duration: 411.419µs)","trace[1938057076] 'applied index is now lower than readState.Index' (duration: 148.514504ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:17:15.378632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.575081ms","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-07T04:17:15.37865Z","caller":"traceutil/trace.go:171","msg":"trace[579483171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1797; }","duration":"284.629631ms","start":"2026-02-07T04:17:15.094016Z","end":"2026-02-07T04:17:15.378646Z","steps":["trace[579483171] 'agreement among raft nodes before linearized reading' (duration: 284.55684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:17:15.378748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.276549ms","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-07T04:17:15.378765Z","caller":"traceutil/trace.go:171","msg":"trace[493988210] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1798; }","duration":"215.324241ms","start":"2026-02-07T04:17:15.163437Z","end":"2026-02-07T04:17:15.378761Z","steps":["trace[493988210] 'agreement among raft nodes before linearized reading' (duration: 215.28183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:17:15.378842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.184275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:17:15.37886Z","caller":"traceutil/trace.go:171","msg":"trace[1747820767] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1798; }","duration":"241.203456ms","start":"2026-02-07T04:17:15.137651Z","end":"2026-02-07T04:17:15.378855Z","steps":["trace[1747820767] 'agreement among raft nodes before linearized reading' (duration: 241.174765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:17:15.379145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.934273ms","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-07T04:17:15.379165Z","caller":"traceutil/trace.go:171","msg":"trace[585054706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1798; }","duration":"277.987105ms","start":"2026-02-07T04:17:15.101173Z","end":"2026-02-07T04:17:15.37916Z","steps":["trace[585054706] 'agreement among raft nodes before linearized reading' (duration: 277.931313ms)"],"step_count":1}