{"level":"warn","ts":"2026-02-28T01:29:44.948725Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-28T01:29:44.948837Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.70:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.70:2380","--initial-cluster=instance=https://162.253.55.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.70:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-28T01:29:44.9489Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-28T01:29:44.948913Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-02-28T01:29:44.948929Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-28T01:29:44.949529Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"]} {"level":"info","ts":"2026-02-28T01:29:44.949598Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.70:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-28T01:29:44.953132Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.282167ms"} {"level":"info","ts":"2026-02-28T01:29:44.958007Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"23887246632736aa","cluster-id":"62cfeca3ef46d3ef"} {"level":"info","ts":"2026-02-28T01:29:44.958181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=()"} {"level":"info","ts":"2026-02-28T01:29:44.958357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 0"} {"level":"info","ts":"2026-02-28T01:29:44.958376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 23887246632736aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-28T01:29:44.958447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 1"} {"level":"info","ts":"2026-02-28T01:29:44.958839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"warn","ts":"2026-02-28T01:29:44.964617Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-28T01:29:44.966791Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-28T01:29:44.967848Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-28T01:29:44.970852Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"23887246632736aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-28T01:29:44.970961Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"23887246632736aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-28T01:29:44.97134Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-28T01:29:44.971435Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-28T01:29:44.971447Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-28T01:29:44.972176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"info","ts":"2026-02-28T01:29:44.972391Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","added-peer-id":"23887246632736aa","added-peer-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-02-28T01:29:44.979577Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-28T01:29:44.979793Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-02-28T01:29:44.979863Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-02-28T01:29:44.981447Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"23887246632736aa","initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-28T01:29:44.981502Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-28T01:29:45.259276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa is starting a new election at term 1"} {"level":"info","ts":"2026-02-28T01:29:45.259358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became pre-candidate at term 1"} {"level":"info","ts":"2026-02-28T01:29:45.259588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgPreVoteResp from 23887246632736aa at term 1"} {"level":"info","ts":"2026-02-28T01:29:45.259662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became candidate at term 2"} {"level":"info","ts":"2026-02-28T01:29:45.259682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgVoteResp from 23887246632736aa at term 2"} {"level":"info","ts":"2026-02-28T01:29:45.259765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became leader at term 2"} {"level":"info","ts":"2026-02-28T01:29:45.259823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 23887246632736aa elected leader 23887246632736aa at term 2"} {"level":"info","ts":"2026-02-28T01:29:45.261009Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"23887246632736aa","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.70:2379]}","request-path":"/0/members/23887246632736aa/attributes","cluster-id":"62cfeca3ef46d3ef","publish-timeout":"7s"} {"level":"info","ts":"2026-02-28T01:29:45.261093Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-28T01:29:45.261408Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-28T01:29:45.261477Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-28T01:29:45.262232Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","cluster-version":"3.5"} {"level":"info","ts":"2026-02-28T01:29:45.262429Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-28T01:29:45.262528Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-28T01:29:45.263109Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-28T01:29:45.263202Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-28T01:29:45.267131Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-28T01:29:45.268398Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.70:2379"} {"level":"info","ts":"2026-02-28T01:30:20.699735Z","caller":"traceutil/trace.go:171","msg":"trace[320008209] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"157.955565ms","start":"2026-02-28T01:30:20.541756Z","end":"2026-02-28T01:30:20.699711Z","steps":["trace[320008209] 'process raft request' (duration: 157.569157ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:30:21.156224Z","caller":"traceutil/trace.go:171","msg":"trace[961068480] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"270.887245ms","start":"2026-02-28T01:30:20.885309Z","end":"2026-02-28T01:30:21.156196Z","steps":["trace[961068480] 'process raft request' (duration: 270.722092ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:30:21.536529Z","caller":"traceutil/trace.go:171","msg":"trace[219852602] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"298.594256ms","start":"2026-02-28T01:30:21.23791Z","end":"2026-02-28T01:30:21.536504Z","steps":["trace[219852602] 'process raft request' (duration: 298.473912ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:30:21.946478Z","caller":"traceutil/trace.go:171","msg":"trace[1398660655] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:543; }","duration":"666.339995ms","start":"2026-02-28T01:30:21.280114Z","end":"2026-02-28T01:30:21.946454Z","steps":["trace[1398660655] 'read index received' (duration: 256.58579ms)","trace[1398660655] 'applied index is now lower than readState.Index' (duration: 409.753175ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:30:21.946567Z","caller":"traceutil/trace.go:171","msg":"trace[1385299220] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"688.49532ms","start":"2026-02-28T01:30:21.25801Z","end":"2026-02-28T01:30:21.946505Z","steps":["trace[1385299220] 'process raft request' (duration: 688.251824ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:30:21.946877Z","caller":"traceutil/trace.go:171","msg":"trace[1927599413] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"686.164877ms","start":"2026-02-28T01:30:21.260692Z","end":"2026-02-28T01:30:21.946857Z","steps":["trace[1927599413] 'process raft request' (duration: 685.714877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:21.946964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.838157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-28T01:30:21.947046Z","caller":"traceutil/trace.go:171","msg":"trace[1822211027] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:528; }","duration":"666.97173ms","start":"2026-02-28T01:30:21.28006Z","end":"2026-02-28T01:30:21.947032Z","steps":["trace[1822211027] 'agreement among raft nodes before linearized reading' (duration: 666.774175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:21.947073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.316169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-28T01:30:21.94709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.103755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:30:21.947156Z","caller":"traceutil/trace.go:171","msg":"trace[502839325] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:528; }","duration":"327.213427ms","start":"2026-02-28T01:30:21.619929Z","end":"2026-02-28T01:30:21.947142Z","steps":["trace[502839325] 'agreement among raft nodes before linearized reading' (duration: 327.105705ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:30:21.947156Z","caller":"traceutil/trace.go:171","msg":"trace[1266505298] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:528; }","duration":"334.460942ms","start":"2026-02-28T01:30:21.612675Z","end":"2026-02-28T01:30:21.947135Z","steps":["trace[1266505298] 'agreement among raft nodes before linearized reading' (duration: 334.167615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:21.947187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:21.619919Z","time spent":"327.260948ms","remote":"127.0.0.1:52922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-28T01:30:21.947213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:21.612653Z","time spent":"334.550543ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-28T01:30:21.947081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:21.280041Z","time spent":"667.030991ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-28T01:30:21.947363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:21.260683Z","time spent":"686.241948ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:30:21.947359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:21.258001Z","time spent":"688.646233ms","remote":"127.0.0.1:53092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4744,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:30:42.203076Z","caller":"traceutil/trace.go:171","msg":"trace[666424758] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"131.8524ms","start":"2026-02-28T01:30:42.071188Z","end":"2026-02-28T01:30:42.203041Z","steps":["trace[666424758] 'process raft request' (duration: 131.649296ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:30:42.828564Z","caller":"traceutil/trace.go:171","msg":"trace[1615436591] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"418.631708ms","start":"2026-02-28T01:30:42.40991Z","end":"2026-02-28T01:30:42.828542Z","steps":["trace[1615436591] 'process raft request' (duration: 418.459864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:42.82869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:42.409882Z","time spent":"418.74043ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:30:44.042342Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133043111560447,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-28T01:30:44.215513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.436386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-28T01:30:44.21577Z","caller":"traceutil/trace.go:171","msg":"trace[891898531] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"686.014613ms","start":"2026-02-28T01:30:43.529722Z","end":"2026-02-28T01:30:44.215737Z","steps":["trace[891898531] 'process raft request' (duration: 555.600615ms)","trace[891898531] 'compare' (duration: 129.317003ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:30:44.215918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:43.529708Z","time spent":"686.140835ms","remote":"127.0.0.1:52990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":811,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-28T01:30:44.311891Z","caller":"traceutil/trace.go:171","msg":"trace[417341098] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:743; }","duration":"769.853961ms","start":"2026-02-28T01:30:43.542006Z","end":"2026-02-28T01:30:44.31186Z","steps":["trace[417341098] 'read index received' (duration: 543.331826ms)","trace[417341098] 'applied index is now lower than readState.Index' (duration: 226.520515ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:30:44.31219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.167229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-28T01:30:44.3122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.067351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:30:44.312256Z","caller":"traceutil/trace.go:171","msg":"trace[1503930832] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:721; }","duration":"770.278511ms","start":"2026-02-28T01:30:43.54196Z","end":"2026-02-28T01:30:44.312239Z","steps":["trace[1503930832] 'agreement among raft nodes before linearized reading' (duration: 770.058696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:44.312258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.396634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-28T01:30:44.312296Z","caller":"traceutil/trace.go:171","msg":"trace[1815941145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:721; }","duration":"692.179203ms","start":"2026-02-28T01:30:43.620094Z","end":"2026-02-28T01:30:44.312273Z","steps":["trace[1815941145] 'agreement among raft nodes before linearized reading' (duration: 692.000309ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:30:44.312355Z","caller":"traceutil/trace.go:171","msg":"trace[869952504] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:721; }","duration":"241.538687ms","start":"2026-02-28T01:30:44.070775Z","end":"2026-02-28T01:30:44.312313Z","steps":["trace[869952504] 'agreement among raft nodes before linearized reading' (duration: 241.322012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:44.312359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.82528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-28T01:30:44.312309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:43.541942Z","time spent":"770.353953ms","remote":"127.0.0.1:53192","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-02-28T01:30:44.312385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:43.620062Z","time spent":"692.314107ms","remote":"127.0.0.1:52922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-28T01:30:44.3124Z","caller":"traceutil/trace.go:171","msg":"trace[559961690] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:721; }","duration":"102.902482ms","start":"2026-02-28T01:30:44.209486Z","end":"2026-02-28T01:30:44.312388Z","steps":["trace[559961690] 'agreement among raft nodes before linearized reading' (duration: 102.770519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:44.312447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.99629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-28T01:30:44.312506Z","caller":"traceutil/trace.go:171","msg":"trace[970606903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:721; }","duration":"685.068792ms","start":"2026-02-28T01:30:43.627422Z","end":"2026-02-28T01:30:44.31249Z","steps":["trace[970606903] 'agreement among raft nodes before linearized reading' (duration: 684.893188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:44.312539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:43.627413Z","time spent":"685.118013ms","remote":"127.0.0.1:53192","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-02-28T01:30:47.226325Z","caller":"traceutil/trace.go:171","msg":"trace[663292500] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:757; }","duration":"287.670258ms","start":"2026-02-28T01:30:46.938632Z","end":"2026-02-28T01:30:47.226303Z","steps":["trace[663292500] 'read index received' (duration: 287.605466ms)","trace[663292500] 'applied index is now lower than readState.Index' (duration: 63.972µs)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:30:47.226394Z","caller":"traceutil/trace.go:171","msg":"trace[728503081] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"299.992148ms","start":"2026-02-28T01:30:46.926369Z","end":"2026-02-28T01:30:47.226361Z","steps":["trace[728503081] 'process raft request' (duration: 299.813964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:47.22652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.843831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-28T01:30:47.226559Z","caller":"traceutil/trace.go:171","msg":"trace[1480949537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:734; }","duration":"287.948654ms","start":"2026-02-28T01:30:46.9386Z","end":"2026-02-28T01:30:47.226549Z","steps":["trace[1480949537] 'agreement among raft nodes before linearized reading' (duration: 287.792121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:30:47.226557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:30:46.92636Z","time spent":"300.11245ms","remote":"127.0.0.1:53106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5053,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:31:00.633783Z","caller":"traceutil/trace.go:171","msg":"trace[1123765786] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"116.517356ms","start":"2026-02-28T01:31:00.51725Z","end":"2026-02-28T01:31:00.633767Z","steps":["trace[1123765786] 'process raft request' (duration: 116.432084ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:00.647986Z","caller":"traceutil/trace.go:171","msg":"trace[748605760] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:801; }","duration":"129.094914ms","start":"2026-02-28T01:31:00.51887Z","end":"2026-02-28T01:31:00.647964Z","steps":["trace[748605760] 'read index received' (duration: 114.94667ms)","trace[748605760] 'applied index is now lower than readState.Index' (duration: 14.147184ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:31:00.648275Z","caller":"traceutil/trace.go:171","msg":"trace[186009947] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"130.004593ms","start":"2026-02-28T01:31:00.518259Z","end":"2026-02-28T01:31:00.648263Z","steps":["trace[186009947] 'process raft request' (duration: 129.584294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:00.64859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.706827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-28T01:31:00.648634Z","caller":"traceutil/trace.go:171","msg":"trace[359929902] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:775; }","duration":"129.774148ms","start":"2026-02-28T01:31:00.518848Z","end":"2026-02-28T01:31:00.648622Z","steps":["trace[359929902] 'agreement among raft nodes before linearized reading' (duration: 129.615705ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:08.639128Z","caller":"traceutil/trace.go:171","msg":"trace[882391524] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"187.729455ms","start":"2026-02-28T01:31:08.451362Z","end":"2026-02-28T01:31:08.639091Z","steps":["trace[882391524] 'process raft request' (duration: 187.54269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:08.996891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.50191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T01:31:08.997148Z","caller":"traceutil/trace.go:171","msg":"trace[554280145] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:822; }","duration":"260.998561ms","start":"2026-02-28T01:31:08.736132Z","end":"2026-02-28T01:31:08.997131Z","steps":["trace[554280145] 'read index received' (duration: 52.162584ms)","trace[554280145] 'applied index is now lower than readState.Index' (duration: 208.834887ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:31:08.997205Z","caller":"traceutil/trace.go:171","msg":"trace[2130872142] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"262.75611ms","start":"2026-02-28T01:31:08.734402Z","end":"2026-02-28T01:31:08.997158Z","steps":["trace[2130872142] 'process raft request' (duration: 262.666448ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:08.997322Z","caller":"traceutil/trace.go:171","msg":"trace[206116215] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"316.257325ms","start":"2026-02-28T01:31:08.68104Z","end":"2026-02-28T01:31:08.997297Z","steps":["trace[206116215] 'process raft request' (duration: 107.241002ms)","trace[206116215] 'compare' (duration: 208.382127ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:31:08.997409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:08.681015Z","time spent":"316.352777ms","remote":"127.0.0.1:53192","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-02-28T01:31:08.997481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.266731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-28T01:31:08.997605Z","caller":"traceutil/trace.go:171","msg":"trace[2005009326] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:796; }","duration":"203.437055ms","start":"2026-02-28T01:31:08.79414Z","end":"2026-02-28T01:31:08.997577Z","steps":["trace[2005009326] 'agreement among raft nodes before linearized reading' (duration: 203.145388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:08.997672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.638787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-28T01:31:08.997837Z","caller":"traceutil/trace.go:171","msg":"trace[1875192999] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:796; }","duration":"251.809481ms","start":"2026-02-28T01:31:08.746003Z","end":"2026-02-28T01:31:08.997812Z","steps":["trace[1875192999] 'agreement among raft nodes before linearized reading' (duration: 251.274409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:08.997898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.768269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-28T01:31:08.997945Z","caller":"traceutil/trace.go:171","msg":"trace[11122253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:796; }","duration":"261.842801ms","start":"2026-02-28T01:31:08.736091Z","end":"2026-02-28T01:31:08.997934Z","steps":["trace[11122253] 'agreement among raft nodes before linearized reading' (duration: 261.130244ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:09.178815Z","caller":"traceutil/trace.go:171","msg":"trace[1882944835] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"175.483264ms","start":"2026-02-28T01:31:09.003305Z","end":"2026-02-28T01:31:09.178788Z","steps":["trace[1882944835] 'process raft request' (duration: 175.398102ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:09.179024Z","caller":"traceutil/trace.go:171","msg":"trace[1680237624] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"177.394838ms","start":"2026-02-28T01:31:09.001613Z","end":"2026-02-28T01:31:09.179008Z","steps":["trace[1680237624] 'process raft request' (duration: 176.923768ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:09.188576Z","caller":"traceutil/trace.go:171","msg":"trace[1771965714] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"149.174682ms","start":"2026-02-28T01:31:09.039377Z","end":"2026-02-28T01:31:09.188551Z","steps":["trace[1771965714] 'process raft request' (duration: 149.043909ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:18.596572Z","caller":"traceutil/trace.go:171","msg":"trace[858610441] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"275.547663ms","start":"2026-02-28T01:31:18.320997Z","end":"2026-02-28T01:31:18.596545Z","steps":["trace[858610441] 'process raft request' (duration: 275.416771ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:18.841119Z","caller":"traceutil/trace.go:171","msg":"trace[1823339038] linearizableReadLoop","detail":"{readStateIndex:859; appliedIndex:856; }","duration":"154.702729ms","start":"2026-02-28T01:31:18.686394Z","end":"2026-02-28T01:31:18.841097Z","steps":["trace[1823339038] 'read index received' (duration: 65.882578ms)","trace[1823339038] 'applied index is now lower than readState.Index' (duration: 88.818971ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:31:18.841194Z","caller":"traceutil/trace.go:171","msg":"trace[362602544] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"175.173377ms","start":"2026-02-28T01:31:18.665991Z","end":"2026-02-28T01:31:18.841164Z","steps":["trace[362602544] 'process raft request' (duration: 152.883957ms)","trace[362602544] 'compare' (duration: 22.067925ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:31:18.841243Z","caller":"traceutil/trace.go:171","msg":"trace[1324592528] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"175.150637ms","start":"2026-02-28T01:31:18.666082Z","end":"2026-02-28T01:31:18.841233Z","steps":["trace[1324592528] 'process raft request' (duration: 174.978003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:18.841498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.094748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-28T01:31:18.841541Z","caller":"traceutil/trace.go:171","msg":"trace[1916576304] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:828; }","duration":"155.212902ms","start":"2026-02-28T01:31:18.686316Z","end":"2026-02-28T01:31:18.841529Z","steps":["trace[1916576304] 'agreement among raft nodes before linearized reading' (duration: 155.044448ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:18.989436Z","caller":"traceutil/trace.go:171","msg":"trace[417748944] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"118.049571ms","start":"2026-02-28T01:31:18.871352Z","end":"2026-02-28T01:31:18.989402Z","steps":["trace[417748944] 'process raft request' (duration: 115.167105ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:18.989547Z","caller":"traceutil/trace.go:171","msg":"trace[323658130] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"118.132032ms","start":"2026-02-28T01:31:18.871379Z","end":"2026-02-28T01:31:18.989511Z","steps":["trace[323658130] 'process raft request' (duration: 117.938018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:19.916284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.909216ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-28T01:31:19.916418Z","caller":"traceutil/trace.go:171","msg":"trace[1584612347] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"259.247621ms","start":"2026-02-28T01:31:19.657153Z","end":"2026-02-28T01:31:19.9164Z","steps":["trace[1584612347] 'process raft request' (duration: 79.155381ms)","trace[1584612347] 'compare' (duration: 179.733272ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:31:21.339311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.016676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-28T01:31:21.339396Z","caller":"traceutil/trace.go:171","msg":"trace[44125438] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:880; }","duration":"259.148808ms","start":"2026-02-28T01:31:21.080229Z","end":"2026-02-28T01:31:21.339378Z","steps":["trace[44125438] 'range keys from in-memory index tree' (duration: 258.8022ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:23.617139Z","caller":"traceutil/trace.go:171","msg":"trace[844792642] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:917; }","duration":"267.951819ms","start":"2026-02-28T01:31:23.349164Z","end":"2026-02-28T01:31:23.617116Z","steps":["trace[844792642] 'read index received' (duration: 267.701934ms)","trace[844792642] 'applied index is now lower than readState.Index' (duration: 248.685µs)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:31:23.617306Z","caller":"traceutil/trace.go:171","msg":"trace[380866863] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"718.143829ms","start":"2026-02-28T01:31:22.899142Z","end":"2026-02-28T01:31:23.617286Z","steps":["trace[380866863] 'process raft request' (duration: 717.825382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:23.617385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.196555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-28T01:31:23.617426Z","caller":"traceutil/trace.go:171","msg":"trace[1739065805] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:885; }","duration":"268.294287ms","start":"2026-02-28T01:31:23.349121Z","end":"2026-02-28T01:31:23.617416Z","steps":["trace[1739065805] 'agreement among raft nodes before linearized reading' (duration: 268.144784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:23.617479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:22.899114Z","time spent":"718.262152ms","remote":"127.0.0.1:53192","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-02-28T01:31:23.617499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.817354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-28T01:31:23.617569Z","caller":"traceutil/trace.go:171","msg":"trace[1646265111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:885; }","duration":"266.919686ms","start":"2026-02-28T01:31:23.350636Z","end":"2026-02-28T01:31:23.617555Z","steps":["trace[1646265111] 'agreement among raft nodes before linearized reading' (duration: 266.755373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:23.617703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.635061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-28T01:31:23.617725Z","caller":"traceutil/trace.go:171","msg":"trace[919458280] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:885; }","duration":"266.673682ms","start":"2026-02-28T01:31:23.351046Z","end":"2026-02-28T01:31:23.617719Z","steps":["trace[919458280] 'agreement among raft nodes before linearized reading' (duration: 266.60381ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:23.917911Z","caller":"traceutil/trace.go:171","msg":"trace[974174869] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:918; }","duration":"296.694408ms","start":"2026-02-28T01:31:23.62119Z","end":"2026-02-28T01:31:23.917885Z","steps":["trace[974174869] 'read index received' (duration: 255.611288ms)","trace[974174869] 'applied index is now lower than readState.Index' (duration: 41.08207ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:31:23.918036Z","caller":"traceutil/trace.go:171","msg":"trace[649643926] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"297.318191ms","start":"2026-02-28T01:31:23.620682Z","end":"2026-02-28T01:31:23.918Z","steps":["trace[649643926] 'process raft request' (duration: 256.104538ms)","trace[649643926] 'compare' (duration: 40.986008ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:31:23.918074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.849281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:31:23.918239Z","caller":"traceutil/trace.go:171","msg":"trace[934368979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:886; }","duration":"297.060066ms","start":"2026-02-28T01:31:23.621165Z","end":"2026-02-28T01:31:23.918225Z","steps":["trace[934368979] 'agreement among raft nodes before linearized reading' (duration: 296.840861ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:23.926073Z","caller":"traceutil/trace.go:171","msg":"trace[1957067601] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"304.698001ms","start":"2026-02-28T01:31:23.621359Z","end":"2026-02-28T01:31:23.926057Z","steps":["trace[1957067601] 'process raft request' (duration: 304.507267ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:23.926094Z","caller":"traceutil/trace.go:171","msg":"trace[334288260] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"304.534678ms","start":"2026-02-28T01:31:23.621545Z","end":"2026-02-28T01:31:23.92608Z","steps":["trace[334288260] 'process raft request' (duration: 304.430115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:23.926166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:23.621347Z","time spent":"304.769492ms","remote":"127.0.0.1:53192","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-02-28T01:31:23.926155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.26737ms","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":"warn","ts":"2026-02-28T01:31:23.926231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:23.621539Z","time spent":"304.600609ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:31:23.926275Z","caller":"traceutil/trace.go:171","msg":"trace[131022239] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:890; }","duration":"220.430914ms","start":"2026-02-28T01:31:23.705827Z","end":"2026-02-28T01:31:23.926258Z","steps":["trace[131022239] 'agreement among raft nodes before linearized reading' (duration: 220.25424ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:23.92636Z","caller":"traceutil/trace.go:171","msg":"trace[2007577544] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"304.694411ms","start":"2026-02-28T01:31:23.621658Z","end":"2026-02-28T01:31:23.926352Z","steps":["trace[2007577544] 'process raft request' (duration: 304.354403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:23.926376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.440335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-28T01:31:23.926394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:23.621653Z","time spent":"304.721911ms","remote":"127.0.0.1:53192","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-02-28T01:31:23.926408Z","caller":"traceutil/trace.go:171","msg":"trace[448529672] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:890; }","duration":"217.502667ms","start":"2026-02-28T01:31:23.708894Z","end":"2026-02-28T01:31:23.926397Z","steps":["trace[448529672] 'agreement among raft nodes before linearized reading' (duration: 217.453926ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:27.737417Z","caller":"traceutil/trace.go:171","msg":"trace[291479159] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"105.942354ms","start":"2026-02-28T01:31:27.631453Z","end":"2026-02-28T01:31:27.737395Z","steps":["trace[291479159] 'process raft request' (duration: 105.830251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:39.130796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.385779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:31:39.130891Z","caller":"traceutil/trace.go:171","msg":"trace[1019642717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:948; }","duration":"511.536573ms","start":"2026-02-28T01:31:38.619334Z","end":"2026-02-28T01:31:39.130871Z","steps":["trace[1019642717] 'range keys from in-memory index tree' (duration: 511.308698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:39.130934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:38.619283Z","time spent":"511.642855ms","remote":"127.0.0.1:52920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-28T01:31:39.130971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.321817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:31:39.131059Z","caller":"traceutil/trace.go:171","msg":"trace[858621494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:948; }","duration":"702.44059ms","start":"2026-02-28T01:31:38.428579Z","end":"2026-02-28T01:31:39.131019Z","steps":["trace[858621494] 'range keys from in-memory index tree' (duration: 702.230225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:39.131114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:38.42855Z","time spent":"702.556902ms","remote":"127.0.0.1:52922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-28T01:31:39.13116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.917492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-28T01:31:39.131217Z","caller":"traceutil/trace.go:171","msg":"trace[1233767676] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:948; }","duration":"335.018864ms","start":"2026-02-28T01:31:38.796182Z","end":"2026-02-28T01:31:39.131201Z","steps":["trace[1233767676] 'range keys from in-memory index tree' (duration: 334.564734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:39.131244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:38.796164Z","time spent":"335.073675ms","remote":"127.0.0.1:52934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.70\" "} {"level":"warn","ts":"2026-02-28T01:31:39.612505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.352144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-28T01:31:39.612611Z","caller":"traceutil/trace.go:171","msg":"trace[1335364201] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:949; }","duration":"280.499257ms","start":"2026-02-28T01:31:39.332085Z","end":"2026-02-28T01:31:39.612584Z","steps":["trace[1335364201] 'range keys from in-memory index tree' (duration: 280.227261ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:42.419205Z","caller":"traceutil/trace.go:171","msg":"trace[1269373932] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:999; }","duration":"383.830271ms","start":"2026-02-28T01:31:42.035352Z","end":"2026-02-28T01:31:42.419182Z","steps":["trace[1269373932] 'read index received' (duration: 383.693288ms)","trace[1269373932] 'applied index is now lower than readState.Index' (duration: 135.883µs)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:31:42.419347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.982134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-28T01:31:42.41942Z","caller":"traceutil/trace.go:171","msg":"trace[1381274223] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:963; }","duration":"384.109957ms","start":"2026-02-28T01:31:42.035298Z","end":"2026-02-28T01:31:42.419408Z","steps":["trace[1381274223] 'agreement among raft nodes before linearized reading' (duration: 383.986115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:42.419455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.309902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-28T01:31:42.419469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:42.035279Z","time spent":"384.179908ms","remote":"127.0.0.1:53016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-02-28T01:31:42.419501Z","caller":"traceutil/trace.go:171","msg":"trace[1929127114] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:963; }","duration":"346.405345ms","start":"2026-02-28T01:31:42.073084Z","end":"2026-02-28T01:31:42.419489Z","steps":["trace[1929127114] 'agreement among raft nodes before linearized reading' (duration: 346.253182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:42.419547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:42.073067Z","time spent":"346.471336ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-28T01:31:42.41964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.85425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-28T01:31:42.419684Z","caller":"traceutil/trace.go:171","msg":"trace[1305809267] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:963; }","duration":"195.900301ms","start":"2026-02-28T01:31:42.223773Z","end":"2026-02-28T01:31:42.419673Z","steps":["trace[1305809267] 'agreement among raft nodes before linearized reading' (duration: 195.744248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:42.41993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.034342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-28T01:31:42.419965Z","caller":"traceutil/trace.go:171","msg":"trace[924041190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:963; }","duration":"202.106784ms","start":"2026-02-28T01:31:42.21785Z","end":"2026-02-28T01:31:42.419956Z","steps":["trace[924041190] 'agreement among raft nodes before linearized reading' (duration: 201.972011ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:42.420116Z","caller":"traceutil/trace.go:171","msg":"trace[1395438196] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"505.3233ms","start":"2026-02-28T01:31:41.913907Z","end":"2026-02-28T01:31:42.41923Z","steps":["trace[1395438196] 'process raft request' (duration: 505.110626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:42.420203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.552966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-28T01:31:42.420248Z","caller":"traceutil/trace.go:171","msg":"trace[1211568492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:963; }","duration":"196.626398ms","start":"2026-02-28T01:31:42.223611Z","end":"2026-02-28T01:31:42.420238Z","steps":["trace[1211568492] 'agreement among raft nodes before linearized reading' (duration: 196.344002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:42.420254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:41.913889Z","time spent":"506.260011ms","remote":"127.0.0.1:53192","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-02-28T01:31:43.048742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.055868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T01:31:43.048878Z","caller":"traceutil/trace.go:171","msg":"trace[808710275] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"625.270725ms","start":"2026-02-28T01:31:42.423583Z","end":"2026-02-28T01:31:43.048854Z","steps":["trace[808710275] 'process raft request' (duration: 111.943962ms)","trace[808710275] 'compare' (duration: 512.597687ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:31:43.048963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:42.423565Z","time spent":"625.356007ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:31:43.071773Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133043111561035,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-28T01:31:43.462215Z","caller":"traceutil/trace.go:171","msg":"trace[516254839] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1001; }","duration":"890.860941ms","start":"2026-02-28T01:31:42.571325Z","end":"2026-02-28T01:31:43.462186Z","steps":["trace[516254839] 'read index received' (duration: 807.374132ms)","trace[516254839] 'applied index is now lower than readState.Index' (duration: 83.485589ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:31:43.462376Z","caller":"traceutil/trace.go:171","msg":"trace[326985710] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"1.037113607s","start":"2026-02-28T01:31:42.425227Z","end":"2026-02-28T01:31:43.462341Z","steps":["trace[326985710] 'process raft request' (duration: 1.036903312s)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:43.46243Z","caller":"traceutil/trace.go:171","msg":"trace[1488287869] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"1.038231692s","start":"2026-02-28T01:31:42.424171Z","end":"2026-02-28T01:31:43.462403Z","steps":["trace[1488287869] 'process raft request' (duration: 954.574989ms)","trace[1488287869] 'compare' (duration: 83.134651ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:31:43.462516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:42.425214Z","time spent":"1.037209949s","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:31:43.462543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:42.424149Z","time spent":"1.038331504s","remote":"127.0.0.1:53192","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-02-28T01:31:43.462665Z","caller":"traceutil/trace.go:171","msg":"trace[540175313] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"1.037473826s","start":"2026-02-28T01:31:42.425168Z","end":"2026-02-28T01:31:43.462642Z","steps":["trace[540175313] 'process raft request' (duration: 1.036886082s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:43.462708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.377504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:31:43.462759Z","caller":"traceutil/trace.go:171","msg":"trace[1775009036] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:967; }","duration":"891.467116ms","start":"2026-02-28T01:31:42.571277Z","end":"2026-02-28T01:31:43.462744Z","steps":["trace[1775009036] 'agreement among raft nodes before linearized reading' (duration: 891.384314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:43.462765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:42.425158Z","time spent":"1.037554017s","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:31:43.462806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:42.571259Z","time spent":"891.531357ms","remote":"127.0.0.1:44930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-28T01:31:43.462986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.239105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-28T01:31:43.463014Z","caller":"traceutil/trace.go:171","msg":"trace[805368913] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:967; }","duration":"535.345077ms","start":"2026-02-28T01:31:42.927661Z","end":"2026-02-28T01:31:43.463006Z","steps":["trace[805368913] 'agreement among raft nodes before linearized reading' (duration: 535.218734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:43.463037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:42.927587Z","time spent":"535.44284ms","remote":"127.0.0.1:53332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-02-28T01:31:44.723463Z","caller":"traceutil/trace.go:171","msg":"trace[862462670] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"295.527371ms","start":"2026-02-28T01:31:44.427911Z","end":"2026-02-28T01:31:44.723438Z","steps":["trace[862462670] 'process raft request' (duration: 295.382798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:45.639388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.967087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T01:31:45.639482Z","caller":"traceutil/trace.go:171","msg":"trace[1955533167] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1007; }","duration":"586.450898ms","start":"2026-02-28T01:31:45.053016Z","end":"2026-02-28T01:31:45.639467Z","steps":["trace[1955533167] 'read index received' (duration: 121.241145ms)","trace[1955533167] 'applied index is now lower than readState.Index' (duration: 465.208543ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:31:45.639566Z","caller":"traceutil/trace.go:171","msg":"trace[1116615469] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"1.18979008s","start":"2026-02-28T01:31:44.449764Z","end":"2026-02-28T01:31:45.639554Z","steps":["trace[1116615469] 'process raft request' (duration: 724.585517ms)","trace[1116615469] 'compare' (duration: 464.841774ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:31:45.639624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:44.449738Z","time spent":"1.189858512s","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:31:45.639944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.185172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-28T01:31:45.640056Z","caller":"traceutil/trace.go:171","msg":"trace[496199142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:970; }","duration":"173.334826ms","start":"2026-02-28T01:31:45.466703Z","end":"2026-02-28T01:31:45.640038Z","steps":["trace[496199142] 'agreement among raft nodes before linearized reading' (duration: 173.07938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:45.64007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.042051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-28T01:31:45.640141Z","caller":"traceutil/trace.go:171","msg":"trace[1222638975] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:970; }","duration":"587.159304ms","start":"2026-02-28T01:31:45.052966Z","end":"2026-02-28T01:31:45.640126Z","steps":["trace[1222638975] 'agreement among raft nodes before linearized reading' (duration: 586.955339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:45.640179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:45.052946Z","time spent":"587.225075ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-28T01:31:45.640274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.964097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-28T01:31:45.640403Z","caller":"traceutil/trace.go:171","msg":"trace[693063694] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:970; }","duration":"173.120741ms","start":"2026-02-28T01:31:45.46726Z","end":"2026-02-28T01:31:45.64038Z","steps":["trace[693063694] 'agreement among raft nodes before linearized reading' (duration: 172.833074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:45.640351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.62798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-28T01:31:45.640511Z","caller":"traceutil/trace.go:171","msg":"trace[1638877599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:970; }","duration":"172.891965ms","start":"2026-02-28T01:31:45.467571Z","end":"2026-02-28T01:31:45.640463Z","steps":["trace[1638877599] 'agreement among raft nodes before linearized reading' (duration: 172.517687ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:45.85863Z","caller":"traceutil/trace.go:171","msg":"trace[530756461] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"214.16938ms","start":"2026-02-28T01:31:45.644436Z","end":"2026-02-28T01:31:45.858605Z","steps":["trace[530756461] 'process raft request' (duration: 144.155498ms)","trace[530756461] 'compare' (duration: 69.90261ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:31:46.097231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.294059ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T01:31:46.097475Z","caller":"traceutil/trace.go:171","msg":"trace[1645381366] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"451.729814ms","start":"2026-02-28T01:31:45.645688Z","end":"2026-02-28T01:31:46.097418Z","steps":["trace[1645381366] 'process raft request' (duration: 325.171559ms)","trace[1645381366] 'compare' (duration: 125.770188ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:31:46.097581Z","caller":"traceutil/trace.go:171","msg":"trace[915374478] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"451.803785ms","start":"2026-02-28T01:31:45.645756Z","end":"2026-02-28T01:31:46.09756Z","steps":["trace[915374478] 'process raft request' (duration: 451.57216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:46.097669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:45.645669Z","time spent":"451.906178ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:31:46.097682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:45.645741Z","time spent":"451.893648ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:31:46.097693Z","caller":"traceutil/trace.go:171","msg":"trace[947958792] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"451.591721ms","start":"2026-02-28T01:31:45.646083Z","end":"2026-02-28T01:31:46.097675Z","steps":["trace[947958792] 'process raft request' (duration: 451.311785ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:46.097834Z","caller":"traceutil/trace.go:171","msg":"trace[1226057980] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1008; }","duration":"449.678467ms","start":"2026-02-28T01:31:45.648137Z","end":"2026-02-28T01:31:46.097815Z","steps":["trace[1226057980] 'read index received' (duration: 140.624087ms)","trace[1226057980] 'applied index is now lower than readState.Index' (duration: 309.05204ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:31:46.097922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:45.646066Z","time spent":"451.770365ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:31:46.097993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.848491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-28T01:31:46.098036Z","caller":"traceutil/trace.go:171","msg":"trace[2050458198] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:974; }","duration":"449.918922ms","start":"2026-02-28T01:31:45.648105Z","end":"2026-02-28T01:31:46.098024Z","steps":["trace[2050458198] 'agreement among raft nodes before linearized reading' (duration: 449.82929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:46.098044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.855153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-02-28T01:31:46.098076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:45.648097Z","time spent":"449.966744ms","remote":"127.0.0.1:53176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-02-28T01:31:46.098101Z","caller":"traceutil/trace.go:171","msg":"trace[1362274268] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:974; }","duration":"293.952895ms","start":"2026-02-28T01:31:45.804131Z","end":"2026-02-28T01:31:46.098084Z","steps":["trace[1362274268] 'agreement among raft nodes before linearized reading' (duration: 293.801932ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:46.466946Z","caller":"traceutil/trace.go:171","msg":"trace[588068458] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"159.223053ms","start":"2026-02-28T01:31:46.307701Z","end":"2026-02-28T01:31:46.466924Z","steps":["trace[588068458] 'process raft request' (duration: 159.10072ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:46.506662Z","caller":"traceutil/trace.go:171","msg":"trace[808243034] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"104.046161ms","start":"2026-02-28T01:31:46.4026Z","end":"2026-02-28T01:31:46.506646Z","steps":["trace[808243034] 'process raft request' (duration: 103.886287ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:46.506573Z","caller":"traceutil/trace.go:171","msg":"trace[740397460] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"196.345122ms","start":"2026-02-28T01:31:46.310206Z","end":"2026-02-28T01:31:46.506551Z","steps":["trace[740397460] 'process raft request' (duration: 196.094777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:47.337975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.508529ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-28T01:31:47.338237Z","caller":"traceutil/trace.go:171","msg":"trace[514878900] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"611.002158ms","start":"2026-02-28T01:31:46.72721Z","end":"2026-02-28T01:31:47.338212Z","steps":["trace[514878900] 'read index received' (duration: 79.032569ms)","trace[514878900] 'applied index is now lower than readState.Index' (duration: 531.966079ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:31:47.338281Z","caller":"traceutil/trace.go:171","msg":"trace[2054242565] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"827.347217ms","start":"2026-02-28T01:31:46.510896Z","end":"2026-02-28T01:31:47.338244Z","steps":["trace[2054242565] 'process raft request' (duration: 295.335056ms)","trace[2054242565] 'compare' (duration: 531.346706ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:31:47.338478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:46.510873Z","time spent":"827.484679ms","remote":"127.0.0.1:52990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":842,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-28T01:31:47.338541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.322665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-28T01:31:47.33859Z","caller":"traceutil/trace.go:171","msg":"trace[1910728758] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:981; }","duration":"611.405956ms","start":"2026-02-28T01:31:46.727171Z","end":"2026-02-28T01:31:47.338577Z","steps":["trace[1910728758] 'agreement among raft nodes before linearized reading' (duration: 611.12459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:47.338627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:46.727156Z","time spent":"611.461928ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-28T01:31:47.784184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.996755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:31:47.784276Z","caller":"traceutil/trace.go:171","msg":"trace[665032590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:982; }","duration":"165.133948ms","start":"2026-02-28T01:31:47.619123Z","end":"2026-02-28T01:31:47.784257Z","steps":["trace[665032590] 'range keys from in-memory index tree' (duration: 164.910323ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:48.044147Z","caller":"traceutil/trace.go:171","msg":"trace[1301009582] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"175.859514ms","start":"2026-02-28T01:31:47.868265Z","end":"2026-02-28T01:31:48.044124Z","steps":["trace[1301009582] 'process raft request' (duration: 175.711651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:48.24139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.91958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-28T01:31:48.241476Z","caller":"traceutil/trace.go:171","msg":"trace[828634120] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:983; }","duration":"133.064954ms","start":"2026-02-28T01:31:48.108394Z","end":"2026-02-28T01:31:48.241459Z","steps":["trace[828634120] 'count revisions from in-memory index tree' (duration: 132.859238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:48.241545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.055098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-28T01:31:48.241541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.398188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-28T01:31:48.241622Z","caller":"traceutil/trace.go:171","msg":"trace[469305610] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:983; }","duration":"138.206951ms","start":"2026-02-28T01:31:48.103396Z","end":"2026-02-28T01:31:48.241603Z","steps":["trace[469305610] 'range keys from in-memory index tree' (duration: 137.857083ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:48.241649Z","caller":"traceutil/trace.go:171","msg":"trace[1579273298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:983; }","duration":"139.549882ms","start":"2026-02-28T01:31:48.10207Z","end":"2026-02-28T01:31:48.24162Z","steps":["trace[1579273298] 'range keys from in-memory index tree' (duration: 139.194184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:48.241573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.996003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-28T01:31:48.241743Z","caller":"traceutil/trace.go:171","msg":"trace[1119734857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:983; }","duration":"140.192428ms","start":"2026-02-28T01:31:48.101535Z","end":"2026-02-28T01:31:48.241728Z","steps":["trace[1119734857] 'range keys from in-memory index tree' (duration: 139.86786ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:48.352743Z","caller":"traceutil/trace.go:171","msg":"trace[51514509] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"106.729742ms","start":"2026-02-28T01:31:48.245985Z","end":"2026-02-28T01:31:48.352715Z","steps":["trace[51514509] 'process raft request' (duration: 106.571658ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:48.701096Z","caller":"traceutil/trace.go:171","msg":"trace[1943941167] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"454.485698ms","start":"2026-02-28T01:31:48.246587Z","end":"2026-02-28T01:31:48.701073Z","steps":["trace[1943941167] 'process raft request' (duration: 454.429356ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:48.701197Z","caller":"traceutil/trace.go:171","msg":"trace[405138243] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"454.937399ms","start":"2026-02-28T01:31:48.246224Z","end":"2026-02-28T01:31:48.701161Z","steps":["trace[405138243] 'process raft request' (duration: 385.822728ms)","trace[405138243] 'compare' (duration: 68.828403ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:31:48.701374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:48.24621Z","time spent":"455.077402ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:31:48.701473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:48.246566Z","time spent":"454.806156ms","remote":"127.0.0.1:53192","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-02-28T01:31:50.003732Z","caller":"traceutil/trace.go:171","msg":"trace[1998287920] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"119.510953ms","start":"2026-02-28T01:31:49.884201Z","end":"2026-02-28T01:31:50.003712Z","steps":["trace[1998287920] 'process raft request' (duration: 119.390381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:51.517235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.395846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-28T01:31:51.517311Z","caller":"traceutil/trace.go:171","msg":"trace[764110050] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"245.459006ms","start":"2026-02-28T01:31:51.271839Z","end":"2026-02-28T01:31:51.517298Z","steps":["trace[764110050] 'process raft request' (duration: 47.946367ms)","trace[764110050] 'compare' (duration: 197.239683ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:31:51.537419Z","caller":"traceutil/trace.go:171","msg":"trace[12886845] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"249.404066ms","start":"2026-02-28T01:31:51.287988Z","end":"2026-02-28T01:31:51.537392Z","steps":["trace[12886845] 'process raft request' (duration: 249.227092ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:51.650297Z","caller":"traceutil/trace.go:171","msg":"trace[1807141297] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1038; }","duration":"104.549371ms","start":"2026-02-28T01:31:51.545728Z","end":"2026-02-28T01:31:51.650278Z","steps":["trace[1807141297] 'read index received' (duration: 37.17323ms)","trace[1807141297] 'applied index is now lower than readState.Index' (duration: 67.374621ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:31:51.650489Z","caller":"traceutil/trace.go:171","msg":"trace[51614361] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"104.630553ms","start":"2026-02-28T01:31:51.545846Z","end":"2026-02-28T01:31:51.650477Z","steps":["trace[51614361] 'process raft request' (duration: 104.361897ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:51.650443Z","caller":"traceutil/trace.go:171","msg":"trace[564253645] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"106.938196ms","start":"2026-02-28T01:31:51.543475Z","end":"2026-02-28T01:31:51.650413Z","steps":["trace[564253645] 'process raft request' (duration: 73.928072ms)","trace[564253645] 'compare' (duration: 32.670587ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:31:51.650865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.118414ms","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:533"} {"level":"info","ts":"2026-02-28T01:31:51.650927Z","caller":"traceutil/trace.go:171","msg":"trace[2089049068] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1002; }","duration":"105.203556ms","start":"2026-02-28T01:31:51.545704Z","end":"2026-02-28T01:31:51.650908Z","steps":["trace[2089049068] 'agreement among raft nodes before linearized reading' (duration: 105.029782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:52.539192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.405828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-28T01:31:52.539273Z","caller":"traceutil/trace.go:171","msg":"trace[1870211248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1003; }","duration":"163.534822ms","start":"2026-02-28T01:31:52.375721Z","end":"2026-02-28T01:31:52.539256Z","steps":["trace[1870211248] 'range keys from in-memory index tree' (duration: 163.189763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:52.539595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.324496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-28T01:31:52.53967Z","caller":"traceutil/trace.go:171","msg":"trace[84605990] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1003; }","duration":"479.438268ms","start":"2026-02-28T01:31:52.060214Z","end":"2026-02-28T01:31:52.539652Z","steps":["trace[84605990] 'range keys from in-memory index tree' (duration: 478.957187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:52.539762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:52.060199Z","time spent":"479.551151ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-28T01:31:53.119261Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133043111561122,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-28T01:31:53.147867Z","caller":"traceutil/trace.go:171","msg":"trace[746078336] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"602.388032ms","start":"2026-02-28T01:31:52.54546Z","end":"2026-02-28T01:31:53.147848Z","steps":["trace[746078336] 'process raft request' (duration: 602.265079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:53.14801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:52.545438Z","time spent":"602.481573ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:31:53.480644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.296149ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T01:31:53.480743Z","caller":"traceutil/trace.go:171","msg":"trace[106436410] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1042; }","duration":"861.797856ms","start":"2026-02-28T01:31:52.618929Z","end":"2026-02-28T01:31:53.480727Z","steps":["trace[106436410] 'read index received' (duration: 528.802208ms)","trace[106436410] 'applied index is now lower than readState.Index' (duration: 332.994548ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:31:53.480815Z","caller":"traceutil/trace.go:171","msg":"trace[2085761634] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"934.820437ms","start":"2026-02-28T01:31:52.545945Z","end":"2026-02-28T01:31:53.480765Z","steps":["trace[2085761634] 'process raft request' (duration: 784.311194ms)","trace[2085761634] 'compare' (duration: 150.159665ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:31:53.480931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.98746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:31:53.481023Z","caller":"traceutil/trace.go:171","msg":"trace[1328108085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1005; }","duration":"862.156585ms","start":"2026-02-28T01:31:52.618853Z","end":"2026-02-28T01:31:53.481009Z","steps":["trace[1328108085] 'agreement among raft nodes before linearized reading' (duration: 861.94142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:53.481063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:52.618831Z","time spent":"862.225576ms","remote":"127.0.0.1:52922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-28T01:31:53.48105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:52.545923Z","time spent":"934.992551ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:31:53.481224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.501332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-28T01:31:53.481271Z","caller":"traceutil/trace.go:171","msg":"trace[244196484] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1005; }","duration":"730.572944ms","start":"2026-02-28T01:31:52.750687Z","end":"2026-02-28T01:31:53.48126Z","steps":["trace[244196484] 'agreement among raft nodes before linearized reading' (duration: 730.423771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:53.481311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:52.750673Z","time spent":"730.630675ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-28T01:31:53.481769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.764327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-28T01:31:53.481812Z","caller":"traceutil/trace.go:171","msg":"trace[884657547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1005; }","duration":"747.849529ms","start":"2026-02-28T01:31:52.733953Z","end":"2026-02-28T01:31:53.481803Z","steps":["trace[884657547] 'agreement among raft nodes before linearized reading' (duration: 747.056121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:31:53.48185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:31:52.733931Z","time spent":"747.90431ms","remote":"127.0.0.1:53192","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-02-28T01:31:53.626428Z","caller":"traceutil/trace.go:171","msg":"trace[1944158652] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"140.68352ms","start":"2026-02-28T01:31:53.485703Z","end":"2026-02-28T01:31:53.626387Z","steps":["trace[1944158652] 'process raft request' (duration: 130.790673ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:53.626802Z","caller":"traceutil/trace.go:171","msg":"trace[85215631] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"140.447193ms","start":"2026-02-28T01:31:53.486239Z","end":"2026-02-28T01:31:53.626686Z","steps":["trace[85215631] 'process raft request' (duration: 140.093746ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:55.18839Z","caller":"traceutil/trace.go:171","msg":"trace[100983517] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"270.693872ms","start":"2026-02-28T01:31:54.917608Z","end":"2026-02-28T01:31:55.188302Z","steps":["trace[100983517] 'process raft request' (duration: 266.958277ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:55.546628Z","caller":"traceutil/trace.go:171","msg":"trace[678170206] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"208.53065ms","start":"2026-02-28T01:31:55.338066Z","end":"2026-02-28T01:31:55.546596Z","steps":["trace[678170206] 'process raft request' (duration: 208.344686ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:55.656995Z","caller":"traceutil/trace.go:171","msg":"trace[1171323100] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"104.328298ms","start":"2026-02-28T01:31:55.552625Z","end":"2026-02-28T01:31:55.656953Z","steps":["trace[1171323100] 'process raft request' (duration: 103.936478ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:31:55.657798Z","caller":"traceutil/trace.go:171","msg":"trace[101151697] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"103.461487ms","start":"2026-02-28T01:31:55.554303Z","end":"2026-02-28T01:31:55.657765Z","steps":["trace[101151697] 'process raft request' (duration: 102.479204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:33:00.245664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.692807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-28T01:33:00.245749Z","caller":"traceutil/trace.go:171","msg":"trace[1245610917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1293; }","duration":"102.806219ms","start":"2026-02-28T01:33:00.14292Z","end":"2026-02-28T01:33:00.245726Z","steps":["trace[1245610917] 'range keys from in-memory index tree' (duration: 102.552353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:33:00.888954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.066938ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:33:00.889136Z","caller":"traceutil/trace.go:171","msg":"trace[1598275939] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1298; }","duration":"203.234882ms","start":"2026-02-28T01:33:00.685848Z","end":"2026-02-28T01:33:00.889083Z","steps":["trace[1598275939] 'range keys from in-memory index tree' (duration: 203.045728ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:33:00.889312Z","caller":"traceutil/trace.go:171","msg":"trace[1659662091] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"139.200415ms","start":"2026-02-28T01:33:00.750093Z","end":"2026-02-28T01:33:00.889293Z","steps":["trace[1659662091] 'process raft request' (duration: 139.099663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:33:22.125229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.019851ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T01:33:22.125409Z","caller":"traceutil/trace.go:171","msg":"trace[149031839] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"197.841176ms","start":"2026-02-28T01:33:21.927548Z","end":"2026-02-28T01:33:22.125389Z","steps":["trace[149031839] 'process raft request' (duration: 28.573149ms)","trace[149031839] 'compare' (duration: 168.917299ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:33:22.276216Z","caller":"traceutil/trace.go:171","msg":"trace[1274074601] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"114.961186ms","start":"2026-02-28T01:33:22.161219Z","end":"2026-02-28T01:33:22.27618Z","steps":["trace[1274074601] 'process raft request' (duration: 79.075293ms)","trace[1274074601] 'compare' (duration: 35.754181ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:33:22.690329Z","caller":"traceutil/trace.go:171","msg":"trace[2016754928] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"164.920849ms","start":"2026-02-28T01:33:22.52537Z","end":"2026-02-28T01:33:22.690291Z","steps":["trace[2016754928] 'process raft request' (duration: 72.360851ms)","trace[2016754928] 'compare' (duration: 92.397304ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:33:38.843467Z","caller":"traceutil/trace.go:171","msg":"trace[1598621130] transaction","detail":"{read_only:false; response_revision:1452; number_of_response:1; }","duration":"214.153215ms","start":"2026-02-28T01:33:38.629283Z","end":"2026-02-28T01:33:38.843436Z","steps":["trace[1598621130] 'process raft request' (duration: 179.743404ms)","trace[1598621130] 'compare' (duration: 34.225227ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:34:15.032374Z","caller":"traceutil/trace.go:171","msg":"trace[996415689] transaction","detail":"{read_only:false; response_revision:1598; number_of_response:1; }","duration":"139.294592ms","start":"2026-02-28T01:34:14.893015Z","end":"2026-02-28T01:34:15.032309Z","steps":["trace[996415689] 'process raft request' (duration: 139.083387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:34:15.411987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.20133ms","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":"info","ts":"2026-02-28T01:34:15.412112Z","caller":"traceutil/trace.go:171","msg":"trace[1610478912] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1601; }","duration":"131.405375ms","start":"2026-02-28T01:34:15.28068Z","end":"2026-02-28T01:34:15.412086Z","steps":["trace[1610478912] 'count revisions from in-memory index tree' (duration: 131.095018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:34:15.412022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.45005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363682"} {"level":"info","ts":"2026-02-28T01:34:15.412216Z","caller":"traceutil/trace.go:171","msg":"trace[208449125] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1601; }","duration":"167.697086ms","start":"2026-02-28T01:34:15.244496Z","end":"2026-02-28T01:34:15.412194Z","steps":["trace[208449125] 'range keys from in-memory index tree' (duration: 167.153112ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:34:48.970671Z","caller":"traceutil/trace.go:171","msg":"trace[102489818] linearizableReadLoop","detail":"{readStateIndex:1829; appliedIndex:1828; }","duration":"111.090952ms","start":"2026-02-28T01:34:48.859556Z","end":"2026-02-28T01:34:48.970647Z","steps":["trace[102489818] 'read index received' (duration: 110.937258ms)","trace[102489818] 'applied index is now lower than readState.Index' (duration: 152.684µs)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:34:48.970769Z","caller":"traceutil/trace.go:171","msg":"trace[1024784588] transaction","detail":"{read_only:false; response_revision:1753; number_of_response:1; }","duration":"111.614393ms","start":"2026-02-28T01:34:48.859116Z","end":"2026-02-28T01:34:48.970731Z","steps":["trace[1024784588] 'process raft request' (duration: 111.420138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:34:48.970865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.290546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-28T01:34:48.970904Z","caller":"traceutil/trace.go:171","msg":"trace[1897324596] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1753; }","duration":"111.359107ms","start":"2026-02-28T01:34:48.859533Z","end":"2026-02-28T01:34:48.970892Z","steps":["trace[1897324596] 'agreement among raft nodes before linearized reading' (duration: 111.210524ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:35:01.330311Z","caller":"traceutil/trace.go:171","msg":"trace[1964224719] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"305.151476ms","start":"2026-02-28T01:35:01.025123Z","end":"2026-02-28T01:35:01.330274Z","steps":["trace[1964224719] 'process raft request' (duration: 304.851529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:35:01.330478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:35:01.025112Z","time spent":"305.283828ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:35:07.461587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.122482ms","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:534"} {"level":"info","ts":"2026-02-28T01:35:07.461815Z","caller":"traceutil/trace.go:171","msg":"trace[1325191581] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1841; }","duration":"111.427199ms","start":"2026-02-28T01:35:07.350368Z","end":"2026-02-28T01:35:07.461796Z","steps":["trace[1325191581] 'range keys from in-memory index tree' (duration: 110.98936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:35:07.461581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.023265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T01:35:07.461902Z","caller":"traceutil/trace.go:171","msg":"trace[48111843] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1841; }","duration":"160.363542ms","start":"2026-02-28T01:35:07.30152Z","end":"2026-02-28T01:35:07.461884Z","steps":["trace[48111843] 'count revisions from in-memory index tree' (duration: 159.889942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:35:07.461635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.215084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-28T01:35:07.461979Z","caller":"traceutil/trace.go:171","msg":"trace[680087330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1841; }","duration":"111.589832ms","start":"2026-02-28T01:35:07.350372Z","end":"2026-02-28T01:35:07.461962Z","steps":["trace[680087330] 'range keys from in-memory index tree' (duration: 111.03281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:35:07.461636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.525778ms","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:507"} {"level":"info","ts":"2026-02-28T01:35:07.462178Z","caller":"traceutil/trace.go:171","msg":"trace[284044008] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1841; }","duration":"342.06535ms","start":"2026-02-28T01:35:07.120095Z","end":"2026-02-28T01:35:07.462161Z","steps":["trace[284044008] 'range keys from in-memory index tree' (duration: 341.360154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:35:07.462214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:35:07.120035Z","time spent":"342.168372ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-28T01:35:13.673572Z","caller":"traceutil/trace.go:171","msg":"trace[1283167888] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"139.017885ms","start":"2026-02-28T01:35:13.534534Z","end":"2026-02-28T01:35:13.673552Z","steps":["trace[1283167888] 'process raft request' (duration: 138.888682ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:35:13.673605Z","caller":"traceutil/trace.go:171","msg":"trace[177804721] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"138.748158ms","start":"2026-02-28T01:35:13.534823Z","end":"2026-02-28T01:35:13.673571Z","steps":["trace[177804721] 'process raft request' (duration: 138.636356ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:35:13.673733Z","caller":"traceutil/trace.go:171","msg":"trace[1516325471] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"140.334745ms","start":"2026-02-28T01:35:13.533386Z","end":"2026-02-28T01:35:13.673721Z","steps":["trace[1516325471] 'process raft request' (duration: 138.76633ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:35:13.673813Z","caller":"traceutil/trace.go:171","msg":"trace[1189977374] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"139.457845ms","start":"2026-02-28T01:35:13.534346Z","end":"2026-02-28T01:35:13.673804Z","steps":["trace[1189977374] 'process raft request' (duration: 139.028765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:35:23.867791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.09421ms","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:532"} {"level":"warn","ts":"2026-02-28T01:35:23.867852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.509162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-28T01:35:23.86792Z","caller":"traceutil/trace.go:171","msg":"trace[2096071639] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1916; }","duration":"154.312755ms","start":"2026-02-28T01:35:23.713587Z","end":"2026-02-28T01:35:23.8679Z","steps":["trace[2096071639] 'range keys from in-memory index tree' (duration: 153.978988ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:35:23.867943Z","caller":"traceutil/trace.go:171","msg":"trace[1350183484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1916; }","duration":"151.644455ms","start":"2026-02-28T01:35:23.716279Z","end":"2026-02-28T01:35:23.867923Z","steps":["trace[1350183484] 'range keys from in-memory index tree' (duration: 151.295697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:35:23.867823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.892751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-28T01:35:23.868081Z","caller":"traceutil/trace.go:171","msg":"trace[1805575724] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1916; }","duration":"160.208298ms","start":"2026-02-28T01:35:23.707857Z","end":"2026-02-28T01:35:23.868066Z","steps":["trace[1805575724] 'range keys from in-memory index tree' (duration: 159.675067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:35:23.867825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.297584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-28T01:35:23.868223Z","caller":"traceutil/trace.go:171","msg":"trace[702792963] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1916; }","duration":"154.743865ms","start":"2026-02-28T01:35:23.713469Z","end":"2026-02-28T01:35:23.868213Z","steps":["trace[702792963] 'range keys from in-memory index tree' (duration: 154.156911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:35:24.109429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.115697ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-28T01:35:24.10976Z","caller":"traceutil/trace.go:171","msg":"trace[1910196041] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"237.949433ms","start":"2026-02-28T01:35:23.871792Z","end":"2026-02-28T01:35:24.109741Z","steps":["trace[1910196041] 'process raft request' (duration: 237.747348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:35:24.248177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.241762ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T01:35:24.248668Z","caller":"traceutil/trace.go:171","msg":"trace[838597937] linearizableReadLoop","detail":"{readStateIndex:2003; appliedIndex:1998; }","duration":"351.014868ms","start":"2026-02-28T01:35:23.897636Z","end":"2026-02-28T01:35:24.248651Z","steps":["trace[838597937] 'read index received' (duration: 39.201µs)","trace[838597937] 'applied index is now lower than readState.Index' (duration: 350.974907ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T01:35:24.248766Z","caller":"traceutil/trace.go:171","msg":"trace[1904570055] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"376.616926ms","start":"2026-02-28T01:35:23.872138Z","end":"2026-02-28T01:35:24.248755Z","steps":["trace[1904570055] 'process raft request' (duration: 376.438662ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T01:35:24.248776Z","caller":"traceutil/trace.go:171","msg":"trace[2137277355] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"376.868722ms","start":"2026-02-28T01:35:23.871869Z","end":"2026-02-28T01:35:24.248738Z","steps":["trace[2137277355] 'process raft request' (duration: 376.648507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:35:24.248846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:35:23.872128Z","time spent":"376.680317ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:35:24.248843Z","caller":"traceutil/trace.go:171","msg":"trace[787304914] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"376.83287ms","start":"2026-02-28T01:35:23.871847Z","end":"2026-02-28T01:35:24.24868Z","steps":["trace[787304914] 'process raft request' (duration: 237.885992ms)","trace[787304914] 'compare' (duration: 138.059889ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T01:35:24.248973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:35:23.871862Z","time spent":"377.006644ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T01:35:24.249041Z","caller":"traceutil/trace.go:171","msg":"trace[395731406] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"327.991638ms","start":"2026-02-28T01:35:23.92101Z","end":"2026-02-28T01:35:24.249001Z","steps":["trace[395731406] 'process raft request' (duration: 327.61036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:35:24.249074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.144248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-node-lease/\" range_end:\"/registry/configmaps/kube-node-lease0\" limit:500 ","response":"range_response_count:1 size:1754"} {"level":"info","ts":"2026-02-28T01:35:24.249147Z","caller":"traceutil/trace.go:171","msg":"trace[1180913014] range","detail":"{range_begin:/registry/configmaps/kube-node-lease/; range_end:/registry/configmaps/kube-node-lease0; response_count:1; response_revision:1921; }","duration":"292.279951ms","start":"2026-02-28T01:35:23.956847Z","end":"2026-02-28T01:35:24.249127Z","steps":["trace[1180913014] 'agreement among raft nodes before linearized reading' (duration: 292.111277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:35:24.24915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.514478ms","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-02-28T01:35:24.2492Z","caller":"traceutil/trace.go:171","msg":"trace[2135056548] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1921; }","duration":"351.596741ms","start":"2026-02-28T01:35:23.89759Z","end":"2026-02-28T01:35:24.249187Z","steps":["trace[2135056548] 'agreement among raft nodes before linearized reading' (duration: 351.225032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T01:35:24.249208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:35:23.920991Z","time spent":"328.130931ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T01:35:24.249239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:35:23.897575Z","time spent":"351.655371ms","remote":"127.0.0.1:53320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-02-28T01:35:24.249096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T01:35:23.871833Z","time spent":"377.172998ms","remote":"127.0.0.1:53192","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-02-28T01:35:24.249449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.003073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/default/\" range_end:\"/registry/configmaps/default0\" limit:500 ","response":"range_response_count:1 size:1738"} {"level":"info","ts":"2026-02-28T01:35:24.249556Z","caller":"traceutil/trace.go:171","msg":"trace[1833446097] range","detail":"{range_begin:/registry/configmaps/default/; range_end:/registry/configmaps/default0; response_count:1; response_revision:1921; }","duration":"291.156376ms","start":"2026-02-28T01:35:23.958376Z","end":"2026-02-28T01:35:24.249533Z","steps":["trace[1833446097] 'agreement among raft nodes before linearized reading' (duration: 290.993723ms)"],"step_count":1}