{"level":"warn","ts":"2026-02-01T18:59:52.782979Z","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-01T18:59:52.783154Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.200: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.200:2380","--initial-cluster=instance=https://162.253.55.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.200: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-01T18:59:52.783514Z","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-01T18:59:52.786415Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-02-01T18:59:52.786497Z","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-01T18:59:52.787116Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"]} {"level":"info","ts":"2026-02-01T18:59:52.790789Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.200: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-01T18:59:52.798745Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.259012ms"} {"level":"info","ts":"2026-02-01T18:59:52.82022Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e10bb51ed7578852","cluster-id":"aa14459abd2161c3"} {"level":"info","ts":"2026-02-01T18:59:52.82035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=()"} {"level":"info","ts":"2026-02-01T18:59:52.820378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 0"} {"level":"info","ts":"2026-02-01T18:59:52.820466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e10bb51ed7578852 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-01T18:59:52.82054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 1"} {"level":"info","ts":"2026-02-01T18:59:52.820699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"warn","ts":"2026-02-01T18:59:52.836577Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-01T18:59:52.844778Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-01T18:59:52.848975Z","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-01T18:59:52.865958Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e10bb51ed7578852","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-01T18:59:52.866328Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e10bb51ed7578852","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-01T18:59:52.866436Z","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-01T18:59:52.866461Z","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-01T18:59:52.866471Z","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-01T18:59:52.879752Z","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-01T18:59:52.87992Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e10bb51ed7578852","initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-01T18:59:52.879944Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-01T18:59:52.880099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"info","ts":"2026-02-01T18:59:52.880159Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","added-peer-id":"e10bb51ed7578852","added-peer-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-02-01T18:59:52.880204Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-02-01T18:59:52.880213Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-02-01T18:59:53.021954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 is starting a new election at term 1"} {"level":"info","ts":"2026-02-01T18:59:53.021997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-01T18:59:53.022019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgPreVoteResp from e10bb51ed7578852 at term 1"} {"level":"info","ts":"2026-02-01T18:59:53.022128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became candidate at term 2"} {"level":"info","ts":"2026-02-01T18:59:53.022144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgVoteResp from e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-02-01T18:59:53.022153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became leader at term 2"} {"level":"info","ts":"2026-02-01T18:59:53.022196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e10bb51ed7578852 elected leader e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-02-01T18:59:53.062235Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e10bb51ed7578852","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.200:2379]}","request-path":"/0/members/e10bb51ed7578852/attributes","cluster-id":"aa14459abd2161c3","publish-timeout":"7s"} {"level":"info","ts":"2026-02-01T18:59:53.065347Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T18:59:53.06563Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-01T18:59:53.065646Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-01T18:59:53.066294Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T18:59:53.066437Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:59:53.069195Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.200:2379"} {"level":"info","ts":"2026-02-01T18:59:53.069266Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:59:53.072808Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:59:53.07283Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:59:53.07423Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-01T18:59:59.941941Z","caller":"traceutil/trace.go:171","msg":"trace[1023989106] linearizableReadLoop","detail":"{readStateIndex:277; appliedIndex:275; }","duration":"115.900013ms","start":"2026-02-01T18:59:59.826026Z","end":"2026-02-01T18:59:59.941926Z","steps":["trace[1023989106] 'read index received' (duration: 96.245965ms)","trace[1023989106] 'applied index is now lower than readState.Index' (duration: 19.653398ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:59:59.94204Z","caller":"traceutil/trace.go:171","msg":"trace[777210755] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"119.766641ms","start":"2026-02-01T18:59:59.822265Z","end":"2026-02-01T18:59:59.942032Z","steps":["trace[777210755] 'process raft request' (duration: 99.999561ms)","trace[777210755] 'compare' (duration: 19.396152ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:59:59.942231Z","caller":"traceutil/trace.go:171","msg":"trace[1164319244] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"118.736268ms","start":"2026-02-01T18:59:59.823488Z","end":"2026-02-01T18:59:59.942224Z","steps":["trace[1164319244] 'process raft request' (duration: 118.39787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:59:59.942344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.297832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-01T18:59:59.942375Z","caller":"traceutil/trace.go:171","msg":"trace[306950650] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:269; }","duration":"116.365494ms","start":"2026-02-01T18:59:59.826001Z","end":"2026-02-01T18:59:59.942366Z","steps":["trace[306950650] 'agreement among raft nodes before linearized reading' (duration: 116.303632ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:00.188696Z","caller":"traceutil/trace.go:171","msg":"trace[1694314704] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"175.071302ms","start":"2026-02-01T19:00:00.013608Z","end":"2026-02-01T19:00:00.188679Z","steps":["trace[1694314704] 'process raft request' (duration: 174.619162ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:00.201339Z","caller":"traceutil/trace.go:171","msg":"trace[228212469] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"175.150875ms","start":"2026-02-01T19:00:00.026168Z","end":"2026-02-01T19:00:00.201319Z","steps":["trace[228212469] 'process raft request' (duration: 175.019942ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:00.402345Z","caller":"traceutil/trace.go:171","msg":"trace[1425478705] linearizableReadLoop","detail":"{readStateIndex:283; appliedIndex:282; }","duration":"195.764775ms","start":"2026-02-01T19:00:00.206559Z","end":"2026-02-01T19:00:00.402324Z","steps":["trace[1425478705] 'read index received' (duration: 158.062775ms)","trace[1425478705] 'applied index is now lower than readState.Index' (duration: 37.7008ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:00.402481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.878588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:00.402512Z","caller":"traceutil/trace.go:171","msg":"trace[1648154199] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:275; }","duration":"195.95808ms","start":"2026-02-01T19:00:00.206545Z","end":"2026-02-01T19:00:00.402503Z","steps":["trace[1648154199] 'agreement among raft nodes before linearized reading' (duration: 195.858148ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:00.402818Z","caller":"traceutil/trace.go:171","msg":"trace[1656297254] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"196.671236ms","start":"2026-02-01T19:00:00.206135Z","end":"2026-02-01T19:00:00.402806Z","steps":["trace[1656297254] 'process raft request' (duration: 158.548876ms)","trace[1656297254] 'compare' (duration: 37.530766ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:12.428847Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085375204325259,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T19:00:12.929866Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085375204325259,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T19:00:13.16369Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.255740301s","expected-duration":"1s"} {"level":"info","ts":"2026-02-01T19:00:13.164376Z","caller":"traceutil/trace.go:171","msg":"trace[960059550] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"1.257494111s","start":"2026-02-01T19:00:11.906859Z","end":"2026-02-01T19:00:13.164354Z","steps":["trace[960059550] 'process raft request' (duration: 1.257315177s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:13.164833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:11.906845Z","time spent":"1.257578014s","remote":"127.0.0.1:58494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6883,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:00:13.281089Z","caller":"traceutil/trace.go:171","msg":"trace[1802848869] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:421; }","duration":"1.353212055s","start":"2026-02-01T19:00:11.927859Z","end":"2026-02-01T19:00:13.281071Z","steps":["trace[1802848869] 'read index received' (duration: 1.236236496s)","trace[1802848869] 'applied index is now lower than readState.Index' (duration: 116.974449ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:13.281203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.353346948s","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-01T19:00:13.281222Z","caller":"traceutil/trace.go:171","msg":"trace[2032302098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:411; }","duration":"1.35342754s","start":"2026-02-01T19:00:11.927789Z","end":"2026-02-01T19:00:13.281216Z","steps":["trace[2032302098] 'agreement among raft nodes before linearized reading' (duration: 1.353367239s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:13.281241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:11.92777Z","time spent":"1.35346599s","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T19:00:13.281291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.343670287s","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-01T19:00:13.281334Z","caller":"traceutil/trace.go:171","msg":"trace[78199322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:411; }","duration":"1.343740259s","start":"2026-02-01T19:00:11.937584Z","end":"2026-02-01T19:00:13.281324Z","steps":["trace[78199322] 'agreement among raft nodes before linearized reading' (duration: 1.343661108s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:13.281358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:11.937557Z","time spent":"1.34379378s","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T19:00:13.281466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.977197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-01T19:00:13.281486Z","caller":"traceutil/trace.go:171","msg":"trace[722210265] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:411; }","duration":"113.042858ms","start":"2026-02-01T19:00:13.168435Z","end":"2026-02-01T19:00:13.281478Z","steps":["trace[722210265] 'agreement among raft nodes before linearized reading' (duration: 112.970136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:13.281513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.289099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:13.281528Z","caller":"traceutil/trace.go:171","msg":"trace[1813591800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"834.34182ms","start":"2026-02-01T19:00:12.447182Z","end":"2026-02-01T19:00:13.281524Z","steps":["trace[1813591800] 'agreement among raft nodes before linearized reading' (duration: 834.319759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:13.28154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:12.447163Z","time spent":"834.374131ms","remote":"127.0.0.1:58288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T19:00:13.281607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.804009ms","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-01T19:00:13.28162Z","caller":"traceutil/trace.go:171","msg":"trace[1870383685] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:411; }","duration":"303.84792ms","start":"2026-02-01T19:00:12.977767Z","end":"2026-02-01T19:00:13.281615Z","steps":["trace[1870383685] 'agreement among raft nodes before linearized reading' (duration: 303.825479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:13.281631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:12.977749Z","time spent":"303.87919ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T19:00:13.281642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256731904s","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-01T19:00:13.281662Z","caller":"traceutil/trace.go:171","msg":"trace[1853758269] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:411; }","duration":"1.256775495s","start":"2026-02-01T19:00:12.02488Z","end":"2026-02-01T19:00:13.281656Z","steps":["trace[1853758269] 'agreement among raft nodes before linearized reading' (duration: 1.256711323s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:13.281693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:12.024868Z","time spent":"1.256820847s","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-01T19:00:15.409396Z","caller":"traceutil/trace.go:171","msg":"trace[506296557] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:428; }","duration":"102.945228ms","start":"2026-02-01T19:00:15.30643Z","end":"2026-02-01T19:00:15.409375Z","steps":["trace[506296557] 'read index received' (duration: 102.786705ms)","trace[506296557] 'applied index is now lower than readState.Index' (duration: 157.623µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:00:15.410316Z","caller":"traceutil/trace.go:171","msg":"trace[1390700345] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"107.501321ms","start":"2026-02-01T19:00:15.3028Z","end":"2026-02-01T19:00:15.410301Z","steps":["trace[1390700345] 'process raft request' (duration: 106.463948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:15.410469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.021452ms","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-01T19:00:15.410505Z","caller":"traceutil/trace.go:171","msg":"trace[1283907159] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:417; }","duration":"104.068683ms","start":"2026-02-01T19:00:15.306428Z","end":"2026-02-01T19:00:15.410496Z","steps":["trace[1283907159] 'agreement among raft nodes before linearized reading' (duration: 103.993162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:15.410592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.349847ms","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-01T19:00:15.410621Z","caller":"traceutil/trace.go:171","msg":"trace[1146648996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:417; }","duration":"103.378008ms","start":"2026-02-01T19:00:15.307236Z","end":"2026-02-01T19:00:15.410614Z","steps":["trace[1146648996] 'agreement among raft nodes before linearized reading' (duration: 103.335677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:15.412064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.758783ms","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-01T19:00:15.412126Z","caller":"traceutil/trace.go:171","msg":"trace[1088509695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:417; }","duration":"102.815185ms","start":"2026-02-01T19:00:15.309281Z","end":"2026-02-01T19:00:15.412096Z","steps":["trace[1088509695] 'agreement among raft nodes before linearized reading' (duration: 101.488585ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:15.670207Z","caller":"traceutil/trace.go:171","msg":"trace[1735014107] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"253.911462ms","start":"2026-02-01T19:00:15.416276Z","end":"2026-02-01T19:00:15.670187Z","steps":["trace[1735014107] 'process raft request' (duration: 155.773184ms)","trace[1735014107] 'compare' (duration: 97.76158ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:00:15.67022Z","caller":"traceutil/trace.go:171","msg":"trace[739420213] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"248.290603ms","start":"2026-02-01T19:00:15.421907Z","end":"2026-02-01T19:00:15.670198Z","steps":["trace[739420213] 'process raft request' (duration: 248.241402ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:15.670447Z","caller":"traceutil/trace.go:171","msg":"trace[1897514178] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"253.792929ms","start":"2026-02-01T19:00:15.416647Z","end":"2026-02-01T19:00:15.67044Z","steps":["trace[1897514178] 'process raft request' (duration: 253.41894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:21.240929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.004909ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-01T19:00:21.241061Z","caller":"traceutil/trace.go:171","msg":"trace[1614796208] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"569.958999ms","start":"2026-02-01T19:00:20.671091Z","end":"2026-02-01T19:00:21.24105Z","steps":["trace[1614796208] 'process raft request' (duration: 569.899938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:21.241098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:20.671053Z","time spent":"570.030141ms","remote":"127.0.0.1:58642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":734,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T19:00:21.24123Z","caller":"traceutil/trace.go:171","msg":"trace[1143509619] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"570.138503ms","start":"2026-02-01T19:00:20.671084Z","end":"2026-02-01T19:00:21.241223Z","steps":["trace[1143509619] 'process raft request' (duration: 395.715745ms)","trace[1143509619] 'compare' (duration: 173.843095ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:21.241256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:20.671066Z","time spent":"570.178655ms","remote":"127.0.0.1:58642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":770,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T19:00:23.798325Z","caller":"traceutil/trace.go:171","msg":"trace[1354958292] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"332.718399ms","start":"2026-02-01T19:00:23.465589Z","end":"2026-02-01T19:00:23.798307Z","steps":["trace[1354958292] 'process raft request' (duration: 332.412382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:23.798427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:23.46558Z","time spent":"332.801441ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:00:25.929235Z","caller":"traceutil/trace.go:171","msg":"trace[1625772712] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"125.539203ms","start":"2026-02-01T19:00:25.80368Z","end":"2026-02-01T19:00:25.929219Z","steps":["trace[1625772712] 'process raft request' (duration: 125.42301ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:26.07677Z","caller":"traceutil/trace.go:171","msg":"trace[1059573897] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"143.459653ms","start":"2026-02-01T19:00:25.933279Z","end":"2026-02-01T19:00:26.076739Z","steps":["trace[1059573897] 'process raft request' (duration: 126.800933ms)","trace[1059573897] 'compare' (duration: 16.497365ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:00:26.367936Z","caller":"traceutil/trace.go:171","msg":"trace[1341324811] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"432.918863ms","start":"2026-02-01T19:00:25.934993Z","end":"2026-02-01T19:00:26.367912Z","steps":["trace[1341324811] 'process raft request' (duration: 432.872672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:26.368074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:25.934986Z","time spent":"433.020637ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:00:26.368194Z","caller":"traceutil/trace.go:171","msg":"trace[1059738557] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"434.438139ms","start":"2026-02-01T19:00:25.933742Z","end":"2026-02-01T19:00:26.36818Z","steps":["trace[1059738557] 'process raft request' (duration: 418.505795ms)","trace[1059738557] 'compare' (duration: 15.499784ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:26.368263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:25.933737Z","time spent":"434.47866ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:00:26.60762Z","caller":"traceutil/trace.go:171","msg":"trace[1787129158] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:493; }","duration":"158.502256ms","start":"2026-02-01T19:00:26.449101Z","end":"2026-02-01T19:00:26.607603Z","steps":["trace[1787129158] 'read index received' (duration: 155.065498ms)","trace[1787129158] 'applied index is now lower than readState.Index' (duration: 3.436008ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:26.607714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.601248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:26.607737Z","caller":"traceutil/trace.go:171","msg":"trace[1623264243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:480; }","duration":"158.66957ms","start":"2026-02-01T19:00:26.449061Z","end":"2026-02-01T19:00:26.60773Z","steps":["trace[1623264243] 'agreement among raft nodes before linearized reading' (duration: 158.605148ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:26.607907Z","caller":"traceutil/trace.go:171","msg":"trace[757289812] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"184.286103ms","start":"2026-02-01T19:00:26.423614Z","end":"2026-02-01T19:00:26.6079Z","steps":["trace[757289812] 'process raft request' (duration: 180.59088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:32.485695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.330254201s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-d77z4\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-01T19:00:32.485764Z","caller":"traceutil/trace.go:171","msg":"trace[211842783] range","detail":"{range_begin:/registry/pods/kube-system/cilium-d77z4; range_end:; response_count:1; response_revision:493; }","duration":"1.330367274s","start":"2026-02-01T19:00:31.15538Z","end":"2026-02-01T19:00:32.485747Z","steps":["trace[211842783] 'range keys from in-memory index tree' (duration: 1.330141128s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:32.4858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:31.155357Z","time spent":"1.330435095s","remote":"127.0.0.1:58494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16680,"request content":"key:\"/registry/pods/kube-system/cilium-d77z4\" "} {"level":"warn","ts":"2026-02-01T19:00:32.485893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.251411ms","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-01T19:00:32.485955Z","caller":"traceutil/trace.go:171","msg":"trace[281887297] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:493; }","duration":"540.354004ms","start":"2026-02-01T19:00:31.945584Z","end":"2026-02-01T19:00:32.485938Z","steps":["trace[281887297] 'range keys from in-memory index tree' (duration: 540.094698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:32.485993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:31.945566Z","time spent":"540.419165ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T19:00:32.486236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.805982ms","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-01T19:00:32.486265Z","caller":"traceutil/trace.go:171","msg":"trace[1835019225] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:493; }","duration":"375.857123ms","start":"2026-02-01T19:00:32.110399Z","end":"2026-02-01T19:00:32.486256Z","steps":["trace[1835019225] 'range keys from in-memory index tree' (duration: 375.71328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:32.48629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:32.110383Z","time spent":"375.900634ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-01T19:00:32.526579Z","caller":"traceutil/trace.go:171","msg":"trace[1485949233] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:508; }","duration":"136.968974ms","start":"2026-02-01T19:00:32.389589Z","end":"2026-02-01T19:00:32.526558Z","steps":["trace[1485949233] 'read index received' (duration: 82.88289ms)","trace[1485949233] 'applied index is now lower than readState.Index' (duration: 54.084344ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:32.527921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.310534ms","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-01T19:00:32.527964Z","caller":"traceutil/trace.go:171","msg":"trace[1485500387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:494; }","duration":"138.392156ms","start":"2026-02-01T19:00:32.38956Z","end":"2026-02-01T19:00:32.527952Z","steps":["trace[1485500387] 'agreement among raft nodes before linearized reading' (duration: 138.251073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:32.544877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.259615ms","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-01T19:00:32.544944Z","caller":"traceutil/trace.go:171","msg":"trace[257694887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:496; }","duration":"153.347476ms","start":"2026-02-01T19:00:32.39158Z","end":"2026-02-01T19:00:32.544928Z","steps":["trace[257694887] 'agreement among raft nodes before linearized reading' (duration: 153.246794ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:34.31553Z","caller":"traceutil/trace.go:171","msg":"trace[301015735] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"136.109935ms","start":"2026-02-01T19:00:34.179399Z","end":"2026-02-01T19:00:34.315509Z","steps":["trace[301015735] 'process raft request' (duration: 133.661569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:35.775482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.026929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:35.775536Z","caller":"traceutil/trace.go:171","msg":"trace[1611575072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"330.122271ms","start":"2026-02-01T19:00:35.445402Z","end":"2026-02-01T19:00:35.775524Z","steps":["trace[1611575072] 'range keys from in-memory index tree' (duration: 329.971277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:35.775562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:35.445384Z","time spent":"330.172322ms","remote":"127.0.0.1:58288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T19:00:37.60703Z","caller":"traceutil/trace.go:171","msg":"trace[663218847] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:576; }","duration":"161.335169ms","start":"2026-02-01T19:00:37.445673Z","end":"2026-02-01T19:00:37.607008Z","steps":["trace[663218847] 'read index received' (duration: 161.185676ms)","trace[663218847] 'applied index is now lower than readState.Index' (duration: 148.663µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:37.609004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.455683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:37.609039Z","caller":"traceutil/trace.go:171","msg":"trace[259104413] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"163.394097ms","start":"2026-02-01T19:00:37.445635Z","end":"2026-02-01T19:00:37.609029Z","steps":["trace[259104413] 'agreement among raft nodes before linearized reading' (duration: 161.447212ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:37.609265Z","caller":"traceutil/trace.go:171","msg":"trace[1074431649] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"234.896588ms","start":"2026-02-01T19:00:37.37436Z","end":"2026-02-01T19:00:37.609257Z","steps":["trace[1074431649] 'process raft request' (duration: 232.547564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:45.728555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.934007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-01T19:00:45.7287Z","caller":"traceutil/trace.go:171","msg":"trace[166439357] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"359.649914ms","start":"2026-02-01T19:00:45.369027Z","end":"2026-02-01T19:00:45.728677Z","steps":["trace[166439357] 'process raft request' (duration: 82.469031ms)","trace[166439357] 'compare' (duration: 276.747122ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:45.728802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:45.369012Z","time spent":"359.763006ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T19:00:45.731689Z","caller":"traceutil/trace.go:171","msg":"trace[1744351952] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:670; }","duration":"359.141221ms","start":"2026-02-01T19:00:45.37253Z","end":"2026-02-01T19:00:45.731671Z","steps":["trace[1744351952] 'read index received' (duration: 78.974121ms)","trace[1744351952] 'applied index is now lower than readState.Index' (duration: 280.16569ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:00:45.73194Z","caller":"traceutil/trace.go:171","msg":"trace[389946668] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"359.4844ms","start":"2026-02-01T19:00:45.372445Z","end":"2026-02-01T19:00:45.731929Z","steps":["trace[389946668] 'process raft request' (duration: 359.130122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:45.732026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:45.372424Z","time spent":"359.552612ms","remote":"127.0.0.1:58762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4191,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T19:00:45.732271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.736714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-tp2zb\" ","response":"range_response_count:1 size:4806"} {"level":"info","ts":"2026-02-01T19:00:45.732308Z","caller":"traceutil/trace.go:171","msg":"trace[1935530394] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-tp2zb; range_end:; response_count:1; response_revision:653; }","duration":"359.801957ms","start":"2026-02-01T19:00:45.372496Z","end":"2026-02-01T19:00:45.732298Z","steps":["trace[1935530394] 'agreement among raft nodes before linearized reading' (duration: 359.660454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:45.732342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:45.372491Z","time spent":"359.845178ms","remote":"127.0.0.1:58494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4830,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-tp2zb\" "} {"level":"warn","ts":"2026-02-01T19:00:45.732576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.862592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:45.732615Z","caller":"traceutil/trace.go:171","msg":"trace[1885383560] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"286.931384ms","start":"2026-02-01T19:00:45.445672Z","end":"2026-02-01T19:00:45.732604Z","steps":["trace[1885383560] 'agreement among raft nodes before linearized reading' (duration: 286.878112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:46.911462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.141906ms","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-01T19:00:46.911519Z","caller":"traceutil/trace.go:171","msg":"trace[1453441805] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:661; }","duration":"852.227498ms","start":"2026-02-01T19:00:46.059277Z","end":"2026-02-01T19:00:46.911505Z","steps":["trace[1453441805] 'range keys from in-memory index tree' (duration: 852.026933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:46.911545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:46.059263Z","time spent":"852.275989ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T19:00:46.912169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.89387ms","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-01T19:00:46.912198Z","caller":"traceutil/trace.go:171","msg":"trace[461784619] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:661; }","duration":"256.978341ms","start":"2026-02-01T19:00:46.655214Z","end":"2026-02-01T19:00:46.912192Z","steps":["trace[461784619] 'range keys from in-memory index tree' (duration: 256.770976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:46.912304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.11645ms","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-01T19:00:46.912327Z","caller":"traceutil/trace.go:171","msg":"trace[1589297744] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:661; }","duration":"252.183501ms","start":"2026-02-01T19:00:46.660138Z","end":"2026-02-01T19:00:46.912322Z","steps":["trace[1589297744] 'range keys from in-memory index tree' (duration: 252.006357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:46.91259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.784623ms","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-01T19:00:46.912615Z","caller":"traceutil/trace.go:171","msg":"trace[1324145802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:661; }","duration":"212.833785ms","start":"2026-02-01T19:00:46.699776Z","end":"2026-02-01T19:00:46.91261Z","steps":["trace[1324145802] 'range keys from in-memory index tree' (duration: 212.672032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:46.91272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.58923ms","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-01T19:00:46.912744Z","caller":"traceutil/trace.go:171","msg":"trace[1877844867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:661; }","duration":"216.614511ms","start":"2026-02-01T19:00:46.696125Z","end":"2026-02-01T19:00:46.91274Z","steps":["trace[1877844867] 'range keys from in-memory index tree' (duration: 216.512949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:47.278815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.132034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T19:00:47.278974Z","caller":"traceutil/trace.go:171","msg":"trace[978008414] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"353.640856ms","start":"2026-02-01T19:00:46.925315Z","end":"2026-02-01T19:00:47.278955Z","steps":["trace[978008414] 'process raft request' (duration: 150.313238ms)","trace[978008414] 'compare' (duration: 203.012571ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:00:47.279031Z","caller":"traceutil/trace.go:171","msg":"trace[656440289] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"135.250715ms","start":"2026-02-01T19:00:47.14377Z","end":"2026-02-01T19:00:47.27902Z","steps":["trace[656440289] 'process raft request' (duration: 135.226914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:47.279079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:46.925295Z","time spent":"353.731508ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:00:47.279188Z","caller":"traceutil/trace.go:171","msg":"trace[723540032] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"353.78592ms","start":"2026-02-01T19:00:46.925398Z","end":"2026-02-01T19:00:47.279184Z","steps":["trace[723540032] 'process raft request' (duration: 353.485663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:47.279219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:46.925393Z","time spent":"353.80874ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:00:47.2793Z","caller":"traceutil/trace.go:171","msg":"trace[2058195185] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"353.614176ms","start":"2026-02-01T19:00:46.92568Z","end":"2026-02-01T19:00:47.279294Z","steps":["trace[2058195185] 'process raft request' (duration: 353.255408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:47.279334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:46.925673Z","time spent":"353.642556ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:00:47.279409Z","caller":"traceutil/trace.go:171","msg":"trace[893569687] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"352.861738ms","start":"2026-02-01T19:00:46.92654Z","end":"2026-02-01T19:00:47.279402Z","steps":["trace[893569687] 'process raft request' (duration: 352.432919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:47.279469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:46.926517Z","time spent":"352.936621ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T19:00:47.554861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.842111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-01T19:00:47.554955Z","caller":"traceutil/trace.go:171","msg":"trace[1481856246] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:686; }","duration":"304.315251ms","start":"2026-02-01T19:00:47.250616Z","end":"2026-02-01T19:00:47.554931Z","steps":["trace[1481856246] 'read index received' (duration: 135.311956ms)","trace[1481856246] 'applied index is now lower than readState.Index' (duration: 169.001975ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:00:47.554957Z","caller":"traceutil/trace.go:171","msg":"trace[1423421822] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"305.654212ms","start":"2026-02-01T19:00:47.249282Z","end":"2026-02-01T19:00:47.554937Z","steps":["trace[1423421822] 'process raft request' (duration: 136.678488ms)","trace[1423421822] 'compare' (duration: 168.633576ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:47.555208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:47.249265Z","time spent":"305.736024ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-01T19:00:47.555322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.69993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-tp2zb\" ","response":"range_response_count:1 size:4818"} {"level":"info","ts":"2026-02-01T19:00:47.555344Z","caller":"traceutil/trace.go:171","msg":"trace[498074203] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-tp2zb; range_end:; response_count:1; response_revision:668; }","duration":"304.752391ms","start":"2026-02-01T19:00:47.250585Z","end":"2026-02-01T19:00:47.555338Z","steps":["trace[498074203] 'agreement among raft nodes before linearized reading' (duration: 304.606888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:47.555366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:47.250568Z","time spent":"304.792532ms","remote":"127.0.0.1:58494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4842,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-tp2zb\" "} {"level":"warn","ts":"2026-02-01T19:00:47.555486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.177075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.200\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-01T19:00:47.555507Z","caller":"traceutil/trace.go:171","msg":"trace[2136052909] range","detail":"{range_begin:/registry/masterleases/162.253.55.200; range_end:; response_count:1; response_revision:668; }","duration":"292.237686ms","start":"2026-02-01T19:00:47.263263Z","end":"2026-02-01T19:00:47.555501Z","steps":["trace[2136052909] 'agreement among raft nodes before linearized reading' (duration: 292.136614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:47.555595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.143421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:47.555654Z","caller":"traceutil/trace.go:171","msg":"trace[1171810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"113.226733ms","start":"2026-02-01T19:00:47.442423Z","end":"2026-02-01T19:00:47.555649Z","steps":["trace[1171810] 'agreement among raft nodes before linearized reading' (duration: 113.159162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:47.998163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.511969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T19:00:47.998256Z","caller":"traceutil/trace.go:171","msg":"trace[2012165385] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"375.548816ms","start":"2026-02-01T19:00:47.622696Z","end":"2026-02-01T19:00:47.998245Z","steps":["trace[2012165385] 'process raft request' (duration: 247.906244ms)","trace[2012165385] 'compare' (duration: 127.421107ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:47.998288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:47.622676Z","time spent":"375.598558ms","remote":"127.0.0.1:58314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:00:51.525666Z","caller":"traceutil/trace.go:171","msg":"trace[1846581664] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"232.484664ms","start":"2026-02-01T19:00:51.293164Z","end":"2026-02-01T19:00:51.525648Z","steps":["trace[1846581664] 'process raft request' (duration: 232.389602ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:51.527621Z","caller":"traceutil/trace.go:171","msg":"trace[1847501367] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:698; }","duration":"233.991868ms","start":"2026-02-01T19:00:51.293618Z","end":"2026-02-01T19:00:51.52761Z","steps":["trace[1847501367] 'read index received' (duration: 233.986938ms)","trace[1847501367] 'applied index is now lower than readState.Index' (duration: 3.74µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:51.576094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.456429ms","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-01T19:00:51.576129Z","caller":"traceutil/trace.go:171","msg":"trace[361958011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:678; }","duration":"282.513321ms","start":"2026-02-01T19:00:51.293605Z","end":"2026-02-01T19:00:51.576119Z","steps":["trace[361958011] 'agreement among raft nodes before linearized reading' (duration: 234.05442ms)","trace[361958011] 'range keys from in-memory index tree' (duration: 48.348869ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:00:51.576465Z","caller":"traceutil/trace.go:171","msg":"trace[824047081] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"281.043767ms","start":"2026-02-01T19:00:51.29541Z","end":"2026-02-01T19:00:51.576454Z","steps":["trace[824047081] 'process raft request' (duration: 246.723725ms)","trace[824047081] 'compare' (duration: 34.165659ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:51.576613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.439452ms","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-01T19:00:51.576677Z","caller":"traceutil/trace.go:171","msg":"trace[562870357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:679; }","duration":"279.533714ms","start":"2026-02-01T19:00:51.297126Z","end":"2026-02-01T19:00:51.57666Z","steps":["trace[562870357] 'agreement among raft nodes before linearized reading' (duration: 279.280508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:51.576694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.932718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:51.576717Z","caller":"traceutil/trace.go:171","msg":"trace[477273275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:679; }","duration":"134.978559ms","start":"2026-02-01T19:00:51.441732Z","end":"2026-02-01T19:00:51.576711Z","steps":["trace[477273275] 'agreement among raft nodes before linearized reading' (duration: 134.942418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:51.860132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.490987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T19:00:51.860196Z","caller":"traceutil/trace.go:171","msg":"trace[1699300293] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"278.596882ms","start":"2026-02-01T19:00:51.581588Z","end":"2026-02-01T19:00:51.860185Z","steps":["trace[1699300293] 'process raft request' (duration: 127.985192ms)","trace[1699300293] 'compare' (duration: 150.316833ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:00:52.591105Z","caller":"traceutil/trace.go:171","msg":"trace[1760038491] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:701; }","duration":"148.32537ms","start":"2026-02-01T19:00:52.442763Z","end":"2026-02-01T19:00:52.591088Z","steps":["trace[1760038491] 'read index received' (duration: 145.369513ms)","trace[1760038491] 'applied index is now lower than readState.Index' (duration: 2.954217ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:52.591196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.417182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:52.591219Z","caller":"traceutil/trace.go:171","msg":"trace[549517288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"148.469832ms","start":"2026-02-01T19:00:52.44274Z","end":"2026-02-01T19:00:52.59121Z","steps":["trace[549517288] 'agreement among raft nodes before linearized reading' (duration: 148.417141ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:53.073256Z","caller":"traceutil/trace.go:171","msg":"trace[17956285] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"100.913362ms","start":"2026-02-01T19:00:52.972317Z","end":"2026-02-01T19:00:53.07323Z","steps":["trace[17956285] 'process raft request' (duration: 100.722178ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:09.958723Z","caller":"traceutil/trace.go:171","msg":"trace[378905423] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:758; }","duration":"259.024551ms","start":"2026-02-01T19:01:09.699683Z","end":"2026-02-01T19:01:09.958707Z","steps":["trace[378905423] 'read index received' (duration: 258.881118ms)","trace[378905423] 'applied index is now lower than readState.Index' (duration: 142.943µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:01:09.958965Z","caller":"traceutil/trace.go:171","msg":"trace[2099489815] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"281.770093ms","start":"2026-02-01T19:01:09.677188Z","end":"2026-02-01T19:01:09.958958Z","steps":["trace[2099489815] 'process raft request' (duration: 281.422126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:09.959116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.42002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-01T19:01:09.959134Z","caller":"traceutil/trace.go:171","msg":"trace[1234741368] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:734; }","duration":"259.46557ms","start":"2026-02-01T19:01:09.699663Z","end":"2026-02-01T19:01:09.959129Z","steps":["trace[1234741368] 'agreement among raft nodes before linearized reading' (duration: 259.387729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:09.959218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.499561ms","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-01T19:01:09.959231Z","caller":"traceutil/trace.go:171","msg":"trace[2006299893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:734; }","duration":"255.522052ms","start":"2026-02-01T19:01:09.703704Z","end":"2026-02-01T19:01:09.959226Z","steps":["trace[2006299893] 'agreement among raft nodes before linearized reading' (duration: 255.487322ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:10.596218Z","caller":"traceutil/trace.go:171","msg":"trace[1822941318] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"631.207022ms","start":"2026-02-01T19:01:09.964992Z","end":"2026-02-01T19:01:10.596199Z","steps":["trace[1822941318] 'process raft request' (duration: 621.713087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:10.596363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:09.964969Z","time spent":"631.319694ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:01:11.056571Z","caller":"traceutil/trace.go:171","msg":"trace[1126191278] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:759; }","duration":"821.606276ms","start":"2026-02-01T19:01:10.234951Z","end":"2026-02-01T19:01:11.056557Z","steps":["trace[1126191278] 'read index received' (duration: 351.84227ms)","trace[1126191278] 'applied index is now lower than readState.Index' (duration: 469.763346ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:01:11.056721Z","caller":"traceutil/trace.go:171","msg":"trace[847461675] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"1.091507824s","start":"2026-02-01T19:01:09.965206Z","end":"2026-02-01T19:01:11.056713Z","steps":["trace[847461675] 'process raft request' (duration: 1.091206077s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:11.056779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:09.965191Z","time spent":"1.091544464s","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T19:01:11.056826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.856643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T19:01:11.056875Z","caller":"traceutil/trace.go:171","msg":"trace[301066561] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:738; }","duration":"821.951264ms","start":"2026-02-01T19:01:10.234912Z","end":"2026-02-01T19:01:11.056863Z","steps":["trace[301066561] 'agreement among raft nodes before linearized reading' (duration: 821.854532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:11.056882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.339728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:01:11.0569Z","caller":"traceutil/trace.go:171","msg":"trace[1452440114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:738; }","duration":"613.358319ms","start":"2026-02-01T19:01:10.443537Z","end":"2026-02-01T19:01:11.056895Z","steps":["trace[1452440114] 'agreement among raft nodes before linearized reading' (duration: 613.329018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:11.056907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:10.234892Z","time spent":"822.006316ms","remote":"127.0.0.1:58748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-01T19:01:11.056914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:10.443456Z","time spent":"613.454671ms","remote":"127.0.0.1:58290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T19:01:11.057045Z","caller":"traceutil/trace.go:171","msg":"trace[1589807862] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"1.09177771s","start":"2026-02-01T19:01:09.965258Z","end":"2026-02-01T19:01:11.057036Z","steps":["trace[1589807862] 'process raft request' (duration: 1.091236598s)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:11.057095Z","caller":"traceutil/trace.go:171","msg":"trace[268917591] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"478.943133ms","start":"2026-02-01T19:01:10.578147Z","end":"2026-02-01T19:01:11.05709Z","steps":["trace[268917591] 'process raft request' (duration: 478.388251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:11.057108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:09.965254Z","time spent":"1.091813111s","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T19:01:11.057122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:10.57813Z","time spent":"478.977293ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:01:14.809196Z","caller":"traceutil/trace.go:171","msg":"trace[1337386716] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"190.478548ms","start":"2026-02-01T19:01:14.618695Z","end":"2026-02-01T19:01:14.809174Z","steps":["trace[1337386716] 'process raft request' (duration: 190.341885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:15.60036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.363389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T19:01:15.600433Z","caller":"traceutil/trace.go:171","msg":"trace[1553962491] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:746; }","duration":"676.480951ms","start":"2026-02-01T19:01:14.923935Z","end":"2026-02-01T19:01:15.600416Z","steps":["trace[1553962491] 'count revisions from in-memory index tree' (duration: 676.223125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:15.600477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:14.923914Z","time spent":"676.554093ms","remote":"127.0.0.1:58568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-02-01T19:01:15.601033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.394211ms","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-01T19:01:15.601063Z","caller":"traceutil/trace.go:171","msg":"trace[929347135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:746; }","duration":"522.460742ms","start":"2026-02-01T19:01:15.078593Z","end":"2026-02-01T19:01:15.601054Z","steps":["trace[929347135] 'range keys from in-memory index tree' (duration: 522.162815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:15.601089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:15.078576Z","time spent":"522.507183ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T19:01:15.601305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.44949ms","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-01T19:01:15.601342Z","caller":"traceutil/trace.go:171","msg":"trace[1426962869] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:746; }","duration":"521.495731ms","start":"2026-02-01T19:01:15.079838Z","end":"2026-02-01T19:01:15.601334Z","steps":["trace[1426962869] 'range keys from in-memory index tree' (duration: 521.380668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:15.601376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:15.079834Z","time spent":"521.531642ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T19:01:15.601583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.297856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T19:01:15.601611Z","caller":"traceutil/trace.go:171","msg":"trace[580503701] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:746; }","duration":"374.327107ms","start":"2026-02-01T19:01:15.227276Z","end":"2026-02-01T19:01:15.601603Z","steps":["trace[580503701] 'count revisions from in-memory index tree' (duration: 374.238895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:15.601639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:15.227227Z","time spent":"374.404678ms","remote":"127.0.0.1:58792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-01T19:01:15.601849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.072698ms","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-01T19:01:15.601875Z","caller":"traceutil/trace.go:171","msg":"trace[1678202484] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:746; }","duration":"289.13863ms","start":"2026-02-01T19:01:15.312729Z","end":"2026-02-01T19:01:15.601867Z","steps":["trace[1678202484] 'range keys from in-memory index tree' (duration: 288.956505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:15.602105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.9092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:01:15.602134Z","caller":"traceutil/trace.go:171","msg":"trace[981695555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:746; }","duration":"155.967351ms","start":"2026-02-01T19:01:15.446157Z","end":"2026-02-01T19:01:15.602124Z","steps":["trace[981695555] 'range keys from in-memory index tree' (duration: 155.863858ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:15.793249Z","caller":"traceutil/trace.go:171","msg":"trace[541595605] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"185.904796ms","start":"2026-02-01T19:01:15.607323Z","end":"2026-02-01T19:01:15.793228Z","steps":["trace[541595605] 'process raft request' (duration: 185.793774ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:16.01725Z","caller":"traceutil/trace.go:171","msg":"trace[1216137230] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"408.323484ms","start":"2026-02-01T19:01:15.608911Z","end":"2026-02-01T19:01:16.017234Z","steps":["trace[1216137230] 'process raft request' (duration: 364.619449ms)","trace[1216137230] 'compare' (duration: 43.49523ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:01:16.017366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:15.608901Z","time spent":"408.388315ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:01:16.017566Z","caller":"traceutil/trace.go:171","msg":"trace[718257414] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"407.567167ms","start":"2026-02-01T19:01:15.609993Z","end":"2026-02-01T19:01:16.017561Z","steps":["trace[718257414] 'process raft request' (duration: 407.099526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:16.01762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:15.609985Z","time spent":"407.614678ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:01:16.248999Z","caller":"traceutil/trace.go:171","msg":"trace[754527935] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"225.695451ms","start":"2026-02-01T19:01:16.023285Z","end":"2026-02-01T19:01:16.248981Z","steps":["trace[754527935] 'process raft request' (duration: 185.118018ms)","trace[754527935] 'compare' (duration: 40.504301ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:01:28.583323Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085375204326205,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-01T19:01:28.61067Z","caller":"traceutil/trace.go:171","msg":"trace[877999685] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"528.073838ms","start":"2026-02-01T19:01:28.082579Z","end":"2026-02-01T19:01:28.610653Z","steps":["trace[877999685] 'process raft request' (duration: 527.978756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:28.610765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:28.082563Z","time spent":"528.145559ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:01:28.613417Z","caller":"traceutil/trace.go:171","msg":"trace[838566855] linearizableReadLoop","detail":"{readStateIndex:870; appliedIndex:868; }","duration":"530.574484ms","start":"2026-02-01T19:01:28.082833Z","end":"2026-02-01T19:01:28.613408Z","steps":["trace[838566855] 'read index received' (duration: 527.789091ms)","trace[838566855] 'applied index is now lower than readState.Index' (duration: 2.784863ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:01:28.613566Z","caller":"traceutil/trace.go:171","msg":"trace[71353832] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"450.763057ms","start":"2026-02-01T19:01:28.162796Z","end":"2026-02-01T19:01:28.613559Z","steps":["trace[71353832] 'process raft request' (duration: 450.567763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:28.613609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:28.162778Z","time spent":"450.803769ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T19:01:28.613761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.924292ms","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-01T19:01:28.613782Z","caller":"traceutil/trace.go:171","msg":"trace[1200409385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:839; }","duration":"530.953852ms","start":"2026-02-01T19:01:28.082822Z","end":"2026-02-01T19:01:28.613776Z","steps":["trace[1200409385] 'agreement among raft nodes before linearized reading' (duration: 530.87708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:28.613809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:28.082817Z","time spent":"530.987504ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T19:01:28.613931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.360401ms","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-01T19:01:28.613957Z","caller":"traceutil/trace.go:171","msg":"trace[1079110312] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:839; }","duration":"304.411252ms","start":"2026-02-01T19:01:28.30954Z","end":"2026-02-01T19:01:28.613951Z","steps":["trace[1079110312] 'agreement among raft nodes before linearized reading' (duration: 304.356961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:28.613973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:28.309526Z","time spent":"304.443402ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T19:01:28.614083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.485852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:01:28.614105Z","caller":"traceutil/trace.go:171","msg":"trace[905902736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:839; }","duration":"164.529412ms","start":"2026-02-01T19:01:28.449571Z","end":"2026-02-01T19:01:28.6141Z","steps":["trace[905902736] 'agreement among raft nodes before linearized reading' (duration: 164.497322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:30.385327Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085375204326215,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T19:01:30.886182Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085375204326215,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T19:01:31.012288Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.164149439s","expected-duration":"1s"} {"level":"info","ts":"2026-02-01T19:01:31.012522Z","caller":"traceutil/trace.go:171","msg":"trace[1043886350] linearizableReadLoop","detail":"{readStateIndex:874; appliedIndex:873; }","duration":"1.127373002s","start":"2026-02-01T19:01:29.885122Z","end":"2026-02-01T19:01:31.012495Z","steps":["trace[1043886350] 'read index received' (duration: 1.127249278s)","trace[1043886350] 'applied index is now lower than readState.Index' (duration: 123.214µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:01:31.012684Z","caller":"traceutil/trace.go:171","msg":"trace[761682093] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"1.16460843s","start":"2026-02-01T19:01:29.84807Z","end":"2026-02-01T19:01:31.012679Z","steps":["trace[761682093] 'process raft request' (duration: 1.164348703s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:31.012771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:29.84806Z","time spent":"1.16464302s","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T19:01:31.012898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.12777539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T19:01:31.01292Z","caller":"traceutil/trace.go:171","msg":"trace[871303550] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:843; }","duration":"1.127821002s","start":"2026-02-01T19:01:29.885094Z","end":"2026-02-01T19:01:31.012915Z","steps":["trace[871303550] 'agreement among raft nodes before linearized reading' (duration: 1.127777091s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:31.012934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:29.885079Z","time spent":"1.127851582s","remote":"127.0.0.1:58634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-01T19:01:31.013019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.36702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:01:31.013037Z","caller":"traceutil/trace.go:171","msg":"trace[1351783395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:843; }","duration":"566.418652ms","start":"2026-02-01T19:01:30.446614Z","end":"2026-02-01T19:01:31.013032Z","steps":["trace[1351783395] 'agreement among raft nodes before linearized reading' (duration: 566.394041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:31.013049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:30.446596Z","time spent":"566.450723ms","remote":"127.0.0.1:58288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T19:01:31.013181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.770407ms","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-01T19:01:31.013198Z","caller":"traceutil/trace.go:171","msg":"trace[184710298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:843; }","duration":"107.808678ms","start":"2026-02-01T19:01:30.905386Z","end":"2026-02-01T19:01:31.013194Z","steps":["trace[184710298] 'agreement among raft nodes before linearized reading' (duration: 107.750817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:31.013276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.457386ms","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-01T19:01:31.013292Z","caller":"traceutil/trace.go:171","msg":"trace[401635001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:843; }","duration":"388.483626ms","start":"2026-02-01T19:01:30.624805Z","end":"2026-02-01T19:01:31.013288Z","steps":["trace[401635001] 'agreement among raft nodes before linearized reading' (duration: 388.443765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:31.013303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:30.624799Z","time spent":"388.500976ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T19:01:31.013371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.826299ms","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-01T19:01:31.013386Z","caller":"traceutil/trace.go:171","msg":"trace[1615518121] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:843; }","duration":"390.85817ms","start":"2026-02-01T19:01:30.622525Z","end":"2026-02-01T19:01:31.013383Z","steps":["trace[1615518121] 'agreement among raft nodes before linearized reading' (duration: 390.820779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:31.013397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:30.622516Z","time spent":"390.878311ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T19:01:31.013468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.443433ms","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-01T19:01:31.013479Z","caller":"traceutil/trace.go:171","msg":"trace[2036285015] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:843; }","duration":"399.483164ms","start":"2026-02-01T19:01:30.613993Z","end":"2026-02-01T19:01:31.013476Z","steps":["trace[2036285015] 'agreement among raft nodes before linearized reading' (duration: 399.450674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:31.01349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:30.613978Z","time spent":"399.509955ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T19:01:31.013543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.22836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T19:01:31.013555Z","caller":"traceutil/trace.go:171","msg":"trace[996564121] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:843; }","duration":"467.302601ms","start":"2026-02-01T19:01:30.546249Z","end":"2026-02-01T19:01:31.013552Z","steps":["trace[996564121] 'agreement among raft nodes before linearized reading' (duration: 467.282611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:31.013573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:30.54623Z","time spent":"467.339371ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-02-01T19:01:33.199458Z","caller":"traceutil/trace.go:171","msg":"trace[773375402] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"181.158818ms","start":"2026-02-01T19:01:33.018283Z","end":"2026-02-01T19:01:33.199441Z","steps":["trace[773375402] 'process raft request' (duration: 181.048636ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:33.199703Z","caller":"traceutil/trace.go:171","msg":"trace[1302462336] linearizableReadLoop","detail":"{readStateIndex:881; appliedIndex:881; }","duration":"124.255847ms","start":"2026-02-01T19:01:33.075442Z","end":"2026-02-01T19:01:33.199698Z","steps":["trace[1302462336] 'read index received' (duration: 124.253587ms)","trace[1302462336] 'applied index is now lower than readState.Index' (duration: 1.9µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:01:33.199953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.500513ms","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-01T19:01:33.199974Z","caller":"traceutil/trace.go:171","msg":"trace[554094338] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:849; }","duration":"124.551423ms","start":"2026-02-01T19:01:33.075418Z","end":"2026-02-01T19:01:33.199969Z","steps":["trace[554094338] 'agreement among raft nodes before linearized reading' (duration: 124.496922ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:37.497079Z","caller":"traceutil/trace.go:171","msg":"trace[1527564347] linearizableReadLoop","detail":"{readStateIndex:891; appliedIndex:890; }","duration":"217.046846ms","start":"2026-02-01T19:01:37.280016Z","end":"2026-02-01T19:01:37.497063Z","steps":["trace[1527564347] 'read index received' (duration: 216.950864ms)","trace[1527564347] 'applied index is now lower than readState.Index' (duration: 95.152µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:01:37.497332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.305002ms","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-01T19:01:37.497357Z","caller":"traceutil/trace.go:171","msg":"trace[92964126] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:858; }","duration":"217.360073ms","start":"2026-02-01T19:01:37.27999Z","end":"2026-02-01T19:01:37.49735Z","steps":["trace[92964126] 'agreement among raft nodes before linearized reading' (duration: 217.296131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:37.497547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.142551ms","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-01T19:01:37.497567Z","caller":"traceutil/trace.go:171","msg":"trace[139396972] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:858; }","duration":"194.175752ms","start":"2026-02-01T19:01:37.303385Z","end":"2026-02-01T19:01:37.497561Z","steps":["trace[139396972] 'agreement among raft nodes before linearized reading' (duration: 194.09455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:37.497844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.096816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-01T19:01:37.497865Z","caller":"traceutil/trace.go:171","msg":"trace[972473640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:858; }","duration":"192.126046ms","start":"2026-02-01T19:01:37.305733Z","end":"2026-02-01T19:01:37.497859Z","steps":["trace[972473640] 'agreement among raft nodes before linearized reading' (duration: 192.073245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:37.498015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.268559ms","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-01T19:01:37.498036Z","caller":"traceutil/trace.go:171","msg":"trace[1462007780] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:858; }","duration":"192.29872ms","start":"2026-02-01T19:01:37.305731Z","end":"2026-02-01T19:01:37.498029Z","steps":["trace[1462007780] 'agreement among raft nodes before linearized reading' (duration: 192.229428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:37.498143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.422082ms","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-01T19:01:37.49816Z","caller":"traceutil/trace.go:171","msg":"trace[109371920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:858; }","duration":"192.452804ms","start":"2026-02-01T19:01:37.305702Z","end":"2026-02-01T19:01:37.498154Z","steps":["trace[109371920] 'agreement among raft nodes before linearized reading' (duration: 192.408043ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:37.739103Z","caller":"traceutil/trace.go:171","msg":"trace[1043684074] linearizableReadLoop","detail":"{readStateIndex:892; appliedIndex:891; }","duration":"238.371207ms","start":"2026-02-01T19:01:37.500716Z","end":"2026-02-01T19:01:37.739087Z","steps":["trace[1043684074] 'read index received' (duration: 216.226688ms)","trace[1043684074] 'applied index is now lower than readState.Index' (duration: 22.143859ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:01:37.739169Z","caller":"traceutil/trace.go:171","msg":"trace[1307650495] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"240.380572ms","start":"2026-02-01T19:01:37.498783Z","end":"2026-02-01T19:01:37.739164Z","steps":["trace[1307650495] 'process raft request' (duration: 218.216252ms)","trace[1307650495] 'compare' (duration: 22.021767ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:01:37.739316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.589212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:01:37.739333Z","caller":"traceutil/trace.go:171","msg":"trace[551189342] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:859; }","duration":"238.642652ms","start":"2026-02-01T19:01:37.500685Z","end":"2026-02-01T19:01:37.739328Z","steps":["trace[551189342] 'agreement among raft nodes before linearized reading' (duration: 238.588171ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:37.754201Z","caller":"traceutil/trace.go:171","msg":"trace[1784338768] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"248.74831ms","start":"2026-02-01T19:01:37.505426Z","end":"2026-02-01T19:01:37.754174Z","steps":["trace[1784338768] 'process raft request' (duration: 248.520045ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:37.754247Z","caller":"traceutil/trace.go:171","msg":"trace[1301091762] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"247.687556ms","start":"2026-02-01T19:01:37.506538Z","end":"2026-02-01T19:01:37.754225Z","steps":["trace[1301091762] 'process raft request' (duration: 247.544133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:37.75446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.663105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:01:37.754487Z","caller":"traceutil/trace.go:171","msg":"trace[588793715] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:864; }","duration":"147.732826ms","start":"2026-02-01T19:01:37.606746Z","end":"2026-02-01T19:01:37.754478Z","steps":["trace[588793715] 'agreement among raft nodes before linearized reading' (duration: 147.683255ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:37.75457Z","caller":"traceutil/trace.go:171","msg":"trace[1154235286] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"247.992653ms","start":"2026-02-01T19:01:37.506572Z","end":"2026-02-01T19:01:37.754565Z","steps":["trace[1154235286] 'process raft request' (duration: 247.534042ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:37.754656Z","caller":"traceutil/trace.go:171","msg":"trace[268842752] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"238.51819ms","start":"2026-02-01T19:01:37.516131Z","end":"2026-02-01T19:01:37.754649Z","steps":["trace[268842752] 'process raft request' (duration: 237.996948ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:37.754718Z","caller":"traceutil/trace.go:171","msg":"trace[61822376] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"238.53289ms","start":"2026-02-01T19:01:37.516177Z","end":"2026-02-01T19:01:37.75471Z","steps":["trace[61822376] 'process raft request' (duration: 238.002628ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:44.146588Z","caller":"traceutil/trace.go:171","msg":"trace[1907174784] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"373.520579ms","start":"2026-02-01T19:01:43.773045Z","end":"2026-02-01T19:01:44.146565Z","steps":["trace[1907174784] 'process raft request' (duration: 373.383035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:44.146709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:43.773034Z","time spent":"373.62051ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:01:44.147002Z","caller":"traceutil/trace.go:171","msg":"trace[386437388] linearizableReadLoop","detail":"{readStateIndex:911; appliedIndex:911; }","duration":"371.675248ms","start":"2026-02-01T19:01:43.77532Z","end":"2026-02-01T19:01:44.146995Z","steps":["trace[386437388] 'read index received' (duration: 371.670958ms)","trace[386437388] 'applied index is now lower than readState.Index' (duration: 3.88µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:01:44.147105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.47959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-01T19:01:44.147125Z","caller":"traceutil/trace.go:171","msg":"trace[1775549051] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:877; }","duration":"354.519161ms","start":"2026-02-01T19:01:43.792598Z","end":"2026-02-01T19:01:44.147118Z","steps":["trace[1775549051] 'agreement among raft nodes before linearized reading' (duration: 354.435609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:44.147142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:43.792572Z","time spent":"354.566912ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T19:01:44.14727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.946714ms","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-01T19:01:44.147287Z","caller":"traceutil/trace.go:171","msg":"trace[404869928] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:877; }","duration":"371.988014ms","start":"2026-02-01T19:01:43.775294Z","end":"2026-02-01T19:01:44.147282Z","steps":["trace[404869928] 'agreement among raft nodes before linearized reading' (duration: 371.936783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:44.147302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:43.775283Z","time spent":"372.015916ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T19:01:44.147487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.285975ms","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-01T19:01:44.147506Z","caller":"traceutil/trace.go:171","msg":"trace[1977817181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:877; }","duration":"338.317695ms","start":"2026-02-01T19:01:43.809184Z","end":"2026-02-01T19:01:44.147501Z","steps":["trace[1977817181] 'agreement among raft nodes before linearized reading' (duration: 338.267084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:44.147522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:43.809177Z","time spent":"338.340937ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T19:01:44.147628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.933858ms","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-01T19:01:44.147644Z","caller":"traceutil/trace.go:171","msg":"trace[879233979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:877; }","duration":"353.971348ms","start":"2026-02-01T19:01:43.793668Z","end":"2026-02-01T19:01:44.14764Z","steps":["trace[879233979] 'agreement among raft nodes before linearized reading' (duration: 353.925187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:44.147661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:43.793657Z","time spent":"353.99896ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T19:01:44.620284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.337134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T19:01:44.620436Z","caller":"traceutil/trace.go:171","msg":"trace[1590917440] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"465.887879ms","start":"2026-02-01T19:01:44.15453Z","end":"2026-02-01T19:01:44.620418Z","steps":["trace[1590917440] 'process raft request' (duration: 220.34248ms)","trace[1590917440] 'compare' (duration: 245.174861ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:01:44.620503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:44.154517Z","time spent":"465.95616ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:01:44.797278Z","caller":"traceutil/trace.go:171","msg":"trace[1968117482] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"638.70306ms","start":"2026-02-01T19:01:44.158557Z","end":"2026-02-01T19:01:44.79726Z","steps":["trace[1968117482] 'process raft request' (duration: 638.673499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:44.797414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:44.15855Z","time spent":"638.783282ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:01:44.797357Z","caller":"traceutil/trace.go:171","msg":"trace[970964536] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"641.125344ms","start":"2026-02-01T19:01:44.156185Z","end":"2026-02-01T19:01:44.79731Z","steps":["trace[970964536] 'process raft request' (duration: 573.949042ms)","trace[970964536] 'compare' (duration: 66.969258ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:01:44.797567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:44.156177Z","time spent":"641.311258ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:01:44.797781Z","caller":"traceutil/trace.go:171","msg":"trace[1411176646] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"640.683213ms","start":"2026-02-01T19:01:44.157089Z","end":"2026-02-01T19:01:44.797772Z","steps":["trace[1411176646] 'process raft request' (duration: 640.112211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:44.797827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:44.157082Z","time spent":"640.721594ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:01:58.850453Z","caller":"traceutil/trace.go:171","msg":"trace[1299078483] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"186.610988ms","start":"2026-02-01T19:01:58.663821Z","end":"2026-02-01T19:01:58.850432Z","steps":["trace[1299078483] 'process raft request' (duration: 186.503385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:59.186211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.703217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-01T19:01:59.186277Z","caller":"traceutil/trace.go:171","msg":"trace[1837813544] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"181.647419ms","start":"2026-02-01T19:01:59.004617Z","end":"2026-02-01T19:01:59.186264Z","steps":["trace[1837813544] 'process raft request' (duration: 60.841469ms)","trace[1837813544] 'compare' (duration: 120.619286ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:01:59.368085Z","caller":"traceutil/trace.go:171","msg":"trace[1000576310] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"176.855215ms","start":"2026-02-01T19:01:59.191207Z","end":"2026-02-01T19:01:59.368062Z","steps":["trace[1000576310] 'process raft request' (duration: 155.407896ms)","trace[1000576310] 'compare' (duration: 20.986858ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:01:59.368313Z","caller":"traceutil/trace.go:171","msg":"trace[296161017] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"176.916956ms","start":"2026-02-01T19:01:59.191386Z","end":"2026-02-01T19:01:59.368303Z","steps":["trace[296161017] 'read index received' (duration: 155.234362ms)","trace[296161017] 'applied index is now lower than readState.Index' (duration: 21.682094ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:01:59.368419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.015868ms","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:536"} {"level":"info","ts":"2026-02-01T19:01:59.368441Z","caller":"traceutil/trace.go:171","msg":"trace[933379552] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:961; }","duration":"177.07579ms","start":"2026-02-01T19:01:59.191357Z","end":"2026-02-01T19:01:59.368433Z","steps":["trace[933379552] 'agreement among raft nodes before linearized reading' (duration: 176.975798ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:02:00.563819Z","caller":"traceutil/trace.go:171","msg":"trace[1773938788] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:1000; }","duration":"118.183782ms","start":"2026-02-01T19:02:00.445613Z","end":"2026-02-01T19:02:00.563797Z","steps":["trace[1773938788] 'read index received' (duration: 117.927986ms)","trace[1773938788] 'applied index is now lower than readState.Index' (duration: 254.926µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:02:00.564099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.289014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:02:00.564142Z","caller":"traceutil/trace.go:171","msg":"trace[1046357982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:964; }","duration":"118.55633ms","start":"2026-02-01T19:02:00.445575Z","end":"2026-02-01T19:02:00.564131Z","steps":["trace[1046357982] 'agreement among raft nodes before linearized reading' (duration: 118.303815ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:02:00.564425Z","caller":"traceutil/trace.go:171","msg":"trace[1634825283] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"267.281271ms","start":"2026-02-01T19:02:00.297136Z","end":"2026-02-01T19:02:00.564417Z","steps":["trace[1634825283] 'process raft request' (duration: 266.462363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:02:01.515882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.458364ms","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-01T19:02:01.515958Z","caller":"traceutil/trace.go:171","msg":"trace[626294462] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:964; }","duration":"661.563265ms","start":"2026-02-01T19:02:00.854373Z","end":"2026-02-01T19:02:01.515936Z","steps":["trace[626294462] 'range keys from in-memory index tree' (duration: 661.34059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:02:01.515982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:02:00.854355Z","time spent":"661.620777ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T19:02:01.516143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.420832ms","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-01T19:02:01.516161Z","caller":"traceutil/trace.go:171","msg":"trace[38758604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:964; }","duration":"645.460973ms","start":"2026-02-01T19:02:00.870695Z","end":"2026-02-01T19:02:01.516156Z","steps":["trace[38758604] 'range keys from in-memory index tree' (duration: 645.351291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:02:01.516178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:02:00.870684Z","time spent":"645.489004ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T19:02:01.516298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.67736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-01T19:02:01.516316Z","caller":"traceutil/trace.go:171","msg":"trace[815242435] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:964; }","duration":"635.731101ms","start":"2026-02-01T19:02:00.880579Z","end":"2026-02-01T19:02:01.51631Z","steps":["trace[815242435] 'range keys from in-memory index tree' (duration: 635.602738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:02:01.516344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:02:00.880568Z","time spent":"635.768962ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T19:02:01.516451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.478841ms","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-01T19:02:01.516476Z","caller":"traceutil/trace.go:171","msg":"trace[1629232929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:964; }","duration":"627.519432ms","start":"2026-02-01T19:02:00.888952Z","end":"2026-02-01T19:02:01.516471Z","steps":["trace[1629232929] 'range keys from in-memory index tree' (duration: 627.42391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:02:01.516495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:02:00.888942Z","time spent":"627.548552ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T19:02:01.516746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.129665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T19:02:01.516767Z","caller":"traceutil/trace.go:171","msg":"trace[1358136479] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:964; }","duration":"460.152516ms","start":"2026-02-01T19:02:01.056608Z","end":"2026-02-01T19:02:01.51676Z","steps":["trace[1358136479] 'count revisions from in-memory index tree' (duration: 460.067913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:02:01.516784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:02:01.056589Z","time spent":"460.190716ms","remote":"127.0.0.1:46556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-01T19:02:01.517081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.883198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:02:01.5171Z","caller":"traceutil/trace.go:171","msg":"trace[208578934] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:964; }","duration":"156.930339ms","start":"2026-02-01T19:02:01.360164Z","end":"2026-02-01T19:02:01.517095Z","steps":["trace[208578934] 'count revisions from in-memory index tree' (duration: 156.835697ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:02:01.779571Z","caller":"traceutil/trace.go:171","msg":"trace[502086792] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"252.566828ms","start":"2026-02-01T19:02:01.526989Z","end":"2026-02-01T19:02:01.779556Z","steps":["trace[502086792] 'process raft request' (duration: 252.472106ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:02:02.449086Z","caller":"traceutil/trace.go:171","msg":"trace[1218836806] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"756.486369ms","start":"2026-02-01T19:02:01.692586Z","end":"2026-02-01T19:02:02.449072Z","steps":["trace[1218836806] 'process raft request' (duration: 756.456419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:02:02.449175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:02:01.692565Z","time spent":"756.563621ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5770,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:02:02.449364Z","caller":"traceutil/trace.go:171","msg":"trace[119151464] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"922.22475ms","start":"2026-02-01T19:02:01.527131Z","end":"2026-02-01T19:02:02.449356Z","steps":["trace[119151464] 'process raft request' (duration: 841.443996ms)","trace[119151464] 'compare' (duration: 80.044388ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:02:02.449401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:02:01.527125Z","time spent":"922.255301ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:02:02.449482Z","caller":"traceutil/trace.go:171","msg":"trace[213968549] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"918.795986ms","start":"2026-02-01T19:02:01.53068Z","end":"2026-02-01T19:02:02.449476Z","steps":["trace[213968549] 'process raft request' (duration: 918.311734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:02:02.449511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:02:01.53065Z","time spent":"918.845677ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:02:02.449585Z","caller":"traceutil/trace.go:171","msg":"trace[1855410791] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"916.500587ms","start":"2026-02-01T19:02:01.533079Z","end":"2026-02-01T19:02:02.44958Z","steps":["trace[1855410791] 'process raft request' (duration: 915.943494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:02:02.449614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:02:01.53307Z","time spent":"916.528757ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:02:02.451506Z","caller":"traceutil/trace.go:171","msg":"trace[833408322] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1002; }","duration":"480.396436ms","start":"2026-02-01T19:02:01.971101Z","end":"2026-02-01T19:02:02.451497Z","steps":["trace[833408322] 'read index received' (duration: 397.439354ms)","trace[833408322] 'applied index is now lower than readState.Index' (duration: 82.956492ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:02:02.451626Z","caller":"traceutil/trace.go:171","msg":"trace[1481275414] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"647.442637ms","start":"2026-02-01T19:02:01.804176Z","end":"2026-02-01T19:02:02.451619Z","steps":["trace[1481275414] 'process raft request' (duration: 647.272873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:02:02.451664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:02:01.804143Z","time spent":"647.497927ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T19:02:02.451757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.664392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:02:02.45178Z","caller":"traceutil/trace.go:171","msg":"trace[1666421490] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:970; }","duration":"480.686982ms","start":"2026-02-01T19:02:01.971086Z","end":"2026-02-01T19:02:02.451773Z","steps":["trace[1666421490] 'agreement among raft nodes before linearized reading' (duration: 480.653261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:02:02.4518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:02:01.971038Z","time spent":"480.756124ms","remote":"127.0.0.1:58426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-01T19:02:02.451923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.620613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-01T19:02:02.45194Z","caller":"traceutil/trace.go:171","msg":"trace[393316119] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:970; }","duration":"470.651564ms","start":"2026-02-01T19:02:01.981283Z","end":"2026-02-01T19:02:02.451934Z","steps":["trace[393316119] 'agreement among raft nodes before linearized reading' (duration: 470.591122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:02:02.451956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:02:01.981272Z","time spent":"470.679584ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":534,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-01T19:02:04.524974Z","caller":"traceutil/trace.go:171","msg":"trace[274454610] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"136.847419ms","start":"2026-02-01T19:02:04.388113Z","end":"2026-02-01T19:02:04.524961Z","steps":["trace[274454610] 'process raft request' (duration: 136.652365ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:03:44.335753Z","caller":"traceutil/trace.go:171","msg":"trace[2142184287] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"108.186583ms","start":"2026-02-01T19:03:44.227545Z","end":"2026-02-01T19:03:44.335732Z","steps":["trace[2142184287] 'process raft request' (duration: 108.08093ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:03:45.2007Z","caller":"traceutil/trace.go:171","msg":"trace[780053015] linearizableReadLoop","detail":"{readStateIndex:1463; appliedIndex:1461; }","duration":"149.646241ms","start":"2026-02-01T19:03:45.051023Z","end":"2026-02-01T19:03:45.20067Z","steps":["trace[780053015] 'read index received' (duration: 16.241936ms)","trace[780053015] 'applied index is now lower than readState.Index' (duration: 133.401805ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:03:45.201119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.064261ms","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-01T19:03:45.201177Z","caller":"traceutil/trace.go:171","msg":"trace[321400560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1405; }","duration":"150.153273ms","start":"2026-02-01T19:03:45.051009Z","end":"2026-02-01T19:03:45.201162Z","steps":["trace[321400560] 'agreement among raft nodes before linearized reading' (duration: 149.780214ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:03:45.201479Z","caller":"traceutil/trace.go:171","msg":"trace[912444376] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"150.916469ms","start":"2026-02-01T19:03:45.050544Z","end":"2026-02-01T19:03:45.20146Z","steps":["trace[912444376] 'process raft request' (duration: 149.59792ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:03:45.200771Z","caller":"traceutil/trace.go:171","msg":"trace[594500051] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"157.401582ms","start":"2026-02-01T19:03:45.043344Z","end":"2026-02-01T19:03:45.200746Z","steps":["trace[594500051] 'process raft request' (duration: 90.749961ms)","trace[594500051] 'compare' (duration: 65.862004ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:03:49.244346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.227353ms","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-01T19:03:49.244677Z","caller":"traceutil/trace.go:171","msg":"trace[1016622186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1419; }","duration":"123.57637ms","start":"2026-02-01T19:03:49.121086Z","end":"2026-02-01T19:03:49.244663Z","steps":["trace[1016622186] 'range keys from in-memory index tree' (duration: 122.803903ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:03:52.994179Z","caller":"traceutil/trace.go:171","msg":"trace[486476700] transaction","detail":"{read_only:false; response_revision:1434; number_of_response:1; }","duration":"108.355585ms","start":"2026-02-01T19:03:52.885799Z","end":"2026-02-01T19:03:52.994154Z","steps":["trace[486476700] 'process raft request' (duration: 108.148801ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:04:29.889809Z","caller":"traceutil/trace.go:171","msg":"trace[1400404072] transaction","detail":"{read_only:false; response_revision:1587; number_of_response:1; }","duration":"105.863728ms","start":"2026-02-01T19:04:29.783921Z","end":"2026-02-01T19:04:29.889785Z","steps":["trace[1400404072] 'process raft request' (duration: 105.682794ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:04:31.764991Z","caller":"traceutil/trace.go:171","msg":"trace[1686880616] transaction","detail":"{read_only:false; response_revision:1594; number_of_response:1; }","duration":"151.782444ms","start":"2026-02-01T19:04:31.613184Z","end":"2026-02-01T19:04:31.764966Z","steps":["trace[1686880616] 'process raft request' (duration: 93.862206ms)","trace[1686880616] 'compare' (duration: 57.828786ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:05:14.39136Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085375204328616,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-01T19:05:14.444847Z","caller":"traceutil/trace.go:171","msg":"trace[1205154063] linearizableReadLoop","detail":"{readStateIndex:1873; appliedIndex:1872; }","duration":"553.153388ms","start":"2026-02-01T19:05:13.891097Z","end":"2026-02-01T19:05:14.44425Z","steps":["trace[1205154063] 'read index received' (duration: 553.024974ms)","trace[1205154063] 'applied index is now lower than readState.Index' (duration: 127.654µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:05:14.444865Z","caller":"traceutil/trace.go:171","msg":"trace[279504866] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"790.138647ms","start":"2026-02-01T19:05:13.654704Z","end":"2026-02-01T19:05:14.444842Z","steps":["trace[279504866] 'process raft request' (duration: 789.459001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:05:14.445139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:05:13.654688Z","time spent":"790.396482ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T19:05:14.44501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.897314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-01T19:05:14.44527Z","caller":"traceutil/trace.go:171","msg":"trace[392381031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1796; }","duration":"554.19661ms","start":"2026-02-01T19:05:13.891063Z","end":"2026-02-01T19:05:14.44526Z","steps":["trace[392381031] 'agreement among raft nodes before linearized reading' (duration: 553.851892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:05:14.445297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:05:13.891048Z","time spent":"554.243051ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T19:05:14.445499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.626741ms","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-01T19:05:14.4456Z","caller":"traceutil/trace.go:171","msg":"trace[1384351845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1796; }","duration":"489.755774ms","start":"2026-02-01T19:05:13.955834Z","end":"2026-02-01T19:05:14.44559Z","steps":["trace[1384351845] 'agreement among raft nodes before linearized reading' (duration: 489.554759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:05:14.445713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:05:13.955822Z","time spent":"489.881027ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T19:05:14.445916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.264993ms","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:531"} {"level":"info","ts":"2026-02-01T19:05:14.446153Z","caller":"traceutil/trace.go:171","msg":"trace[712992664] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1796; }","duration":"219.537529ms","start":"2026-02-01T19:05:14.226606Z","end":"2026-02-01T19:05:14.446143Z","steps":["trace[712992664] 'agreement among raft nodes before linearized reading' (duration: 219.252113ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:05:47.645939Z","caller":"traceutil/trace.go:171","msg":"trace[833913782] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"149.975151ms","start":"2026-02-01T19:05:47.495939Z","end":"2026-02-01T19:05:47.645914Z","steps":["trace[833913782] 'process raft request' (duration: 149.825238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:05:53.237375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.972006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-hn7pl\" ","response":"range_response_count:1 size:4845"} {"level":"info","ts":"2026-02-01T19:05:53.23744Z","caller":"traceutil/trace.go:171","msg":"trace[1184041962] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-hn7pl; range_end:; response_count:1; response_revision:1963; }","duration":"142.042958ms","start":"2026-02-01T19:05:53.095382Z","end":"2026-02-01T19:05:53.237425Z","steps":["trace[1184041962] 'range keys from in-memory index tree' (duration: 141.860544ms)"],"step_count":1}