{"level":"warn","ts":"2026-01-29T19:40:04.92314Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T19:40:04.923326Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.43: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.43:2380","--initial-cluster=instance=https://162.253.55.43:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.43:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.43: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-01-29T19:40:04.923463Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T19:40:04.923524Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-01-29T19:40:04.923583Z","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-01-29T19:40:04.925389Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"]} {"level":"info","ts":"2026-01-29T19:40:04.925821Z","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.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.43: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-01-29T19:40:04.977199Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"50.886931ms"} {"level":"info","ts":"2026-01-29T19:40:05.000095Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"14f8f2dfdaccebdb","cluster-id":"5f02ccb15672f35d"} {"level":"info","ts":"2026-01-29T19:40:05.000165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=()"} {"level":"info","ts":"2026-01-29T19:40:05.000189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 0"} {"level":"info","ts":"2026-01-29T19:40:05.000201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 14f8f2dfdaccebdb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-29T19:40:05.00021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 1"} {"level":"info","ts":"2026-01-29T19:40:05.000249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"warn","ts":"2026-01-29T19:40:05.095046Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-29T19:40:05.09839Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-29T19:40:05.107531Z","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-01-29T19:40:05.125403Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"14f8f2dfdaccebdb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-29T19:40:05.126301Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"14f8f2dfdaccebdb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-29T19:40:05.126494Z","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-01-29T19:40:05.126539Z","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-01-29T19:40:05.126562Z","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-01-29T19:40:05.128708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"info","ts":"2026-01-29T19:40:05.128868Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","added-peer-id":"14f8f2dfdaccebdb","added-peer-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-01-29T19:40:05.143065Z","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-01-29T19:40:05.143382Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"14f8f2dfdaccebdb","initial-advertise-peer-urls":["https://162.253.55.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-29T19:40:05.143201Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-01-29T19:40:05.144257Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-01-29T19:40:05.144212Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-29T19:40:05.602851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb is starting a new election at term 1"} {"level":"info","ts":"2026-01-29T19:40:05.603015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became pre-candidate at term 1"} {"level":"info","ts":"2026-01-29T19:40:05.603101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgPreVoteResp from 14f8f2dfdaccebdb at term 1"} {"level":"info","ts":"2026-01-29T19:40:05.603127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became candidate at term 2"} {"level":"info","ts":"2026-01-29T19:40:05.603147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgVoteResp from 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-01-29T19:40:05.603193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became leader at term 2"} {"level":"info","ts":"2026-01-29T19:40:05.603227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 14f8f2dfdaccebdb elected leader 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-01-29T19:40:05.696375Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T19:40:05.696986Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"14f8f2dfdaccebdb","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.43:2379]}","request-path":"/0/members/14f8f2dfdaccebdb/attributes","cluster-id":"5f02ccb15672f35d","publish-timeout":"7s"} {"level":"info","ts":"2026-01-29T19:40:05.697056Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T19:40:05.697638Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T19:40:05.699608Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-29T19:40:05.703438Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-29T19:40:05.703476Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-29T19:40:05.703761Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T19:40:05.705284Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T19:40:05.705429Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T19:40:05.705724Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.43:2379"} {"level":"info","ts":"2026-01-29T19:40:08.597748Z","caller":"traceutil/trace.go:171","msg":"trace[1926995002] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"149.664457ms","start":"2026-01-29T19:40:08.448064Z","end":"2026-01-29T19:40:08.597728Z","steps":["trace[1926995002] 'process raft request' (duration: 101.227957ms)","trace[1926995002] 'compare' (duration: 48.055521ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:40:08.598065Z","caller":"traceutil/trace.go:171","msg":"trace[473356348] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"119.591657ms","start":"2026-01-29T19:40:08.478464Z","end":"2026-01-29T19:40:08.598056Z","steps":["trace[473356348] 'process raft request' (duration: 118.993092ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:40:08.598229Z","caller":"traceutil/trace.go:171","msg":"trace[540688347] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"118.147001ms","start":"2026-01-29T19:40:08.480073Z","end":"2026-01-29T19:40:08.59822Z","steps":["trace[540688347] 'process raft request' (duration: 117.414864ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:40:09.350186Z","caller":"traceutil/trace.go:171","msg":"trace[35876566] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"122.947387ms","start":"2026-01-29T19:40:09.227213Z","end":"2026-01-29T19:40:09.35016Z","steps":["trace[35876566] 'process raft request' (duration: 122.868234ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:40:09.350292Z","caller":"traceutil/trace.go:171","msg":"trace[1181796529] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"124.77055ms","start":"2026-01-29T19:40:09.225488Z","end":"2026-01-29T19:40:09.350259Z","steps":["trace[1181796529] 'process raft request' (duration: 37.075538ms)","trace[1181796529] 'compare' (duration: 87.260221ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:40:16.074297Z","caller":"traceutil/trace.go:171","msg":"trace[612660286] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"364.668219ms","start":"2026-01-29T19:40:15.709609Z","end":"2026-01-29T19:40:16.074277Z","steps":["trace[612660286] 'process raft request' (duration: 364.473444ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:40:16.074864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:40:15.709588Z","time spent":"364.872754ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:40:16.075073Z","caller":"traceutil/trace.go:171","msg":"trace[836968302] linearizableReadLoop","detail":"{readStateIndex:356; appliedIndex:356; }","duration":"326.73205ms","start":"2026-01-29T19:40:15.748327Z","end":"2026-01-29T19:40:16.075059Z","steps":["trace[836968302] 'read index received' (duration: 326.72708ms)","trace[836968302] 'applied index is now lower than readState.Index' (duration: 4.31µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:40:16.075316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.762593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-01-29T19:40:16.075356Z","caller":"traceutil/trace.go:171","msg":"trace[1017992582] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:345; }","duration":"276.862395ms","start":"2026-01-29T19:40:15.798486Z","end":"2026-01-29T19:40:16.075348Z","steps":["trace[1017992582] 'agreement among raft nodes before linearized reading' (duration: 276.701381ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:40:16.075754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.459558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-01-29T19:40:16.075782Z","caller":"traceutil/trace.go:171","msg":"trace[567570783] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:345; }","duration":"327.521829ms","start":"2026-01-29T19:40:15.748253Z","end":"2026-01-29T19:40:16.075775Z","steps":["trace[567570783] 'agreement among raft nodes before linearized reading' (duration: 327.473788ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:40:16.075946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:40:15.748231Z","time spent":"327.707303ms","remote":"127.0.0.1:53446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":256,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-01-29T19:40:16.076483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.463605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-01-29T19:40:16.07651Z","caller":"traceutil/trace.go:171","msg":"trace[1552000973] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:345; }","duration":"127.525727ms","start":"2026-01-29T19:40:15.948977Z","end":"2026-01-29T19:40:16.076503Z","steps":["trace[1552000973] 'agreement among raft nodes before linearized reading' (duration: 127.484696ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:40:16.076783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.340731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-01-29T19:40:16.076808Z","caller":"traceutil/trace.go:171","msg":"trace[524292465] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:345; }","duration":"177.367672ms","start":"2026-01-29T19:40:15.899435Z","end":"2026-01-29T19:40:16.076802Z","steps":["trace[524292465] 'agreement among raft nodes before linearized reading' (duration: 177.324651ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:40:16.077229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.599438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-01-29T19:40:16.077273Z","caller":"traceutil/trace.go:171","msg":"trace[1486565874] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:345; }","duration":"228.683591ms","start":"2026-01-29T19:40:15.848583Z","end":"2026-01-29T19:40:16.077267Z","steps":["trace[1486565874] 'agreement among raft nodes before linearized reading' (duration: 228.618589ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:40:21.951322Z","caller":"traceutil/trace.go:171","msg":"trace[385096957] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"480.979667ms","start":"2026-01-29T19:40:21.470318Z","end":"2026-01-29T19:40:21.951298Z","steps":["trace[385096957] 'process raft request' (duration: 480.818853ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:40:21.951459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:40:21.470266Z","time spent":"481.128281ms","remote":"127.0.0.1:53416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4241,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:40:22.697991Z","caller":"traceutil/trace.go:171","msg":"trace[255923456] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"120.811996ms","start":"2026-01-29T19:40:22.577157Z","end":"2026-01-29T19:40:22.697969Z","steps":["trace[255923456] 'process raft request' (duration: 106.066623ms)","trace[255923456] 'compare' (duration: 13.182576ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:40:23.898191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.989917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T19:40:23.898314Z","caller":"traceutil/trace.go:171","msg":"trace[925747557] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"146.81507ms","start":"2026-01-29T19:40:23.751471Z","end":"2026-01-29T19:40:23.898286Z","steps":["trace[925747557] 'process raft request' (duration: 41.511345ms)","trace[925747557] 'compare' (duration: 104.876235ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:40:23.904356Z","caller":"traceutil/trace.go:171","msg":"trace[178105222] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"146.651965ms","start":"2026-01-29T19:40:23.757688Z","end":"2026-01-29T19:40:23.90434Z","steps":["trace[178105222] 'process raft request' (duration: 146.571553ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:40:24.37326Z","caller":"traceutil/trace.go:171","msg":"trace[23462898] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"249.940869ms","start":"2026-01-29T19:40:24.1233Z","end":"2026-01-29T19:40:24.373241Z","steps":["trace[23462898] 'process raft request' (duration: 172.963715ms)","trace[23462898] 'compare' (duration: 76.704328ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:40:24.624777Z","caller":"traceutil/trace.go:171","msg":"trace[572642570] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"241.194791ms","start":"2026-01-29T19:40:24.383565Z","end":"2026-01-29T19:40:24.624759Z","steps":["trace[572642570] 'process raft request' (duration: 241.110279ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:40:24.625134Z","caller":"traceutil/trace.go:171","msg":"trace[913910643] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"243.825404ms","start":"2026-01-29T19:40:24.381283Z","end":"2026-01-29T19:40:24.625109Z","steps":["trace[913910643] 'process raft request' (duration: 243.343623ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:40:24.625347Z","caller":"traceutil/trace.go:171","msg":"trace[1340122012] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"245.776801ms","start":"2026-01-29T19:40:24.379559Z","end":"2026-01-29T19:40:24.625335Z","steps":["trace[1340122012] 'process raft request' (duration: 152.348331ms)","trace[1340122012] 'compare' (duration: 92.60298ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:40:24.625608Z","caller":"traceutil/trace.go:171","msg":"trace[878544826] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"243.357583ms","start":"2026-01-29T19:40:24.382241Z","end":"2026-01-29T19:40:24.625599Z","steps":["trace[878544826] 'process raft request' (duration: 242.413991ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:40:28.639539Z","caller":"traceutil/trace.go:171","msg":"trace[634264031] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"151.87833ms","start":"2026-01-29T19:40:28.487643Z","end":"2026-01-29T19:40:28.639521Z","steps":["trace[634264031] 'process raft request' (duration: 151.752017ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:40:36.496351Z","caller":"traceutil/trace.go:171","msg":"trace[850243351] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"312.665383ms","start":"2026-01-29T19:40:36.183665Z","end":"2026-01-29T19:40:36.49633Z","steps":["trace[850243351] 'process raft request' (duration: 312.602702ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:40:36.496464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:40:36.183654Z","time spent":"312.759236ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10217,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-29T19:40:36.49669Z","caller":"traceutil/trace.go:171","msg":"trace[876020317] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"313.671758ms","start":"2026-01-29T19:40:36.183009Z","end":"2026-01-29T19:40:36.49668Z","steps":["trace[876020317] 'process raft request' (duration: 310.991443ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:40:36.496776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:40:36.182989Z","time spent":"313.723908ms","remote":"127.0.0.1:53702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3805,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-29T19:40:41.246125Z","caller":"traceutil/trace.go:171","msg":"trace[783864592] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"427.619318ms","start":"2026-01-29T19:40:40.818488Z","end":"2026-01-29T19:40:41.246107Z","steps":["trace[783864592] 'process raft request' (duration: 427.510665ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:40:41.246259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:40:40.818477Z","time spent":"427.70902ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:40:41.302571Z","caller":"traceutil/trace.go:171","msg":"trace[33713646] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:541; }","duration":"467.692928ms","start":"2026-01-29T19:40:40.83483Z","end":"2026-01-29T19:40:41.302523Z","steps":["trace[33713646] 'read index received' (duration: 411.742077ms)","trace[33713646] 'applied index is now lower than readState.Index' (duration: 55.949981ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:40:41.302729Z","caller":"traceutil/trace.go:171","msg":"trace[1012537823] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"483.193341ms","start":"2026-01-29T19:40:40.8195Z","end":"2026-01-29T19:40:41.302693Z","steps":["trace[1012537823] 'process raft request' (duration: 482.854252ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:40:41.302863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:40:40.819484Z","time spent":"483.275892ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T19:40:41.303135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.285953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:40:41.303227Z","caller":"traceutil/trace.go:171","msg":"trace[474873184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"468.379574ms","start":"2026-01-29T19:40:40.834805Z","end":"2026-01-29T19:40:41.303185Z","steps":["trace[474873184] 'agreement among raft nodes before linearized reading' (duration: 468.285472ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:40:41.303263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:40:40.834797Z","time spent":"468.457918ms","remote":"127.0.0.1:53240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T19:40:46.886145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.976424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rz75f\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-01-29T19:40:46.886303Z","caller":"traceutil/trace.go:171","msg":"trace[2045990164] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rz75f; range_end:; response_count:1; response_revision:538; }","duration":"314.20539ms","start":"2026-01-29T19:40:46.572082Z","end":"2026-01-29T19:40:46.886288Z","steps":["trace[2045990164] 'range keys from in-memory index tree' (duration: 313.854712ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:40:46.886372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:40:46.572065Z","time spent":"314.276201ms","remote":"127.0.0.1:53416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16674,"request content":"key:\"/registry/pods/kube-system/cilium-rz75f\" "} {"level":"warn","ts":"2026-01-29T19:40:48.22284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.711468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T19:40:48.222925Z","caller":"traceutil/trace.go:171","msg":"trace[1269165654] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:557; }","duration":"956.729908ms","start":"2026-01-29T19:40:47.26618Z","end":"2026-01-29T19:40:48.22291Z","steps":["trace[1269165654] 'read index received' (duration: 173.920618ms)","trace[1269165654] 'applied index is now lower than readState.Index' (duration: 782.80821ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:40:48.223137Z","caller":"traceutil/trace.go:171","msg":"trace[1279288011] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.316652644s","start":"2026-01-29T19:40:46.906471Z","end":"2026-01-29T19:40:48.223124Z","steps":["trace[1279288011] 'process raft request' (duration: 533.591738ms)","trace[1279288011] 'compare' (duration: 781.96571ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:40:48.223197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:40:46.906458Z","time spent":"1.316707775s","remote":"127.0.0.1:53416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T19:40:48.223356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.18093ms","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-01-29T19:40:48.223374Z","caller":"traceutil/trace.go:171","msg":"trace[1545699457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:540; }","duration":"957.251451ms","start":"2026-01-29T19:40:47.266116Z","end":"2026-01-29T19:40:48.223368Z","steps":["trace[1545699457] 'agreement among raft nodes before linearized reading' (duration: 957.180279ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:40:48.223391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:40:47.266097Z","time spent":"957.289452ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-29T19:40:48.223517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.855438ms","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-01-29T19:40:48.223534Z","caller":"traceutil/trace.go:171","msg":"trace[341398551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:540; }","duration":"887.907039ms","start":"2026-01-29T19:40:47.335622Z","end":"2026-01-29T19:40:48.223529Z","steps":["trace[341398551] 'agreement among raft nodes before linearized reading' (duration: 887.855608ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:40:48.22355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:40:47.335578Z","time spent":"887.96842ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-29T19:40:48.22372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.212101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:40:48.223737Z","caller":"traceutil/trace.go:171","msg":"trace[1282791054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"385.265523ms","start":"2026-01-29T19:40:47.838466Z","end":"2026-01-29T19:40:48.223732Z","steps":["trace[1282791054] 'agreement among raft nodes before linearized reading' (duration: 385.238702ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:40:48.223752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:40:47.838425Z","time spent":"385.324054ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T19:40:48.223866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.128076ms","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-01-29T19:40:48.223883Z","caller":"traceutil/trace.go:171","msg":"trace[1226590807] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:540; }","duration":"881.158186ms","start":"2026-01-29T19:40:47.34272Z","end":"2026-01-29T19:40:48.223878Z","steps":["trace[1226590807] 'agreement among raft nodes before linearized reading' (duration: 881.096255ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:40:48.223898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:40:47.342713Z","time spent":"881.181707ms","remote":"127.0.0.1:53504","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-01-29T19:41:00.461376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.56762ms","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-01-29T19:41:00.461455Z","caller":"traceutil/trace.go:171","msg":"trace[393066339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:670; }","duration":"124.670902ms","start":"2026-01-29T19:41:00.336765Z","end":"2026-01-29T19:41:00.461435Z","steps":["trace[393066339] 'range keys from in-memory index tree' (duration: 124.461677ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:00.461765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.073617ms","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-01-29T19:41:00.461798Z","caller":"traceutil/trace.go:171","msg":"trace[1173899235] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:670; }","duration":"149.135428ms","start":"2026-01-29T19:41:00.312652Z","end":"2026-01-29T19:41:00.461787Z","steps":["trace[1173899235] 'range keys from in-memory index tree' (duration: 148.735049ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:00.462032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.295567ms","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-01-29T19:41:00.462062Z","caller":"traceutil/trace.go:171","msg":"trace[601443526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:670; }","duration":"142.353629ms","start":"2026-01-29T19:41:00.319699Z","end":"2026-01-29T19:41:00.462053Z","steps":["trace[601443526] 'range keys from in-memory index tree' (duration: 142.152294ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:00.949252Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349191030869410,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-29T19:41:01.245956Z","caller":"traceutil/trace.go:171","msg":"trace[2113592167] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"801.570781ms","start":"2026-01-29T19:41:00.44437Z","end":"2026-01-29T19:41:01.24594Z","steps":["trace[2113592167] 'process raft request' (duration: 801.480569ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:01.246044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:00.444351Z","time spent":"801.650034ms","remote":"127.0.0.1:53312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-29T19:41:02.275767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.058207ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T19:41:02.276039Z","caller":"traceutil/trace.go:171","msg":"trace[1021123878] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:692; }","duration":"1.827431626s","start":"2026-01-29T19:41:00.448591Z","end":"2026-01-29T19:41:02.276023Z","steps":["trace[1021123878] 'read index received' (duration: 797.309867ms)","trace[1021123878] 'applied index is now lower than readState.Index' (duration: 1.030120779s)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:41:02.276243Z","caller":"traceutil/trace.go:171","msg":"trace[376929705] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"1.811060348s","start":"2026-01-29T19:41:00.465169Z","end":"2026-01-29T19:41:02.276229Z","steps":["trace[376929705] 'process raft request' (duration: 1.367473729s)","trace[376929705] 'compare' (duration: 442.650659ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:41:02.276305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:00.465151Z","time spent":"1.811120079s","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:41:02.276465Z","caller":"traceutil/trace.go:171","msg":"trace[214545512] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"1.810302292s","start":"2026-01-29T19:41:00.466154Z","end":"2026-01-29T19:41:02.276456Z","steps":["trace[214545512] 'process raft request' (duration: 1.809717109s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:02.276515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:00.466141Z","time spent":"1.810346232s","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:41:02.27663Z","caller":"traceutil/trace.go:171","msg":"trace[580375378] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"1.80745181s","start":"2026-01-29T19:41:00.469168Z","end":"2026-01-29T19:41:02.27662Z","steps":["trace[580375378] 'process raft request' (duration: 1.806755194s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:02.276674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:00.469152Z","time spent":"1.807498531s","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T19:41:02.276795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.828206634s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.43\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-29T19:41:02.276814Z","caller":"traceutil/trace.go:171","msg":"trace[1654442070] range","detail":"{range_begin:/registry/masterleases/162.253.55.43; range_end:; response_count:1; response_revision:674; }","duration":"1.828242475s","start":"2026-01-29T19:41:00.448564Z","end":"2026-01-29T19:41:02.276807Z","steps":["trace[1654442070] 'agreement among raft nodes before linearized reading' (duration: 1.828169324s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:02.278538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.751990603s","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-01-29T19:41:02.28129Z","caller":"traceutil/trace.go:171","msg":"trace[1429255174] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:674; }","duration":"1.754785395s","start":"2026-01-29T19:41:00.526495Z","end":"2026-01-29T19:41:02.28128Z","steps":["trace[1429255174] 'agreement among raft nodes before linearized reading' (duration: 1.751988903s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:02.281328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:00.526482Z","time spent":"1.754838896s","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-29T19:41:02.278885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.557045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:41:02.281507Z","caller":"traceutil/trace.go:171","msg":"trace[1049332898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:674; }","duration":"429.196153ms","start":"2026-01-29T19:41:01.852301Z","end":"2026-01-29T19:41:02.281497Z","steps":["trace[1049332898] 'agreement among raft nodes before linearized reading' (duration: 426.562456ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:02.281533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:01.852287Z","time spent":"429.238324ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T19:41:02.278939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.252588913s","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-01-29T19:41:02.28259Z","caller":"traceutil/trace.go:171","msg":"trace[293760813] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:674; }","duration":"1.256258343s","start":"2026-01-29T19:41:01.026312Z","end":"2026-01-29T19:41:02.28257Z","steps":["trace[293760813] 'agreement among raft nodes before linearized reading' (duration: 1.252589692s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:02.282619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:01.026295Z","time spent":"1.256315145s","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-29T19:41:02.281187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:00.448551Z","time spent":"1.83262378s","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.43\" "} {"level":"info","ts":"2026-01-29T19:41:02.79128Z","caller":"traceutil/trace.go:171","msg":"trace[1510980803] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"115.602353ms","start":"2026-01-29T19:41:02.675657Z","end":"2026-01-29T19:41:02.791259Z","steps":["trace[1510980803] 'process raft request' (duration: 101.415452ms)","trace[1510980803] 'compare' (duration: 14.094409ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:41:02.819793Z","caller":"traceutil/trace.go:171","msg":"trace[2114168290] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"143.437132ms","start":"2026-01-29T19:41:02.676342Z","end":"2026-01-29T19:41:02.819779Z","steps":["trace[2114168290] 'process raft request' (duration: 143.356811ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:41:02.90151Z","caller":"traceutil/trace.go:171","msg":"trace[2015811] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"101.504933ms","start":"2026-01-29T19:41:02.799992Z","end":"2026-01-29T19:41:02.901497Z","steps":["trace[2015811] 'process raft request' (duration: 93.687563ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:03.203165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.820889ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T19:41:03.203317Z","caller":"traceutil/trace.go:171","msg":"trace[1685741218] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"283.960353ms","start":"2026-01-29T19:41:02.919343Z","end":"2026-01-29T19:41:03.203303Z","steps":["trace[1685741218] 'process raft request' (duration: 283.910862ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:41:03.203611Z","caller":"traceutil/trace.go:171","msg":"trace[1193248294] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"292.200073ms","start":"2026-01-29T19:41:02.911399Z","end":"2026-01-29T19:41:03.203599Z","steps":["trace[1193248294] 'process raft request' (duration: 102.882555ms)","trace[1193248294] 'compare' (duration: 188.46139ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:41:04.771866Z","caller":"traceutil/trace.go:171","msg":"trace[1931675740] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"414.269105ms","start":"2026-01-29T19:41:04.357573Z","end":"2026-01-29T19:41:04.771842Z","steps":["trace[1931675740] 'process raft request' (duration: 322.082264ms)","trace[1931675740] 'compare' (duration: 91.773602ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:41:04.772132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:04.357559Z","time spent":"414.51453ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:41:04.975234Z","caller":"traceutil/trace.go:171","msg":"trace[501723476] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:741; }","duration":"594.956807ms","start":"2026-01-29T19:41:04.380254Z","end":"2026-01-29T19:41:04.975211Z","steps":["trace[501723476] 'read index received' (duration: 299.40703ms)","trace[501723476] 'applied index is now lower than readState.Index' (duration: 295.548837ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:41:04.975602Z","caller":"traceutil/trace.go:171","msg":"trace[1976865782] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"616.559187ms","start":"2026-01-29T19:41:04.35903Z","end":"2026-01-29T19:41:04.975589Z","steps":["trace[1976865782] 'process raft request' (duration: 573.611951ms)","trace[1976865782] 'compare' (duration: 42.421605ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:41:04.975691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:04.359022Z","time spent":"616.609158ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:41:04.975861Z","caller":"traceutil/trace.go:171","msg":"trace[878656822] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"616.764611ms","start":"2026-01-29T19:41:04.359087Z","end":"2026-01-29T19:41:04.975852Z","steps":["trace[878656822] 'process raft request' (duration: 616.081346ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:04.975911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:04.359083Z","time spent":"616.801112ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T19:41:04.976108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.850106ms","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-01-29T19:41:04.97614Z","caller":"traceutil/trace.go:171","msg":"trace[1106946706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:721; }","duration":"595.903447ms","start":"2026-01-29T19:41:04.380226Z","end":"2026-01-29T19:41:04.97613Z","steps":["trace[1106946706] 'agreement among raft nodes before linearized reading' (duration: 595.794794ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:04.976169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:04.380212Z","time spent":"595.949888ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-29T19:41:04.976303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.862786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:41:04.976329Z","caller":"traceutil/trace.go:171","msg":"trace[924510133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:721; }","duration":"137.912638ms","start":"2026-01-29T19:41:04.838409Z","end":"2026-01-29T19:41:04.976321Z","steps":["trace[924510133] 'agreement among raft nodes before linearized reading' (duration: 137.873967ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:41:06.939962Z","caller":"traceutil/trace.go:171","msg":"trace[2084293915] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"160.92142ms","start":"2026-01-29T19:41:06.779028Z","end":"2026-01-29T19:41:06.939949Z","steps":["trace[2084293915] 'process raft request' (duration: 160.698755ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:07.488959Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349191030869498,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-29T19:41:07.565785Z","caller":"traceutil/trace.go:171","msg":"trace[1208546309] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"580.00004ms","start":"2026-01-29T19:41:06.985768Z","end":"2026-01-29T19:41:07.565768Z","steps":["trace[1208546309] 'process raft request' (duration: 561.856254ms)","trace[1208546309] 'compare' (duration: 17.777417ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:41:07.565889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:06.98575Z","time spent":"580.074931ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:41:07.570347Z","caller":"traceutil/trace.go:171","msg":"trace[439552676] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:749; }","duration":"582.399972ms","start":"2026-01-29T19:41:06.987936Z","end":"2026-01-29T19:41:07.570336Z","steps":["trace[439552676] 'read index received' (duration: 559.692417ms)","trace[439552676] 'applied index is now lower than readState.Index' (duration: 22.707115ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:41:07.570482Z","caller":"traceutil/trace.go:171","msg":"trace[1756603077] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"584.252612ms","start":"2026-01-29T19:41:06.986223Z","end":"2026-01-29T19:41:07.570476Z","steps":["trace[1756603077] 'process raft request' (duration: 584.072048ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:07.570554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:06.986209Z","time spent":"584.292953ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T19:41:07.570693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.75419ms","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-01-29T19:41:07.570713Z","caller":"traceutil/trace.go:171","msg":"trace[694948492] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:727; }","duration":"582.796201ms","start":"2026-01-29T19:41:06.987911Z","end":"2026-01-29T19:41:07.570707Z","steps":["trace[694948492] 'agreement among raft nodes before linearized reading' (duration: 582.72116ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:07.570731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:06.9879Z","time spent":"582.827152ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-29T19:41:07.570927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.898663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T19:41:07.570949Z","caller":"traceutil/trace.go:171","msg":"trace[1230681549] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:727; }","duration":"455.937013ms","start":"2026-01-29T19:41:07.115004Z","end":"2026-01-29T19:41:07.570941Z","steps":["trace[1230681549] 'agreement among raft nodes before linearized reading' (duration: 455.846281ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:07.570967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:07.114993Z","time spent":"455.969094ms","remote":"127.0.0.1:53604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":51,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-01-29T19:41:10.896713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.367157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.43\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-29T19:41:10.896802Z","caller":"traceutil/trace.go:171","msg":"trace[1480042389] range","detail":"{range_begin:/registry/masterleases/162.253.55.43; range_end:; response_count:1; response_revision:735; }","duration":"447.48557ms","start":"2026-01-29T19:41:10.449299Z","end":"2026-01-29T19:41:10.896785Z","steps":["trace[1480042389] 'range keys from in-memory index tree' (duration: 447.208833ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:10.896871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:10.449288Z","time spent":"447.574362ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.43\" "} {"level":"info","ts":"2026-01-29T19:41:11.099635Z","caller":"traceutil/trace.go:171","msg":"trace[1472485713] linearizableReadLoop","detail":"{readStateIndex:760; appliedIndex:759; }","duration":"143.319136ms","start":"2026-01-29T19:41:10.956298Z","end":"2026-01-29T19:41:11.099617Z","steps":["trace[1472485713] 'read index received' (duration: 143.178042ms)","trace[1472485713] 'applied index is now lower than readState.Index' (duration: 139.983µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:41:11.099828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.525891ms","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-01-29T19:41:11.099871Z","caller":"traceutil/trace.go:171","msg":"trace[1428744266] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:735; }","duration":"143.591291ms","start":"2026-01-29T19:41:10.956254Z","end":"2026-01-29T19:41:11.099845Z","steps":["trace[1428744266] 'agreement among raft nodes before linearized reading' (duration: 143.450148ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:41:11.225704Z","caller":"traceutil/trace.go:171","msg":"trace[704632254] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"114.012707ms","start":"2026-01-29T19:41:11.111675Z","end":"2026-01-29T19:41:11.225688Z","steps":["trace[704632254] 'process raft request' (duration: 104.560191ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:41:25.604074Z","caller":"traceutil/trace.go:171","msg":"trace[1770461035] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"332.328508ms","start":"2026-01-29T19:41:25.271728Z","end":"2026-01-29T19:41:25.604056Z","steps":["trace[1770461035] 'process raft request' (duration: 332.238316ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:25.604163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:25.271716Z","time spent":"332.40217ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T19:41:29.001632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.670179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:41:29.001744Z","caller":"traceutil/trace.go:171","msg":"trace[1404265996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"163.842024ms","start":"2026-01-29T19:41:28.837886Z","end":"2026-01-29T19:41:29.001728Z","steps":["trace[1404265996] 'range keys from in-memory index tree' (duration: 163.584347ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:41:29.888338Z","caller":"traceutil/trace.go:171","msg":"trace[1870027445] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"172.317608ms","start":"2026-01-29T19:41:29.715998Z","end":"2026-01-29T19:41:29.888315Z","steps":["trace[1870027445] 'process raft request' (duration: 172.180205ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:41:29.889103Z","caller":"traceutil/trace.go:171","msg":"trace[973873937] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:820; }","duration":"172.836779ms","start":"2026-01-29T19:41:29.716254Z","end":"2026-01-29T19:41:29.889091Z","steps":["trace[973873937] 'read index received' (duration: 172.831779ms)","trace[973873937] 'applied index is now lower than readState.Index' (duration: 4µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:41:29.889264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.991362ms","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-01-29T19:41:29.889304Z","caller":"traceutil/trace.go:171","msg":"trace[1843789525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:792; }","duration":"173.060934ms","start":"2026-01-29T19:41:29.71623Z","end":"2026-01-29T19:41:29.889291Z","steps":["trace[1843789525] 'agreement among raft nodes before linearized reading' (duration: 172.930791ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:41:30.245775Z","caller":"traceutil/trace.go:171","msg":"trace[1672336085] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"351.230231ms","start":"2026-01-29T19:41:29.894528Z","end":"2026-01-29T19:41:30.245759Z","steps":["trace[1672336085] 'process raft request' (duration: 351.19915ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:30.245883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:29.894509Z","time spent":"351.316483ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:41:30.246073Z","caller":"traceutil/trace.go:171","msg":"trace[890463838] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"396.013857ms","start":"2026-01-29T19:41:29.850051Z","end":"2026-01-29T19:41:30.246065Z","steps":["trace[890463838] 'process raft request' (duration: 303.805326ms)","trace[890463838] 'compare' (duration: 91.767542ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:41:30.246109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:29.850038Z","time spent":"396.050318ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:41:30.246185Z","caller":"traceutil/trace.go:171","msg":"trace[1605997451] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:820; }","duration":"357.036298ms","start":"2026-01-29T19:41:29.889143Z","end":"2026-01-29T19:41:30.246179Z","steps":["trace[1605997451] 'read index received' (duration: 264.630993ms)","trace[1605997451] 'applied index is now lower than readState.Index' (duration: 92.403835ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:41:30.246256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.960458ms","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-01-29T19:41:30.246273Z","caller":"traceutil/trace.go:171","msg":"trace[1574018670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:794; }","duration":"529.984579ms","start":"2026-01-29T19:41:29.716283Z","end":"2026-01-29T19:41:30.246268Z","steps":["trace[1574018670] 'agreement among raft nodes before linearized reading' (duration: 529.915857ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:30.246289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:29.71628Z","time spent":"530.005369ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-29T19:41:30.246409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.349166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:41:30.246426Z","caller":"traceutil/trace.go:171","msg":"trace[365143023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"408.395987ms","start":"2026-01-29T19:41:29.838024Z","end":"2026-01-29T19:41:30.24642Z","steps":["trace[365143023] 'agreement among raft nodes before linearized reading' (duration: 408.368836ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:30.246445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:29.838008Z","time spent":"408.433998ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T19:41:30.247126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.797346ms","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-01-29T19:41:30.24718Z","caller":"traceutil/trace.go:171","msg":"trace[506356701] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:794; }","duration":"530.868138ms","start":"2026-01-29T19:41:29.716298Z","end":"2026-01-29T19:41:30.247166Z","steps":["trace[506356701] 'agreement among raft nodes before linearized reading' (duration: 530.692884ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:30.24721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:29.716295Z","time spent":"530.907329ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-01-29T19:41:30.41427Z","caller":"traceutil/trace.go:171","msg":"trace[524649451] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"163.088776ms","start":"2026-01-29T19:41:30.251162Z","end":"2026-01-29T19:41:30.414251Z","steps":["trace[524649451] 'process raft request' (duration: 146.980995ms)","trace[524649451] 'compare' (duration: 15.968948ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:41:30.419108Z","caller":"traceutil/trace.go:171","msg":"trace[1111585133] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"165.605251ms","start":"2026-01-29T19:41:30.253493Z","end":"2026-01-29T19:41:30.419099Z","steps":["trace[1111585133] 'process raft request' (duration: 165.56208ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:41:42.888991Z","caller":"traceutil/trace.go:171","msg":"trace[1677494200] linearizableReadLoop","detail":"{readStateIndex:913; appliedIndex:912; }","duration":"407.277903ms","start":"2026-01-29T19:41:42.481697Z","end":"2026-01-29T19:41:42.888975Z","steps":["trace[1677494200] 'read index received' (duration: 407.15245ms)","trace[1677494200] 'applied index is now lower than readState.Index' (duration: 124.973µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:41:42.889076Z","caller":"traceutil/trace.go:171","msg":"trace[558494794] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"412.920926ms","start":"2026-01-29T19:41:42.476151Z","end":"2026-01-29T19:41:42.889072Z","steps":["trace[558494794] 'process raft request' (duration: 412.743382ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:42.889141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:42.476139Z","time spent":"412.952867ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T19:41:42.889252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.40438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:41:42.889351Z","caller":"traceutil/trace.go:171","msg":"trace[709170264] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:880; }","duration":"376.535032ms","start":"2026-01-29T19:41:42.512799Z","end":"2026-01-29T19:41:42.889334Z","steps":["trace[709170264] 'agreement among raft nodes before linearized reading' (duration: 376.394919ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:42.889394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:42.512777Z","time spent":"376.606524ms","remote":"127.0.0.1:53534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-01-29T19:41:42.889513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.813545ms","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-01-29T19:41:42.889633Z","caller":"traceutil/trace.go:171","msg":"trace[1183219953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:880; }","duration":"407.944897ms","start":"2026-01-29T19:41:42.481681Z","end":"2026-01-29T19:41:42.889625Z","steps":["trace[1183219953] 'agreement among raft nodes before linearized reading' (duration: 407.780564ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:42.889649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:42.481674Z","time spent":"407.970048ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-29T19:41:42.889678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.191946ms","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-01-29T19:41:42.889722Z","caller":"traceutil/trace.go:171","msg":"trace[209423063] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:880; }","duration":"338.269158ms","start":"2026-01-29T19:41:42.551443Z","end":"2026-01-29T19:41:42.889712Z","steps":["trace[209423063] 'agreement among raft nodes before linearized reading' (duration: 338.144705ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:42.889753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:42.55143Z","time spent":"338.316739ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-01-29T19:41:43.091455Z","caller":"traceutil/trace.go:171","msg":"trace[2092697600] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"193.068731ms","start":"2026-01-29T19:41:42.898347Z","end":"2026-01-29T19:41:43.091416Z","steps":["trace[2092697600] 'process raft request' (duration: 102.060417ms)","trace[2092697600] 'compare' (duration: 90.895362ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:41:43.095262Z","caller":"traceutil/trace.go:171","msg":"trace[750327898] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"195.397811ms","start":"2026-01-29T19:41:42.899848Z","end":"2026-01-29T19:41:43.095245Z","steps":["trace[750327898] 'process raft request' (duration: 195.32643ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:44.551239Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349191030869902,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-29T19:41:44.565629Z","caller":"traceutil/trace.go:171","msg":"trace[1383875056] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"628.036387ms","start":"2026-01-29T19:41:43.937362Z","end":"2026-01-29T19:41:44.565399Z","steps":["trace[1383875056] 'process raft request' (duration: 627.860843ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:44.565735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:43.937354Z","time spent":"628.315393ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:41:44.727973Z","caller":"traceutil/trace.go:171","msg":"trace[1428771006] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:915; }","duration":"677.708191ms","start":"2026-01-29T19:41:44.050245Z","end":"2026-01-29T19:41:44.727953Z","steps":["trace[1428771006] 'read index received' (duration: 515.032793ms)","trace[1428771006] 'applied index is now lower than readState.Index' (duration: 162.674888ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:41:44.728197Z","caller":"traceutil/trace.go:171","msg":"trace[729183492] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"226.681564ms","start":"2026-01-29T19:41:44.50151Z","end":"2026-01-29T19:41:44.728192Z","steps":["trace[729183492] 'process raft request' (duration: 221.615574ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:44.728327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.075569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T19:41:44.728343Z","caller":"traceutil/trace.go:171","msg":"trace[1839191007] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:884; }","duration":"678.13853ms","start":"2026-01-29T19:41:44.0502Z","end":"2026-01-29T19:41:44.728338Z","steps":["trace[1839191007] 'agreement among raft nodes before linearized reading' (duration: 678.096019ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:44.72836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:44.050181Z","time spent":"678.174901ms","remote":"127.0.0.1:53618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":29,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-01-29T19:41:44.728472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.932786ms","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-01-29T19:41:44.728486Z","caller":"traceutil/trace.go:171","msg":"trace[588167891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:884; }","duration":"418.972117ms","start":"2026-01-29T19:41:44.30951Z","end":"2026-01-29T19:41:44.728482Z","steps":["trace[588167891] 'agreement among raft nodes before linearized reading' (duration: 418.915706ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:44.728497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:44.309495Z","time spent":"418.999138ms","remote":"127.0.0.1:53504","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-01-29T19:41:51.382297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.441203ms","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-01-29T19:41:51.382341Z","caller":"traceutil/trace.go:171","msg":"trace[224554516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:903; }","duration":"256.519045ms","start":"2026-01-29T19:41:51.125811Z","end":"2026-01-29T19:41:51.38233Z","steps":["trace[224554516] 'range keys from in-memory index tree' (duration: 256.32738ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:51.382472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.600497ms","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-01-29T19:41:51.382485Z","caller":"traceutil/trace.go:171","msg":"trace[544717591] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:903; }","duration":"256.647037ms","start":"2026-01-29T19:41:51.125834Z","end":"2026-01-29T19:41:51.382481Z","steps":["trace[544717591] 'range keys from in-memory index tree' (duration: 256.547205ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:41:53.490832Z","caller":"traceutil/trace.go:171","msg":"trace[727759300] linearizableReadLoop","detail":"{readStateIndex:943; appliedIndex:942; }","duration":"100.935952ms","start":"2026-01-29T19:41:53.389882Z","end":"2026-01-29T19:41:53.490818Z","steps":["trace[727759300] 'read index received' (duration: 100.805579ms)","trace[727759300] 'applied index is now lower than readState.Index' (duration: 126.232µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:41:53.49101Z","caller":"traceutil/trace.go:171","msg":"trace[500539428] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"464.435029ms","start":"2026-01-29T19:41:53.026537Z","end":"2026-01-29T19:41:53.490972Z","steps":["trace[500539428] 'process raft request' (duration: 464.201254ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:53.491073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:53.026522Z","time spent":"464.50307ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T19:41:53.491216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.332631ms","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-01-29T19:41:53.491231Z","caller":"traceutil/trace.go:171","msg":"trace[1575028436] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:908; }","duration":"101.374971ms","start":"2026-01-29T19:41:53.389851Z","end":"2026-01-29T19:41:53.491226Z","steps":["trace[1575028436] 'agreement among raft nodes before linearized reading' (duration: 101.306579ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:54.199506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.566412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T19:41:54.19962Z","caller":"traceutil/trace.go:171","msg":"trace[1982385147] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:943; }","duration":"365.339867ms","start":"2026-01-29T19:41:53.834246Z","end":"2026-01-29T19:41:54.199586Z","steps":["trace[1982385147] 'read index received' (duration: 364.808536ms)","trace[1982385147] 'applied index is now lower than readState.Index' (duration: 530.551µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:41:54.19966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.412699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:41:54.199671Z","caller":"traceutil/trace.go:171","msg":"trace[1127730073] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:910; }","duration":"365.446459ms","start":"2026-01-29T19:41:53.834221Z","end":"2026-01-29T19:41:54.199667Z","steps":["trace[1127730073] 'agreement among raft nodes before linearized reading' (duration: 365.415368ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:54.199707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:53.834208Z","time spent":"365.4748ms","remote":"127.0.0.1:53240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-29T19:41:54.199857Z","caller":"traceutil/trace.go:171","msg":"trace[1651891740] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"705.190569ms","start":"2026-01-29T19:41:53.49466Z","end":"2026-01-29T19:41:54.199851Z","steps":["trace[1651891740] 'process raft request' (duration: 152.23583ms)","trace[1651891740] 'compare' (duration: 552.430808ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:41:54.199884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:53.494648Z","time spent":"705.221519ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:41:54.19994Z","caller":"traceutil/trace.go:171","msg":"trace[950840081] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"705.18331ms","start":"2026-01-29T19:41:53.494753Z","end":"2026-01-29T19:41:54.199936Z","steps":["trace[950840081] 'process raft request' (duration: 704.810781ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:41:54.199972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:41:53.494748Z","time spent":"705.20051ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:41:56.307012Z","caller":"traceutil/trace.go:171","msg":"trace[854194618] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"101.143736ms","start":"2026-01-29T19:41:56.205853Z","end":"2026-01-29T19:41:56.306997Z","steps":["trace[854194618] 'process raft request' (duration: 101.064904ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:41:56.754207Z","caller":"traceutil/trace.go:171","msg":"trace[476546111] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"100.84554ms","start":"2026-01-29T19:41:56.653346Z","end":"2026-01-29T19:41:56.754191Z","steps":["trace[476546111] 'process raft request' (duration: 100.703727ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:41:58.983601Z","caller":"traceutil/trace.go:171","msg":"trace[373198218] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:957; }","duration":"162.706339ms","start":"2026-01-29T19:41:58.820876Z","end":"2026-01-29T19:41:58.983582Z","steps":["trace[373198218] 'read index received' (duration: 162.571756ms)","trace[373198218] 'applied index is now lower than readState.Index' (duration: 133.893µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:41:58.98375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.859552ms","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-01-29T19:41:58.983771Z","caller":"traceutil/trace.go:171","msg":"trace[422821781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:922; }","duration":"162.938324ms","start":"2026-01-29T19:41:58.820826Z","end":"2026-01-29T19:41:58.983764Z","steps":["trace[422821781] 'agreement among raft nodes before linearized reading' (duration: 162.827261ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:41:58.983839Z","caller":"traceutil/trace.go:171","msg":"trace[1025867602] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"222.157175ms","start":"2026-01-29T19:41:58.761663Z","end":"2026-01-29T19:41:58.98382Z","steps":["trace[1025867602] 'process raft request' (duration: 221.808497ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:42:00.681815Z","caller":"traceutil/trace.go:171","msg":"trace[433697981] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"400.74357ms","start":"2026-01-29T19:42:00.281053Z","end":"2026-01-29T19:42:00.681797Z","steps":["trace[433697981] 'process raft request' (duration: 400.648118ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:42:00.681897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:42:00.281038Z","time spent":"400.813261ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:42:00.683289Z","caller":"traceutil/trace.go:171","msg":"trace[2081439349] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:961; }","duration":"368.789314ms","start":"2026-01-29T19:42:00.31449Z","end":"2026-01-29T19:42:00.683279Z","steps":["trace[2081439349] 'read index received' (duration: 368.787014ms)","trace[2081439349] 'applied index is now lower than readState.Index' (duration: 1.8µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:42:00.683854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.365136ms","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-01-29T19:42:00.683905Z","caller":"traceutil/trace.go:171","msg":"trace[13078389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:925; }","duration":"369.443418ms","start":"2026-01-29T19:42:00.314455Z","end":"2026-01-29T19:42:00.683898Z","steps":["trace[13078389] 'agreement among raft nodes before linearized reading' (duration: 368.886916ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:42:00.684017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:42:00.314435Z","time spent":"369.54178ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-29T19:42:00.935716Z","caller":"traceutil/trace.go:171","msg":"trace[294043847] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"249.387209ms","start":"2026-01-29T19:42:00.686313Z","end":"2026-01-29T19:42:00.9357Z","steps":["trace[294043847] 'process raft request' (duration: 249.358588ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:42:00.935773Z","caller":"traceutil/trace.go:171","msg":"trace[8213176] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"258.750103ms","start":"2026-01-29T19:42:00.676993Z","end":"2026-01-29T19:42:00.935743Z","steps":["trace[8213176] 'process raft request' (duration: 256.891502ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:42:00.935949Z","caller":"traceutil/trace.go:171","msg":"trace[663659263] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"252.614629ms","start":"2026-01-29T19:42:00.683328Z","end":"2026-01-29T19:42:00.935942Z","steps":["trace[663659263] 'read index received' (duration: 250.565034ms)","trace[663659263] 'applied index is now lower than readState.Index' (duration: 2.048745ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:42:00.936068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.252049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.43\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-29T19:42:00.936086Z","caller":"traceutil/trace.go:171","msg":"trace[1424572232] range","detail":"{range_begin:/registry/masterleases/162.253.55.43; range_end:; response_count:1; response_revision:927; }","duration":"483.30189ms","start":"2026-01-29T19:42:00.452777Z","end":"2026-01-29T19:42:00.936079Z","steps":["trace[1424572232] 'agreement among raft nodes before linearized reading' (duration: 483.221298ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:42:00.936102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:42:00.452765Z","time spent":"483.333ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.43\" "} {"level":"info","ts":"2026-01-29T19:42:03.961177Z","caller":"traceutil/trace.go:171","msg":"trace[1243543048] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"429.131409ms","start":"2026-01-29T19:42:03.532031Z","end":"2026-01-29T19:42:03.961162Z","steps":["trace[1243543048] 'process raft request' (duration: 429.036697ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:42:03.961271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:42:03.53202Z","time spent":"429.2006ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:42:05.125202Z","caller":"traceutil/trace.go:171","msg":"trace[1503144800] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"406.682261ms","start":"2026-01-29T19:42:04.718495Z","end":"2026-01-29T19:42:05.125177Z","steps":["trace[1503144800] 'process raft request' (duration: 406.543088ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:42:05.125381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:42:04.718486Z","time spent":"406.788303ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:42:05.126062Z","caller":"traceutil/trace.go:171","msg":"trace[887002170] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:994; }","duration":"291.371716ms","start":"2026-01-29T19:42:04.834677Z","end":"2026-01-29T19:42:05.126049Z","steps":["trace[887002170] 'read index received' (duration: 291.365846ms)","trace[887002170] 'applied index is now lower than readState.Index' (duration: 5.02µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:42:05.126162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.476668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:42:05.126198Z","caller":"traceutil/trace.go:171","msg":"trace[60971539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:957; }","duration":"291.539139ms","start":"2026-01-29T19:42:04.83465Z","end":"2026-01-29T19:42:05.126189Z","steps":["trace[60971539] 'agreement among raft nodes before linearized reading' (duration: 291.476648ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:42:05.126651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.928924ms","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-01-29T19:42:05.126707Z","caller":"traceutil/trace.go:171","msg":"trace[2111176005] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:958; }","duration":"229.027356ms","start":"2026-01-29T19:42:04.897666Z","end":"2026-01-29T19:42:05.126694Z","steps":["trace[2111176005] 'agreement among raft nodes before linearized reading' (duration: 228.903373ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:42:05.126949Z","caller":"traceutil/trace.go:171","msg":"trace[1811619451] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"227.121064ms","start":"2026-01-29T19:42:04.899821Z","end":"2026-01-29T19:42:05.126942Z","steps":["trace[1811619451] 'process raft request' (duration: 226.634344ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:42:05.127101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.277643ms","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-01-29T19:42:05.12712Z","caller":"traceutil/trace.go:171","msg":"trace[1482450510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:958; }","duration":"109.312134ms","start":"2026-01-29T19:42:05.017802Z","end":"2026-01-29T19:42:05.127114Z","steps":["trace[1482450510] 'agreement among raft nodes before linearized reading' (duration: 109.239032ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:42:05.127235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.518778ms","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-01-29T19:42:05.127253Z","caller":"traceutil/trace.go:171","msg":"trace[68481963] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:958; }","duration":"125.59964ms","start":"2026-01-29T19:42:05.001648Z","end":"2026-01-29T19:42:05.127247Z","steps":["trace[68481963] 'agreement among raft nodes before linearized reading' (duration: 125.535468ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:42:05.127353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.446838ms","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-01-29T19:42:05.127369Z","caller":"traceutil/trace.go:171","msg":"trace[1356160430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:958; }","duration":"170.483599ms","start":"2026-01-29T19:42:04.956881Z","end":"2026-01-29T19:42:05.127365Z","steps":["trace[1356160430] 'agreement among raft nodes before linearized reading' (duration: 170.438378ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:42:15.67376Z","caller":"traceutil/trace.go:171","msg":"trace[1654242606] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1070; }","duration":"237.742686ms","start":"2026-01-29T19:42:15.436003Z","end":"2026-01-29T19:42:15.673745Z","steps":["trace[1654242606] 'read index received' (duration: 236.812886ms)","trace[1654242606] 'applied index is now lower than readState.Index' (duration: 929.25µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:42:15.673872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.854709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:42:15.673898Z","caller":"traceutil/trace.go:171","msg":"trace[1519344415] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1032; }","duration":"237.90825ms","start":"2026-01-29T19:42:15.435975Z","end":"2026-01-29T19:42:15.673883Z","steps":["trace[1519344415] 'agreement among raft nodes before linearized reading' (duration: 237.826868ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:42:15.674061Z","caller":"traceutil/trace.go:171","msg":"trace[505942765] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"307.787149ms","start":"2026-01-29T19:42:15.366268Z","end":"2026-01-29T19:42:15.674055Z","steps":["trace[505942765] 'process raft request' (duration: 306.616713ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:42:15.674117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:42:15.366247Z","time spent":"307.83803ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:42:16.966576Z","caller":"traceutil/trace.go:171","msg":"trace[627128612] linearizableReadLoop","detail":"{readStateIndex:1076; appliedIndex:1075; }","duration":"144.307108ms","start":"2026-01-29T19:42:16.822249Z","end":"2026-01-29T19:42:16.966556Z","steps":["trace[627128612] 'read index received' (duration: 144.136524ms)","trace[627128612] 'applied index is now lower than readState.Index' (duration: 169.414µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:42:16.966969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.692467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-01-29T19:42:16.96733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.754846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:42:16.967379Z","caller":"traceutil/trace.go:171","msg":"trace[164375569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1036; }","duration":"128.803907ms","start":"2026-01-29T19:42:16.838565Z","end":"2026-01-29T19:42:16.967369Z","steps":["trace[164375569] 'agreement among raft nodes before linearized reading' (duration: 128.736706ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:42:16.967662Z","caller":"traceutil/trace.go:171","msg":"trace[446609081] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"307.581995ms","start":"2026-01-29T19:42:16.660069Z","end":"2026-01-29T19:42:16.967651Z","steps":["trace[446609081] 'process raft request' (duration: 306.366348ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:42:16.967015Z","caller":"traceutil/trace.go:171","msg":"trace[2139663068] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1036; }","duration":"144.758748ms","start":"2026-01-29T19:42:16.822244Z","end":"2026-01-29T19:42:16.967002Z","steps":["trace[2139663068] 'agreement among raft nodes before linearized reading' (duration: 144.639386ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:42:16.967747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:42:16.660051Z","time spent":"307.635916ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T19:42:17.278066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.577872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:42:17.342076Z","caller":"traceutil/trace.go:171","msg":"trace[158654762] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1036; }","duration":"197.673845ms","start":"2026-01-29T19:42:17.080434Z","end":"2026-01-29T19:42:17.278108Z","steps":["trace[158654762] 'count revisions from in-memory index tree' (duration: 197.512901ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:42:18.353981Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.068354453s","expected-duration":"1s"} {"level":"info","ts":"2026-01-29T19:42:18.366855Z","caller":"traceutil/trace.go:171","msg":"trace[1738228182] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"1.081288306s","start":"2026-01-29T19:42:17.28555Z","end":"2026-01-29T19:42:18.366838Z","steps":["trace[1738228182] 'process raft request' (duration: 1.081148873s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:42:18.367025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:42:17.285531Z","time spent":"1.081359148s","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T19:42:19.37477Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.008180896s","expected-duration":"1s"} {"level":"warn","ts":"2026-01-29T19:42:19.484177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.215925ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T19:42:19.484297Z","caller":"traceutil/trace.go:171","msg":"trace[373812257] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1077; }","duration":"1.464219181s","start":"2026-01-29T19:42:18.020069Z","end":"2026-01-29T19:42:19.484288Z","steps":["trace[373812257] 'read index received' (duration: 347.355712ms)","trace[373812257] 'applied index is now lower than readState.Index' (duration: 1.116862219s)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:42:19.484444Z","caller":"traceutil/trace.go:171","msg":"trace[877426494] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"2.187254046s","start":"2026-01-29T19:42:17.297183Z","end":"2026-01-29T19:42:19.484438Z","steps":["trace[877426494] 'process raft request' (duration: 2.077675753s)","trace[877426494] 'compare' (duration: 108.614072ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:42:19.48449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:42:17.297167Z","time spent":"2.187298768s","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:42:19.48462Z","caller":"traceutil/trace.go:171","msg":"trace[1607752155] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"2.142719165s","start":"2026-01-29T19:42:17.341894Z","end":"2026-01-29T19:42:19.484613Z","steps":["trace[1607752155] 'process raft request' (duration: 2.142336227s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:42:19.484706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:42:17.341881Z","time spent":"2.142807048s","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:42:19.484782Z","caller":"traceutil/trace.go:171","msg":"trace[1138414478] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"2.141959928s","start":"2026-01-29T19:42:17.342818Z","end":"2026-01-29T19:42:19.484778Z","steps":["trace[1138414478] 'process raft request' (duration: 2.141439796s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:42:19.48481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:42:17.342802Z","time spent":"2.141994929s","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T19:42:19.484934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.464855185s","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-01-29T19:42:19.484952Z","caller":"traceutil/trace.go:171","msg":"trace[1008605374] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1040; }","duration":"1.464903836s","start":"2026-01-29T19:42:18.020043Z","end":"2026-01-29T19:42:19.484947Z","steps":["trace[1008605374] 'agreement among raft nodes before linearized reading' (duration: 1.464828105s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:42:19.484966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:42:18.020031Z","time spent":"1.464932337s","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-29T19:42:19.485069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.245493602s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T19:42:19.485087Z","caller":"traceutil/trace.go:171","msg":"trace[235423812] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1040; }","duration":"1.245543143s","start":"2026-01-29T19:42:18.23954Z","end":"2026-01-29T19:42:19.485083Z","steps":["trace[235423812] 'agreement among raft nodes before linearized reading' (duration: 1.245491192s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:42:19.485099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:42:18.239524Z","time spent":"1.245571904s","remote":"127.0.0.1:53596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-01-29T19:42:19.485203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.863395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-01-29T19:42:19.485324Z","caller":"traceutil/trace.go:171","msg":"trace[1826372457] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1040; }","duration":"511.984797ms","start":"2026-01-29T19:42:18.973334Z","end":"2026-01-29T19:42:19.485319Z","steps":["trace[1826372457] 'agreement among raft nodes before linearized reading' (duration: 511.838044ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:42:19.485355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:42:18.973299Z","time spent":"512.051649ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-01-29T19:42:19.485433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.66043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:42:19.48545Z","caller":"traceutil/trace.go:171","msg":"trace[478637920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1040; }","duration":"644.702841ms","start":"2026-01-29T19:42:18.840743Z","end":"2026-01-29T19:42:19.485446Z","steps":["trace[478637920] 'agreement among raft nodes before linearized reading' (duration: 644.677621ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:42:19.485462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:42:18.840728Z","time spent":"644.731241ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T19:42:19.48648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.13463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-01-29T19:42:19.486503Z","caller":"traceutil/trace.go:171","msg":"trace[2039247838] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1040; }","duration":"878.192152ms","start":"2026-01-29T19:42:18.608305Z","end":"2026-01-29T19:42:19.486497Z","steps":["trace[2039247838] 'agreement among raft nodes before linearized reading' (duration: 878.12033ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:42:19.486519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:42:18.60826Z","time spent":"878.254924ms","remote":"127.0.0.1:53504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-01-29T19:43:05.929089Z","caller":"traceutil/trace.go:171","msg":"trace[214868308] linearizableReadLoop","detail":"{readStateIndex:1280; appliedIndex:1279; }","duration":"105.200667ms","start":"2026-01-29T19:43:05.82387Z","end":"2026-01-29T19:43:05.929071Z","steps":["trace[214868308] 'read index received' (duration: 17.466014ms)","trace[214868308] 'applied index is now lower than readState.Index' (duration: 87.733553ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:43:05.929471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.590226ms","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-01-29T19:43:05.929585Z","caller":"traceutil/trace.go:171","msg":"trace[35903855] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1230; }","duration":"105.735739ms","start":"2026-01-29T19:43:05.823838Z","end":"2026-01-29T19:43:05.929574Z","steps":["trace[35903855] 'agreement among raft nodes before linearized reading' (duration: 105.441173ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:43:08.066702Z","caller":"traceutil/trace.go:171","msg":"trace[224358098] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1287; }","duration":"218.290898ms","start":"2026-01-29T19:43:07.848395Z","end":"2026-01-29T19:43:08.066686Z","steps":["trace[224358098] 'read index received' (duration: 218.192186ms)","trace[224358098] 'applied index is now lower than readState.Index' (duration: 98.002µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:43:08.066888Z","caller":"traceutil/trace.go:171","msg":"trace[45425805] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"270.897027ms","start":"2026-01-29T19:43:07.795973Z","end":"2026-01-29T19:43:08.06687Z","steps":["trace[45425805] 'process raft request' (duration: 270.613211ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:43:08.067527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.633395ms","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-01-29T19:43:08.067573Z","caller":"traceutil/trace.go:171","msg":"trace[1685412615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1238; }","duration":"119.732647ms","start":"2026-01-29T19:43:07.947829Z","end":"2026-01-29T19:43:08.067561Z","steps":["trace[1685412615] 'agreement among raft nodes before linearized reading' (duration: 119.599925ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:43:08.06777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.365182ms","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-01-29T19:43:08.069706Z","caller":"traceutil/trace.go:171","msg":"trace[330850504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1238; }","duration":"221.353535ms","start":"2026-01-29T19:43:07.848335Z","end":"2026-01-29T19:43:08.069688Z","steps":["trace[330850504] 'agreement among raft nodes before linearized reading' (duration: 218.549974ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:43:24.478541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.983041ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T19:43:24.478722Z","caller":"traceutil/trace.go:171","msg":"trace[102447498] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"221.781272ms","start":"2026-01-29T19:43:24.256926Z","end":"2026-01-29T19:43:24.478707Z","steps":["trace[102447498] 'process raft request' (duration: 221.749391ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:43:24.478988Z","caller":"traceutil/trace.go:171","msg":"trace[318863500] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"223.182953ms","start":"2026-01-29T19:43:24.255793Z","end":"2026-01-29T19:43:24.478975Z","steps":["trace[318863500] 'process raft request' (duration: 106.695491ms)","trace[318863500] 'compare' (duration: 115.678544ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:43:24.4791Z","caller":"traceutil/trace.go:171","msg":"trace[1164821530] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"223.128931ms","start":"2026-01-29T19:43:24.255962Z","end":"2026-01-29T19:43:24.479091Z","steps":["trace[1164821530] 'process raft request' (duration: 222.668491ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:45:21.637201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.522894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-01-29T19:45:21.637261Z","caller":"traceutil/trace.go:171","msg":"trace[487532222] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1811; }","duration":"135.623296ms","start":"2026-01-29T19:45:21.501619Z","end":"2026-01-29T19:45:21.637242Z","steps":["trace[487532222] 'range keys from in-memory index tree' (duration: 135.383261ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:45:27.465875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.73506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T19:45:27.466409Z","caller":"traceutil/trace.go:171","msg":"trace[835331237] linearizableReadLoop","detail":"{readStateIndex:1919; appliedIndex:1918; }","duration":"233.886936ms","start":"2026-01-29T19:45:27.232502Z","end":"2026-01-29T19:45:27.466389Z","steps":["trace[835331237] 'read index received' (duration: 69.461151ms)","trace[835331237] 'applied index is now lower than readState.Index' (duration: 164.424055ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:45:27.466462Z","caller":"traceutil/trace.go:171","msg":"trace[585963490] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"297.787671ms","start":"2026-01-29T19:45:27.168646Z","end":"2026-01-29T19:45:27.466434Z","steps":["trace[585963490] 'process raft request' (duration: 133.402127ms)","trace[585963490] 'compare' (duration: 163.589397ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:45:27.46704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.51716ms","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-01-29T19:45:27.467553Z","caller":"traceutil/trace.go:171","msg":"trace[1029834475] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1840; }","duration":"235.073593ms","start":"2026-01-29T19:45:27.232461Z","end":"2026-01-29T19:45:27.467535Z","steps":["trace[1029834475] 'agreement among raft nodes before linearized reading' (duration: 234.123212ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:45:46.4732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.790767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/default/\" range_end:\"/registry/jobs/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:45:46.473307Z","caller":"traceutil/trace.go:171","msg":"trace[1360688495] range","detail":"{range_begin:/registry/jobs/default/; range_end:/registry/jobs/default0; response_count:0; response_revision:1923; }","duration":"105.952031ms","start":"2026-01-29T19:45:46.367324Z","end":"2026-01-29T19:45:46.473276Z","steps":["trace[1360688495] 'range keys from in-memory index tree' (duration: 105.720935ms)"],"step_count":1}