{"level":"warn","ts":"2026-02-11T01:56:47.867135Z","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-11T01:56:47.867234Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.218: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.218:2380","--initial-cluster=instance=https://162.253.55.218:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.218:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.218: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-11T01:56:47.867319Z","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-11T01:56:47.867332Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.218:2380"]} {"level":"info","ts":"2026-02-11T01:56:47.867351Z","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-11T01:56:47.868017Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"]} {"level":"info","ts":"2026-02-11T01:56:47.868092Z","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.218:2380"],"listen-peer-urls":["https://162.253.55.218:2380"],"advertise-client-urls":["https://162.253.55.218:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.218: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-11T01:56:47.872799Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.241899ms"} {"level":"info","ts":"2026-02-11T01:56:47.884628Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"bb7d59f4041c8abe","cluster-id":"5330ca29224af3c4"} {"level":"info","ts":"2026-02-11T01:56:47.884709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=()"} {"level":"info","ts":"2026-02-11T01:56:47.884733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became follower at term 0"} {"level":"info","ts":"2026-02-11T01:56:47.884747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft bb7d59f4041c8abe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-11T01:56:47.884762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became follower at term 1"} {"level":"info","ts":"2026-02-11T01:56:47.884797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=(13510053361757227710)"} {"level":"warn","ts":"2026-02-11T01:56:47.894787Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-11T01:56:47.899412Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-11T01:56:47.903029Z","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-11T01:56:47.906934Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"bb7d59f4041c8abe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-11T01:56:47.915684Z","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-11T01:56:47.915868Z","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-11T01:56:47.915881Z","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-11T01:56:47.916164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=(13510053361757227710)"} {"level":"info","ts":"2026-02-11T01:56:47.916189Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"bb7d59f4041c8abe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-11T01:56:47.916274Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5330ca29224af3c4","local-member-id":"bb7d59f4041c8abe","added-peer-id":"bb7d59f4041c8abe","added-peer-peer-urls":["https://162.253.55.218:2380"]} {"level":"info","ts":"2026-02-11T01:56:47.916393Z","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-11T01:56:47.916645Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"bb7d59f4041c8abe","initial-advertise-peer-urls":["https://162.253.55.218:2380"],"listen-peer-urls":["https://162.253.55.218:2380"],"advertise-client-urls":["https://162.253.55.218:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-11T01:56:47.916687Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-11T01:56:47.9167Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.218:2380"} {"level":"info","ts":"2026-02-11T01:56:47.91672Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.218:2380"} {"level":"info","ts":"2026-02-11T01:56:48.285967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe is starting a new election at term 1"} {"level":"info","ts":"2026-02-11T01:56:48.286013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became pre-candidate at term 1"} {"level":"info","ts":"2026-02-11T01:56:48.286043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe received MsgPreVoteResp from bb7d59f4041c8abe at term 1"} {"level":"info","ts":"2026-02-11T01:56:48.286141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became candidate at term 2"} {"level":"info","ts":"2026-02-11T01:56:48.286154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe received MsgVoteResp from bb7d59f4041c8abe at term 2"} {"level":"info","ts":"2026-02-11T01:56:48.286163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became leader at term 2"} {"level":"info","ts":"2026-02-11T01:56:48.28617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bb7d59f4041c8abe elected leader bb7d59f4041c8abe at term 2"} {"level":"info","ts":"2026-02-11T01:56:48.287753Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"bb7d59f4041c8abe","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.218:2379]}","request-path":"/0/members/bb7d59f4041c8abe/attributes","cluster-id":"5330ca29224af3c4","publish-timeout":"7s"} {"level":"info","ts":"2026-02-11T01:56:48.287989Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:56:48.288073Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T01:56:48.288628Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T01:56:48.288759Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-11T01:56:48.288813Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-11T01:56:48.29847Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.218:2379"} {"level":"info","ts":"2026-02-11T01:56:48.298788Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5330ca29224af3c4","local-member-id":"bb7d59f4041c8abe","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:56:48.29889Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:56:48.298982Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:56:48.305224Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-11T01:57:14.583823Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997600066234388477,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T01:57:14.817827Z","caller":"traceutil/trace.go:171","msg":"trace[888894587] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:484; }","duration":"734.952409ms","start":"2026-02-11T01:57:14.082851Z","end":"2026-02-11T01:57:14.817804Z","steps":["trace[888894587] 'read index received' (duration: 734.767005ms)","trace[888894587] 'applied index is now lower than readState.Index' (duration: 184.434µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:57:14.81812Z","caller":"traceutil/trace.go:171","msg":"trace[563640044] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"762.93376ms","start":"2026-02-11T01:57:14.055173Z","end":"2026-02-11T01:57:14.818107Z","steps":["trace[563640044] 'process raft request' (duration: 762.50493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:14.818287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.171425ms","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-11T01:57:14.818387Z","caller":"traceutil/trace.go:171","msg":"trace[746828727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:472; }","duration":"294.362441ms","start":"2026-02-11T01:57:14.524009Z","end":"2026-02-11T01:57:14.818372Z","steps":["trace[746828727] 'agreement among raft nodes before linearized reading' (duration: 294.193107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:14.818603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:14.055148Z","time spent":"763.000381ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:57:14.818678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.79703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:57:14.81871Z","caller":"traceutil/trace.go:171","msg":"trace[844662919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:472; }","duration":"735.854981ms","start":"2026-02-11T01:57:14.082846Z","end":"2026-02-11T01:57:14.818701Z","steps":["trace[844662919] 'agreement among raft nodes before linearized reading' (duration: 735.778349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:14.818783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:14.082801Z","time spent":"735.972184ms","remote":"127.0.0.1:58020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:57:14.818913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.062158ms","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-11T01:57:14.818947Z","caller":"traceutil/trace.go:171","msg":"trace[612705301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:472; }","duration":"292.094628ms","start":"2026-02-11T01:57:14.526841Z","end":"2026-02-11T01:57:14.818936Z","steps":["trace[612705301] 'agreement among raft nodes before linearized reading' (duration: 292.039257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:19.277397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.914362ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:57:19.277488Z","caller":"traceutil/trace.go:171","msg":"trace[208236672] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:516; }","duration":"264.013695ms","start":"2026-02-11T01:57:19.013463Z","end":"2026-02-11T01:57:19.277477Z","steps":["trace[208236672] 'range keys from in-memory index tree' (duration: 263.903852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:19.277865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.410646ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:57:19.277898Z","caller":"traceutil/trace.go:171","msg":"trace[433781066] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:530; }","duration":"425.435615ms","start":"2026-02-11T01:57:18.852456Z","end":"2026-02-11T01:57:19.277892Z","steps":["trace[433781066] 'read index received' (duration: 29.71864ms)","trace[433781066] 'applied index is now lower than readState.Index' (duration: 395.716385ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:57:19.278007Z","caller":"traceutil/trace.go:171","msg":"trace[1955642195] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"429.03137ms","start":"2026-02-11T01:57:18.84897Z","end":"2026-02-11T01:57:19.278002Z","steps":["trace[1955642195] 'process raft request' (duration: 188.362008ms)","trace[1955642195] 'compare' (duration: 240.260502ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:57:19.278042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:18.848964Z","time spent":"429.06116ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:57:19.278156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.699302ms","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-11T01:57:19.278169Z","caller":"traceutil/trace.go:171","msg":"trace[1152880483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"425.724353ms","start":"2026-02-11T01:57:18.85244Z","end":"2026-02-11T01:57:19.278165Z","steps":["trace[1152880483] 'agreement among raft nodes before linearized reading' (duration: 425.671152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:19.278182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:18.852434Z","time spent":"425.745054ms","remote":"127.0.0.1:58296","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-11T01:57:19.278244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.153381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:57:19.278256Z","caller":"traceutil/trace.go:171","msg":"trace[1337106845] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"200.199372ms","start":"2026-02-11T01:57:19.078053Z","end":"2026-02-11T01:57:19.278252Z","steps":["trace[1337106845] 'agreement among raft nodes before linearized reading' (duration: 200.180292ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:57:21.0048Z","caller":"traceutil/trace.go:171","msg":"trace[474965269] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"116.642271ms","start":"2026-02-11T01:57:20.888137Z","end":"2026-02-11T01:57:21.004779Z","steps":["trace[474965269] 'process raft request' (duration: 116.548399ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:57:23.121853Z","caller":"traceutil/trace.go:171","msg":"trace[442111512] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"107.244994ms","start":"2026-02-11T01:57:23.014586Z","end":"2026-02-11T01:57:23.121831Z","steps":["trace[442111512] 'process raft request' (duration: 106.950656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:23.498793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.9312ms","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-11T01:57:23.498933Z","caller":"traceutil/trace.go:171","msg":"trace[474899523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:526; }","duration":"115.101413ms","start":"2026-02-11T01:57:23.383792Z","end":"2026-02-11T01:57:23.498894Z","steps":["trace[474899523] 'range keys from in-memory index tree' (duration: 114.773576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:23.500278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.452584ms","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-11T01:57:23.500344Z","caller":"traceutil/trace.go:171","msg":"trace[620857671] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:526; }","duration":"131.544306ms","start":"2026-02-11T01:57:23.368787Z","end":"2026-02-11T01:57:23.500331Z","steps":["trace[620857671] 'range keys from in-memory index tree' (duration: 131.29138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:23.500795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.626232ms","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-11T01:57:23.500832Z","caller":"traceutil/trace.go:171","msg":"trace[396395405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:526; }","duration":"163.722964ms","start":"2026-02-11T01:57:23.337097Z","end":"2026-02-11T01:57:23.50082Z","steps":["trace[396395405] 'range keys from in-memory index tree' (duration: 163.501479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:27.259064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.301884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:57:27.259145Z","caller":"traceutil/trace.go:171","msg":"trace[866378607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:536; }","duration":"180.448317ms","start":"2026-02-11T01:57:27.07868Z","end":"2026-02-11T01:57:27.259128Z","steps":["trace[866378607] 'range keys from in-memory index tree' (duration: 180.227642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:27.25921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.646772ms","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-11T01:57:27.259255Z","caller":"traceutil/trace.go:171","msg":"trace[763455906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:536; }","duration":"126.721794ms","start":"2026-02-11T01:57:27.132522Z","end":"2026-02-11T01:57:27.259244Z","steps":["trace[763455906] 'range keys from in-memory index tree' (duration: 126.502698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:27.259451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.737517ms","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-11T01:57:27.259472Z","caller":"traceutil/trace.go:171","msg":"trace[1394360387] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:536; }","duration":"237.810709ms","start":"2026-02-11T01:57:27.021655Z","end":"2026-02-11T01:57:27.259466Z","steps":["trace[1394360387] 'range keys from in-memory index tree' (duration: 237.650945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:41.858648Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.080826112s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T01:57:41.858976Z","caller":"traceutil/trace.go:171","msg":"trace[1409214525] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"1.081264052s","start":"2026-02-11T01:57:40.777699Z","end":"2026-02-11T01:57:41.858963Z","steps":["trace[1409214525] 'process raft request' (duration: 1.08116593s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:41.859051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:40.777689Z","time spent":"1.081313953s","remote":"127.0.0.1:58222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5011,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:57:41.859056Z","caller":"traceutil/trace.go:171","msg":"trace[1284459053] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:698; }","duration":"429.286485ms","start":"2026-02-11T01:57:41.429742Z","end":"2026-02-11T01:57:41.859028Z","steps":["trace[1284459053] 'read index received' (duration: 429.268624ms)","trace[1284459053] 'applied index is now lower than readState.Index' (duration: 9.561µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:57:41.859294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.53099ms","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-11T01:57:41.859324Z","caller":"traceutil/trace.go:171","msg":"trace[2077973710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:678; }","duration":"429.627004ms","start":"2026-02-11T01:57:41.42969Z","end":"2026-02-11T01:57:41.859317Z","steps":["trace[2077973710] 'agreement among raft nodes before linearized reading' (duration: 429.46187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:41.859349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:41.429675Z","time spent":"429.668404ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-11T01:57:42.196608Z","caller":"traceutil/trace.go:171","msg":"trace[2134211049] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"720.320977ms","start":"2026-02-11T01:57:41.476272Z","end":"2026-02-11T01:57:42.196593Z","steps":["trace[2134211049] 'process raft request' (duration: 720.198674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:42.196755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:41.476255Z","time spent":"720.423829ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:57:42.197072Z","caller":"traceutil/trace.go:171","msg":"trace[941084487] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:699; }","duration":"337.952545ms","start":"2026-02-11T01:57:41.859114Z","end":"2026-02-11T01:57:42.197066Z","steps":["trace[941084487] 'read index received' (duration: 337.949435ms)","trace[941084487] 'applied index is now lower than readState.Index' (duration: 2.76µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:57:42.19715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.685478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.218\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T01:57:42.197166Z","caller":"traceutil/trace.go:171","msg":"trace[2120994981] range","detail":"{range_begin:/registry/masterleases/162.253.55.218; range_end:; response_count:1; response_revision:679; }","duration":"396.755461ms","start":"2026-02-11T01:57:41.800406Z","end":"2026-02-11T01:57:42.197162Z","steps":["trace[2120994981] 'agreement among raft nodes before linearized reading' (duration: 396.691299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:42.197179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:41.800384Z","time spent":"396.791681ms","remote":"127.0.0.1:58046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.218\" "} {"level":"warn","ts":"2026-02-11T01:57:42.197277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.992886ms","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-11T01:57:42.197289Z","caller":"traceutil/trace.go:171","msg":"trace[1154255305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:679; }","duration":"499.028287ms","start":"2026-02-11T01:57:41.698257Z","end":"2026-02-11T01:57:42.197286Z","steps":["trace[1154255305] 'agreement among raft nodes before linearized reading' (duration: 498.984296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:42.1973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:41.698215Z","time spent":"499.082878ms","remote":"127.0.0.1:58296","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-11T01:57:42.197379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.508677ms","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-11T01:57:42.197394Z","caller":"traceutil/trace.go:171","msg":"trace[870357936] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:679; }","duration":"510.543308ms","start":"2026-02-11T01:57:41.686846Z","end":"2026-02-11T01:57:42.197389Z","steps":["trace[870357936] 'agreement among raft nodes before linearized reading' (duration: 510.505487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:42.197405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:41.686834Z","time spent":"510.568458ms","remote":"127.0.0.1:58296","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":"warn","ts":"2026-02-11T01:57:42.197478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.675839ms","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-11T01:57:42.19752Z","caller":"traceutil/trace.go:171","msg":"trace[920186219] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:679; }","duration":"566.71976ms","start":"2026-02-11T01:57:41.630766Z","end":"2026-02-11T01:57:42.197486Z","steps":["trace[920186219] 'agreement among raft nodes before linearized reading' (duration: 566.687339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:42.197534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:41.630749Z","time spent":"566.781551ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T01:57:42.197625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.887277ms","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-11T01:57:42.197637Z","caller":"traceutil/trace.go:171","msg":"trace[1746864287] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:679; }","duration":"646.925529ms","start":"2026-02-11T01:57:41.550708Z","end":"2026-02-11T01:57:42.197634Z","steps":["trace[1746864287] 'agreement among raft nodes before linearized reading' (duration: 646.883058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:42.197648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:41.55068Z","time spent":"646.96609ms","remote":"127.0.0.1:58296","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":"info","ts":"2026-02-11T01:57:42.257884Z","caller":"traceutil/trace.go:171","msg":"trace[1995447475] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"394.603009ms","start":"2026-02-11T01:57:41.863263Z","end":"2026-02-11T01:57:42.257866Z","steps":["trace[1995447475] 'process raft request' (duration: 394.158258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:42.25799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:41.863249Z","time spent":"394.67873ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:57:42.258137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.051838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-gt4zh\" ","response":"range_response_count:1 size:4733"} {"level":"info","ts":"2026-02-11T01:57:42.258251Z","caller":"traceutil/trace.go:171","msg":"trace[1271564993] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-gt4zh; range_end:; response_count:1; response_revision:682; }","duration":"392.190092ms","start":"2026-02-11T01:57:41.866025Z","end":"2026-02-11T01:57:42.258215Z","steps":["trace[1271564993] 'agreement among raft nodes before linearized reading' (duration: 391.877475ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:57:42.258285Z","caller":"traceutil/trace.go:171","msg":"trace[1020877313] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"390.742348ms","start":"2026-02-11T01:57:41.867532Z","end":"2026-02-11T01:57:42.258275Z","steps":["trace[1020877313] 'process raft request' (duration: 390.157714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:42.258291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:41.866012Z","time spent":"392.270423ms","remote":"127.0.0.1:58222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4756,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-gt4zh\" "} {"level":"warn","ts":"2026-02-11T01:57:42.258317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:41.867522Z","time spent":"390.778629ms","remote":"127.0.0.1:58302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1292,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:57:42.258446Z","caller":"traceutil/trace.go:171","msg":"trace[857004408] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"390.39412ms","start":"2026-02-11T01:57:41.868046Z","end":"2026-02-11T01:57:42.25844Z","steps":["trace[857004408] 'process raft request' (duration: 389.732144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:42.258473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:41.868038Z","time spent":"390.418941ms","remote":"127.0.0.1:58202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":783,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:57:42.258607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.879604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:57:42.258624Z","caller":"traceutil/trace.go:171","msg":"trace[201194665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:682; }","duration":"179.927265ms","start":"2026-02-11T01:57:42.078692Z","end":"2026-02-11T01:57:42.258619Z","steps":["trace[201194665] 'agreement among raft nodes before linearized reading' (duration: 179.889824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:42.519273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:42.199587Z","time spent":"319.683075ms","remote":"127.0.0.1:58046","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-11T01:57:42.519747Z","caller":"traceutil/trace.go:171","msg":"trace[903047695] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"317.429042ms","start":"2026-02-11T01:57:42.202306Z","end":"2026-02-11T01:57:42.519735Z","steps":["trace[903047695] 'process raft request' (duration: 316.90807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:42.519839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:42.202295Z","time spent":"317.497133ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:57:42.520084Z","caller":"traceutil/trace.go:171","msg":"trace[42676337] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"317.669828ms","start":"2026-02-11T01:57:42.202409Z","end":"2026-02-11T01:57:42.520079Z","steps":["trace[42676337] 'process raft request' (duration: 317.177456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:42.520119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:42.202404Z","time spent":"317.693478ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:57:42.520258Z","caller":"traceutil/trace.go:171","msg":"trace[1906316705] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"317.814361ms","start":"2026-02-11T01:57:42.202438Z","end":"2026-02-11T01:57:42.520252Z","steps":["trace[1906316705] 'process raft request' (duration: 317.173766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:42.520283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:42.202434Z","time spent":"317.835561ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:57:42.520586Z","caller":"traceutil/trace.go:171","msg":"trace[111376636] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"317.091134ms","start":"2026-02-11T01:57:42.203487Z","end":"2026-02-11T01:57:42.520578Z","steps":["trace[111376636] 'process raft request' (duration: 316.500291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:42.520616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:42.203478Z","time spent":"317.122385ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:57:42.520764Z","caller":"traceutil/trace.go:171","msg":"trace[2117758580] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:702; }","duration":"258.672258ms","start":"2026-02-11T01:57:42.262086Z","end":"2026-02-11T01:57:42.520758Z","steps":["trace[2117758580] 'read index received' (duration: 257.061171ms)","trace[2117758580] 'applied index is now lower than readState.Index' (duration: 1.610687ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:57:42.520808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.719479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:57:42.520822Z","caller":"traceutil/trace.go:171","msg":"trace[714462284] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:687; }","duration":"258.748521ms","start":"2026-02-11T01:57:42.262069Z","end":"2026-02-11T01:57:42.520818Z","steps":["trace[714462284] 'agreement among raft nodes before linearized reading' (duration: 258.71039ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:57:42.520898Z","caller":"traceutil/trace.go:171","msg":"trace[588337138] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"314.430272ms","start":"2026-02-11T01:57:42.206462Z","end":"2026-02-11T01:57:42.520893Z","steps":["trace[588337138] 'process raft request' (duration: 313.547201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:42.520919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:42.206453Z","time spent":"314.453972ms","remote":"127.0.0.1:58118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T01:57:42.637398Z","caller":"traceutil/trace.go:171","msg":"trace[140852930] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"368.628148ms","start":"2026-02-11T01:57:42.268494Z","end":"2026-02-11T01:57:42.637122Z","steps":["trace[140852930] 'process raft request' (duration: 368.509585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:42.637509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:42.268485Z","time spent":"368.964776ms","remote":"127.0.0.1:58222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4833,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:57:42.693485Z","caller":"traceutil/trace.go:171","msg":"trace[1838784602] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"141.270946ms","start":"2026-02-11T01:57:42.5522Z","end":"2026-02-11T01:57:42.693471Z","steps":["trace[1838784602] 'process raft request' (duration: 141.245635ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:57:42.693523Z","caller":"traceutil/trace.go:171","msg":"trace[1174262932] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"170.35826ms","start":"2026-02-11T01:57:42.523141Z","end":"2026-02-11T01:57:42.6935Z","steps":["trace[1174262932] 'process raft request' (duration: 170.215557ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:57:50.956042Z","caller":"traceutil/trace.go:171","msg":"trace[839486588] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"333.387878ms","start":"2026-02-11T01:57:50.622638Z","end":"2026-02-11T01:57:50.956026Z","steps":["trace[839486588] 'process raft request' (duration: 333.356137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:50.956171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:50.622633Z","time spent":"333.46162ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:57:50.956443Z","caller":"traceutil/trace.go:171","msg":"trace[676772097] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"336.705116ms","start":"2026-02-11T01:57:50.61973Z","end":"2026-02-11T01:57:50.956435Z","steps":["trace[676772097] 'process raft request' (duration: 308.712707ms)","trace[676772097] 'compare' (duration: 27.008726ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:57:50.956488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:50.619718Z","time spent":"336.748458ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:57:50.956572Z","caller":"traceutil/trace.go:171","msg":"trace[1293874573] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"335.242272ms","start":"2026-02-11T01:57:50.621321Z","end":"2026-02-11T01:57:50.956563Z","steps":["trace[1293874573] 'process raft request' (duration: 334.643607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:57:50.956607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:57:50.621316Z","time spent":"335.274873ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:58:02.446504Z","caller":"traceutil/trace.go:171","msg":"trace[260844202] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:792; }","duration":"164.44451ms","start":"2026-02-11T01:58:02.282044Z","end":"2026-02-11T01:58:02.446489Z","steps":["trace[260844202] 'read index received' (duration: 164.344418ms)","trace[260844202] 'applied index is now lower than readState.Index' (duration: 99.512µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:58:02.446529Z","caller":"traceutil/trace.go:171","msg":"trace[86601018] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"395.181473ms","start":"2026-02-11T01:58:02.051328Z","end":"2026-02-11T01:58:02.446509Z","steps":["trace[86601018] 'process raft request' (duration: 395.05193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:02.446661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:02.051299Z","time spent":"395.292665ms","remote":"127.0.0.1:58046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:58:02.446653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.590433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:58:02.446853Z","caller":"traceutil/trace.go:171","msg":"trace[166052672] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:768; }","duration":"164.83898ms","start":"2026-02-11T01:58:02.282005Z","end":"2026-02-11T01:58:02.446844Z","steps":["trace[166052672] 'agreement among raft nodes before linearized reading' (duration: 164.569774ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:58:06.579375Z","caller":"traceutil/trace.go:171","msg":"trace[1850806515] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"153.75647ms","start":"2026-02-11T01:58:06.4256Z","end":"2026-02-11T01:58:06.579356Z","steps":["trace[1850806515] 'process raft request' (duration: 153.579945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:07.210071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.13323ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-11T01:58:07.210137Z","caller":"traceutil/trace.go:171","msg":"trace[350850913] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:807; }","duration":"200.090621ms","start":"2026-02-11T01:58:07.010035Z","end":"2026-02-11T01:58:07.210126Z","steps":["trace[350850913] 'read index received' (duration: 22.863688ms)","trace[350850913] 'applied index is now lower than readState.Index' (duration: 177.226033ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:58:07.210255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.220463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-11T01:58:07.210268Z","caller":"traceutil/trace.go:171","msg":"trace[2020499856] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:782; }","duration":"200.273185ms","start":"2026-02-11T01:58:07.00999Z","end":"2026-02-11T01:58:07.210263Z","steps":["trace[2020499856] 'agreement among raft nodes before linearized reading' (duration: 200.193663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:07.210468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.006604ms","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-11T01:58:07.210499Z","caller":"traceutil/trace.go:171","msg":"trace[1980457284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:782; }","duration":"151.033346ms","start":"2026-02-11T01:58:07.059444Z","end":"2026-02-11T01:58:07.210478Z","steps":["trace[1980457284] 'agreement among raft nodes before linearized reading' (duration: 150.985195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:07.210634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.810208ms","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-11T01:58:07.210647Z","caller":"traceutil/trace.go:171","msg":"trace[705684544] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:782; }","duration":"121.835459ms","start":"2026-02-11T01:58:07.088808Z","end":"2026-02-11T01:58:07.210644Z","steps":["trace[705684544] 'agreement among raft nodes before linearized reading' (duration: 121.798868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:08.71876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.239057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:58:08.718804Z","caller":"traceutil/trace.go:171","msg":"trace[651059288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:785; }","duration":"642.312739ms","start":"2026-02-11T01:58:08.07648Z","end":"2026-02-11T01:58:08.718792Z","steps":["trace[651059288] 'range keys from in-memory index tree' (duration: 642.187196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:08.71882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:08.076465Z","time spent":"642.35104ms","remote":"127.0.0.1:58020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:58:09.488405Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997600066234389143,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:58:09.989057Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997600066234389143,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:58:10.490174Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997600066234389143,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:58:10.579856Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.858046589s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T01:58:10.580128Z","caller":"traceutil/trace.go:171","msg":"trace[2046573783] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"1.858366916s","start":"2026-02-11T01:58:08.721745Z","end":"2026-02-11T01:58:10.580112Z","steps":["trace[2046573783] 'process raft request' (duration: 1.858278214s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:10.580207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:08.721737Z","time spent":"1.858418557s","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:58:11.18121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.416024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:58:11.181276Z","caller":"traceutil/trace.go:171","msg":"trace[628292584] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"2.193579387s","start":"2026-02-11T01:58:08.987684Z","end":"2026-02-11T01:58:11.181264Z","steps":["trace[628292584] 'read index received' (duration: 1.592790395s)","trace[628292584] 'applied index is now lower than readState.Index' (duration: 600.787962ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:58:11.181293Z","caller":"traceutil/trace.go:171","msg":"trace[1163719682] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"2.457869617s","start":"2026-02-11T01:58:08.723404Z","end":"2026-02-11T01:58:11.181273Z","steps":["trace[1163719682] 'process raft request' (duration: 2.14832178s)","trace[1163719682] 'compare' (duration: 309.158658ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:58:11.181354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.193666429s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-11T01:58:11.18138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:08.723397Z","time spent":"2.457935239s","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:58:11.181386Z","caller":"traceutil/trace.go:171","msg":"trace[30273704] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:787; }","duration":"2.19371223s","start":"2026-02-11T01:58:08.987654Z","end":"2026-02-11T01:58:11.181367Z","steps":["trace[30273704] 'agreement among raft nodes before linearized reading' (duration: 2.193667039s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:11.181417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:08.98763Z","time spent":"2.193781391s","remote":"127.0.0.1:58146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:58:11.181573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102803159s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:58:11.181597Z","caller":"traceutil/trace.go:171","msg":"trace[1110263626] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"1.1028487s","start":"2026-02-11T01:58:10.078739Z","end":"2026-02-11T01:58:11.181588Z","steps":["trace[1110263626] 'agreement among raft nodes before linearized reading' (duration: 1.102815559s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:11.181612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:10.078728Z","time spent":"1.102881091s","remote":"127.0.0.1:58020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:58:11.181615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.901545162s","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-11T01:58:11.181634Z","caller":"traceutil/trace.go:171","msg":"trace[821127543] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:787; }","duration":"1.901589642s","start":"2026-02-11T01:58:09.280039Z","end":"2026-02-11T01:58:11.181628Z","steps":["trace[821127543] 'agreement among raft nodes before linearized reading' (duration: 1.9015112s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:11.18165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:09.280025Z","time spent":"1.901621514s","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T01:58:11.181794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.8938024s","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-11T01:58:11.181812Z","caller":"traceutil/trace.go:171","msg":"trace[1496219709] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:787; }","duration":"1.89383205s","start":"2026-02-11T01:58:09.287974Z","end":"2026-02-11T01:58:11.181806Z","steps":["trace[1496219709] 'agreement among raft nodes before linearized reading' (duration: 1.893774799s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:11.181827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:09.287968Z","time spent":"1.893855701s","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-11T01:58:11.181952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.89466068s","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-11T01:58:11.181969Z","caller":"traceutil/trace.go:171","msg":"trace[1993335366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:787; }","duration":"1.89467765s","start":"2026-02-11T01:58:09.287286Z","end":"2026-02-11T01:58:11.181964Z","steps":["trace[1993335366] 'agreement among raft nodes before linearized reading' (duration: 1.894609008s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:11.181982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:09.287264Z","time spent":"1.894714951s","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-11T01:58:12.347592Z","caller":"traceutil/trace.go:171","msg":"trace[1143437405] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"218.938545ms","start":"2026-02-11T01:58:12.128634Z","end":"2026-02-11T01:58:12.347573Z","steps":["trace[1143437405] 'read index received' (duration: 218.767231ms)","trace[1143437405] 'applied index is now lower than readState.Index' (duration: 170.134µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:58:12.347616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:11.80336Z","time spent":"544.248303ms","remote":"127.0.0.1:58046","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-11T01:58:12.347726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.063568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:58:12.347786Z","caller":"traceutil/trace.go:171","msg":"trace[1808237171] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:790; }","duration":"219.16167ms","start":"2026-02-11T01:58:12.12859Z","end":"2026-02-11T01:58:12.347751Z","steps":["trace[1808237171] 'agreement among raft nodes before linearized reading' (duration: 219.078138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:12.827651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.69657ms","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-11T01:58:12.827705Z","caller":"traceutil/trace.go:171","msg":"trace[444683938] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:791; }","duration":"244.783061ms","start":"2026-02-11T01:58:12.582909Z","end":"2026-02-11T01:58:12.827693Z","steps":["trace[444683938] 'range keys from in-memory index tree' (duration: 244.592267ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:58:15.104965Z","caller":"traceutil/trace.go:171","msg":"trace[1721966738] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"217.49109ms","start":"2026-02-11T01:58:14.887451Z","end":"2026-02-11T01:58:15.104942Z","steps":["trace[1721966738] 'process raft request' (duration: 217.363277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:16.031503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.617651ms","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-11T01:58:16.031546Z","caller":"traceutil/trace.go:171","msg":"trace[308682216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:798; }","duration":"835.706433ms","start":"2026-02-11T01:58:15.195829Z","end":"2026-02-11T01:58:16.031535Z","steps":["trace[308682216] 'range keys from in-memory index tree' (duration: 835.502048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:16.031566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:15.195809Z","time spent":"835.752893ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T01:58:16.03168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.720269ms","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-11T01:58:16.031697Z","caller":"traceutil/trace.go:171","msg":"trace[218082099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:798; }","duration":"817.738339ms","start":"2026-02-11T01:58:15.213954Z","end":"2026-02-11T01:58:16.031693Z","steps":["trace[218082099] 'range keys from in-memory index tree' (duration: 817.659498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:16.031708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:15.213924Z","time spent":"817.781631ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T01:58:16.031849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.249539ms","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-11T01:58:16.031866Z","caller":"traceutil/trace.go:171","msg":"trace[116372776] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:798; }","duration":"755.291549ms","start":"2026-02-11T01:58:15.27657Z","end":"2026-02-11T01:58:16.031862Z","steps":["trace[116372776] 'range keys from in-memory index tree' (duration: 755.179147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:16.031878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:15.276561Z","time spent":"755.314221ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-11T01:58:16.031951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.132138ms","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-11T01:58:16.031965Z","caller":"traceutil/trace.go:171","msg":"trace[646119229] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:798; }","duration":"753.16336ms","start":"2026-02-11T01:58:15.278798Z","end":"2026-02-11T01:58:16.031962Z","steps":["trace[646119229] 'range keys from in-memory index tree' (duration: 753.089728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:16.031976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:15.278789Z","time spent":"753.18389ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-11T01:58:16.259178Z","caller":"traceutil/trace.go:171","msg":"trace[1945915092] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"221.197307ms","start":"2026-02-11T01:58:16.037956Z","end":"2026-02-11T01:58:16.259153Z","steps":["trace[1945915092] 'process raft request' (duration: 221.150135ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:58:16.259823Z","caller":"traceutil/trace.go:171","msg":"trace[268795589] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"222.720912ms","start":"2026-02-11T01:58:16.037085Z","end":"2026-02-11T01:58:16.259806Z","steps":["trace[268795589] 'process raft request' (duration: 127.207844ms)","trace[268795589] 'compare' (duration: 94.655318ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:58:16.260346Z","caller":"traceutil/trace.go:171","msg":"trace[1181829946] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"223.171713ms","start":"2026-02-11T01:58:16.037162Z","end":"2026-02-11T01:58:16.260334Z","steps":["trace[1181829946] 'process raft request' (duration: 221.906954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:28.579671Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997600066234389370,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:58:28.878938Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.667729958s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T01:58:28.879297Z","caller":"traceutil/trace.go:171","msg":"trace[946837845] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"1.712804049s","start":"2026-02-11T01:58:27.166472Z","end":"2026-02-11T01:58:28.879276Z","steps":["trace[946837845] 'process raft request' (duration: 1.712654746s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:28.879441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:27.166462Z","time spent":"1.712896632s","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:58:30.07979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00066187s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-11T01:58:30.079897Z","caller":"traceutil/trace.go:171","msg":"trace[295486441] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000836745s","start":"2026-02-11T01:58:28.079039Z","end":"2026-02-11T01:58:30.079876Z","steps":["trace[295486441] 'agreement among raft nodes before linearized reading' (duration: 2.000683841s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:30.079946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:28.079026Z","time spent":"2.000910146s","remote":"127.0.0.1:58020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/11 01:58:30 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-11T01:58:30.555665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.543503758s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:58:30.555813Z","caller":"traceutil/trace.go:171","msg":"trace[1491807942] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:883; }","duration":"1.543658632s","start":"2026-02-11T01:58:29.012133Z","end":"2026-02-11T01:58:30.555791Z","steps":["trace[1491807942] 'range keys from in-memory index tree' (duration: 1.543487468s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:31.397881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00054074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-11T01:58:31.397958Z","caller":"traceutil/trace.go:171","msg":"trace[1447886915] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000678882s","start":"2026-02-11T01:58:29.397262Z","end":"2026-02-11T01:58:31.39794Z","steps":["trace[1447886915] 'agreement among raft nodes before linearized reading' (duration: 2.00056682s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:31.398005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:29.39717Z","time spent":"2.000826126s","remote":"127.0.0.1:58020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:58:32.080044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99993228s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-11T01:58:32.080112Z","caller":"traceutil/trace.go:171","msg":"trace[1144081312] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000047063s","start":"2026-02-11T01:58:30.080048Z","end":"2026-02-11T01:58:32.080095Z","steps":["trace[1144081312] 'agreement among raft nodes before linearized reading' (duration: 1.999964421s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:32.080153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:30.080031Z","time spent":"2.000113195s","remote":"127.0.0.1:58022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/11 01:58:32 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-11T01:58:32.569996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.37234625s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-11T01:58:32.570119Z","caller":"traceutil/trace.go:171","msg":"trace[184641386] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:914; }","duration":"4.490984382s","start":"2026-02-11T01:58:28.079092Z","end":"2026-02-11T01:58:32.570076Z","steps":["trace[184641386] 'read index received' (duration: 800.958374ms)","trace[184641386] 'applied index is now lower than readState.Index' (duration: 3.690023728s)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:58:32.617035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.496853679s","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-11T01:58:32.617103Z","caller":"traceutil/trace.go:171","msg":"trace[1668469338] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:884; }","duration":"4.496985492s","start":"2026-02-11T01:58:28.120101Z","end":"2026-02-11T01:58:32.617086Z","steps":["trace[1668469338] 'agreement among raft nodes before linearized reading' (duration: 4.496769197s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:32.617157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:28.120085Z","time spent":"4.497063223s","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-11T01:58:32.617368Z","caller":"traceutil/trace.go:171","msg":"trace[1230969852] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"534.074428ms","start":"2026-02-11T01:58:32.083265Z","end":"2026-02-11T01:58:32.617339Z","steps":["trace[1230969852] 'process raft request' (duration: 533.486724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:32.617437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:32.083245Z","time spent":"534.1486ms","remote":"127.0.0.1:58118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T01:58:32.617581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.646777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:58:32.617609Z","caller":"traceutil/trace.go:171","msg":"trace[551144910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:884; }","duration":"530.704029ms","start":"2026-02-11T01:58:32.086897Z","end":"2026-02-11T01:58:32.617601Z","steps":["trace[551144910] 'agreement among raft nodes before linearized reading' (duration: 530.657288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:32.617634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:32.086885Z","time spent":"530.743479ms","remote":"127.0.0.1:58020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:58:32.617772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.85789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:58:32.617798Z","caller":"traceutil/trace.go:171","msg":"trace[615680357] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:884; }","duration":"641.931622ms","start":"2026-02-11T01:58:31.975859Z","end":"2026-02-11T01:58:32.617791Z","steps":["trace[615680357] 'agreement among raft nodes before linearized reading' (duration: 641.887601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:32.61835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:31.975837Z","time spent":"642.481466ms","remote":"127.0.0.1:58288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:58:32.618574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.168713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.218\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T01:58:32.618605Z","caller":"traceutil/trace.go:171","msg":"trace[616119314] range","detail":"{range_begin:/registry/masterleases/162.253.55.218; range_end:; response_count:1; response_revision:884; }","duration":"815.197434ms","start":"2026-02-11T01:58:31.803396Z","end":"2026-02-11T01:58:32.618593Z","steps":["trace[616119314] 'agreement among raft nodes before linearized reading' (duration: 815.106972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:32.618631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:31.803351Z","time spent":"815.274825ms","remote":"127.0.0.1:58046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.218\" "} {"level":"warn","ts":"2026-02-11T01:58:32.618769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.067295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:58:32.618799Z","caller":"traceutil/trace.go:171","msg":"trace[1916147498] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:884; }","duration":"925.097275ms","start":"2026-02-11T01:58:31.693693Z","end":"2026-02-11T01:58:32.61879Z","steps":["trace[1916147498] 'agreement among raft nodes before linearized reading' (duration: 925.052184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:32.618827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:31.693627Z","time spent":"925.190509ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:58:32.619009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.735450566s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-11T01:58:32.619043Z","caller":"traceutil/trace.go:171","msg":"trace[1477369283] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:884; }","duration":"1.735506918s","start":"2026-02-11T01:58:30.883524Z","end":"2026-02-11T01:58:32.619031Z","steps":["trace[1477369283] 'agreement among raft nodes before linearized reading' (duration: 1.735415316s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:32.619073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:30.883512Z","time spent":"1.735555139s","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T01:58:32.619837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.297577417s","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-11T01:58:32.619914Z","caller":"traceutil/trace.go:171","msg":"trace[318753996] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:884; }","duration":"4.29766497s","start":"2026-02-11T01:58:28.322237Z","end":"2026-02-11T01:58:32.619901Z","steps":["trace[318753996] 'agreement among raft nodes before linearized reading' (duration: 4.297464395s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:32.619947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:28.32223Z","time spent":"4.297708991s","remote":"127.0.0.1:58296","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-11T01:58:32.620165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.304203373s","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-11T01:58:32.620208Z","caller":"traceutil/trace.go:171","msg":"trace[181855029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:884; }","duration":"4.304250685s","start":"2026-02-11T01:58:28.315945Z","end":"2026-02-11T01:58:32.620196Z","steps":["trace[181855029] 'agreement among raft nodes before linearized reading' (duration: 4.304159543s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:32.62025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:28.315941Z","time spent":"4.304300236s","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T01:58:32.620463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.304997121s","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-11T01:58:32.620499Z","caller":"traceutil/trace.go:171","msg":"trace[1632164844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:884; }","duration":"4.305075774s","start":"2026-02-11T01:58:28.315414Z","end":"2026-02-11T01:58:32.62049Z","steps":["trace[1632164844] 'agreement among raft nodes before linearized reading' (duration: 4.304963531s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:32.620528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:28.315404Z","time spent":"4.305115084s","remote":"127.0.0.1:58296","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-11T01:58:32.619612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.977573761s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:58:32.621024Z","caller":"traceutil/trace.go:171","msg":"trace[329586000] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:884; }","duration":"2.979029535s","start":"2026-02-11T01:58:29.641978Z","end":"2026-02-11T01:58:32.621008Z","steps":["trace[329586000] 'agreement among raft nodes before linearized reading' (duration: 2.977570381s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:32.621091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:29.641935Z","time spent":"2.979139648s","remote":"127.0.0.1:58210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:58:32.619663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.717590024s","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-11T01:58:32.62137Z","caller":"traceutil/trace.go:171","msg":"trace[974559197] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:884; }","duration":"3.719368107s","start":"2026-02-11T01:58:28.901989Z","end":"2026-02-11T01:58:32.621357Z","steps":["trace[974559197] 'agreement among raft nodes before linearized reading' (duration: 3.717652956s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:32.621415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:28.901957Z","time spent":"3.719442529s","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:58:34.622751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.998988418s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-11T01:58:34.622825Z","caller":"traceutil/trace.go:171","msg":"trace[777856041] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.99909013s","start":"2026-02-11T01:58:32.623717Z","end":"2026-02-11T01:58:34.622807Z","steps":["trace[777856041] 'agreement among raft nodes before linearized reading' (duration: 1.998997798s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:34.622871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:32.623709Z","time spent":"1.999153381s","remote":"127.0.0.1:58022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/11 01:58:34 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-11T01:58:35.924755Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.044190026s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-11T01:58:36.112862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.232140922s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-11T01:58:36.113226Z","caller":"traceutil/trace.go:171","msg":"trace[1740004516] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"3.479993995s","start":"2026-02-11T01:58:32.633212Z","end":"2026-02-11T01:58:36.113206Z","steps":["trace[1740004516] 'process raft request' (duration: 3.479959534s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:36.113325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:32.633207Z","time spent":"3.480053846s","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:58:36.113555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:32.622075Z","time spent":"3.491478105s","remote":"127.0.0.1:58046","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-11T01:58:36.113693Z","caller":"traceutil/trace.go:171","msg":"trace[1587196542] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:916; }","duration":"3.489948098s","start":"2026-02-11T01:58:32.623733Z","end":"2026-02-11T01:58:36.113681Z","steps":["trace[1587196542] 'read index received' (duration: 256.986937ms)","trace[1587196542] 'applied index is now lower than readState.Index' (duration: 3.232960141s)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:58:36.113864Z","caller":"traceutil/trace.go:171","msg":"trace[602655220] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"3.485633047s","start":"2026-02-11T01:58:32.628222Z","end":"2026-02-11T01:58:36.113855Z","steps":["trace[602655220] 'process raft request' (duration: 3.484739466s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:36.113923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:32.628104Z","time spent":"3.485781741s","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:58:36.114041Z","caller":"traceutil/trace.go:171","msg":"trace[1501471504] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"3.485116175s","start":"2026-02-11T01:58:32.628915Z","end":"2026-02-11T01:58:36.114032Z","steps":["trace[1501471504] 'process raft request' (duration: 3.484157913s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:36.114089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:32.628907Z","time spent":"3.485153666s","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:58:36.1142Z","caller":"traceutil/trace.go:171","msg":"trace[27140712] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"3.48490144s","start":"2026-02-11T01:58:32.62929Z","end":"2026-02-11T01:58:36.114192Z","steps":["trace[27140712] 'process raft request' (duration: 3.483820425s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:36.11595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:32.629283Z","time spent":"3.486594291s","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:58:36.116059Z","caller":"traceutil/trace.go:171","msg":"trace[1328286774] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"3.483038316s","start":"2026-02-11T01:58:32.63301Z","end":"2026-02-11T01:58:36.116048Z","steps":["trace[1328286774] 'process raft request' (duration: 3.480130138s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:36.116108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:32.633003Z","time spent":"3.483079298s","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:58:36.307341Z","caller":"traceutil/trace.go:171","msg":"trace[1219509043] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"2.926142072s","start":"2026-02-11T01:58:33.38118Z","end":"2026-02-11T01:58:36.307322Z","steps":["trace[1219509043] 'process raft request' (duration: 2.829090202s)","trace[1219509043] 'compare' (duration: 96.673801ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:58:36.307459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:33.381164Z","time spent":"2.926232553s","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:58:36.30775Z","caller":"traceutil/trace.go:171","msg":"trace[1860410840] linearizableReadLoop","detail":"{readStateIndex:923; appliedIndex:922; }","duration":"194.027639ms","start":"2026-02-11T01:58:36.113714Z","end":"2026-02-11T01:58:36.307742Z","steps":["trace[1860410840] 'read index received' (duration: 96.57846ms)","trace[1860410840] 'applied index is now lower than readState.Index' (duration: 97.448409ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:58:36.307833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.516667091s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:58:36.307858Z","caller":"traceutil/trace.go:171","msg":"trace[1360212465] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:890; }","duration":"2.516733613s","start":"2026-02-11T01:58:33.791118Z","end":"2026-02-11T01:58:36.307852Z","steps":["trace[1360212465] 'agreement among raft nodes before linearized reading' (duration: 2.516686072s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:36.307889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:33.7911Z","time spent":"2.516783685s","remote":"127.0.0.1:58256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:58:36.308005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.67898913s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:58:36.308028Z","caller":"traceutil/trace.go:171","msg":"trace[482027147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:890; }","duration":"1.679062352s","start":"2026-02-11T01:58:34.62896Z","end":"2026-02-11T01:58:36.308022Z","steps":["trace[482027147] 'agreement among raft nodes before linearized reading' (duration: 1.679026791s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:36.308046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:34.628948Z","time spent":"1.679093682s","remote":"127.0.0.1:58020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:58:36.308203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.680470304s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18930e951cb04a08\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-11T01:58:36.308229Z","caller":"traceutil/trace.go:171","msg":"trace[2053096669] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18930e951cb04a08; range_end:; response_count:1; response_revision:890; }","duration":"1.680549906s","start":"2026-02-11T01:58:34.627673Z","end":"2026-02-11T01:58:36.308223Z","steps":["trace[2053096669] 'agreement among raft nodes before linearized reading' (duration: 1.680453724s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:36.308247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:34.627644Z","time spent":"1.680598367s","remote":"127.0.0.1:58118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18930e951cb04a08\" "} {"level":"warn","ts":"2026-02-11T01:58:36.308361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.18719972s","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-11T01:58:36.308387Z","caller":"traceutil/trace.go:171","msg":"trace[152121188] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:890; }","duration":"2.187257081s","start":"2026-02-11T01:58:34.121122Z","end":"2026-02-11T01:58:36.308379Z","steps":["trace[152121188] 'agreement among raft nodes before linearized reading' (duration: 2.18721457s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:36.308407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:34.121107Z","time spent":"2.187295013s","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-02-11T01:58:36.30885Z","caller":"traceutil/trace.go:171","msg":"trace[364336645] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"191.453438ms","start":"2026-02-11T01:58:36.117388Z","end":"2026-02-11T01:58:36.308842Z","steps":["trace[364336645] 'process raft request' (duration: 190.305421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:36.798443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.695379ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:58:36.798529Z","caller":"traceutil/trace.go:171","msg":"trace[1393041506] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:924; }","duration":"484.469033ms","start":"2026-02-11T01:58:36.314048Z","end":"2026-02-11T01:58:36.798517Z","steps":["trace[1393041506] 'read index received' (duration: 302.474937ms)","trace[1393041506] 'applied index is now lower than readState.Index' (duration: 181.993066ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:58:36.798579Z","caller":"traceutil/trace.go:171","msg":"trace[238886859] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"485.597511ms","start":"2026-02-11T01:58:36.312972Z","end":"2026-02-11T01:58:36.798569Z","steps":["trace[238886859] 'process raft request' (duration: 303.626935ms)","trace[238886859] 'compare' (duration: 181.372422ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:58:36.798626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:36.312961Z","time spent":"485.642291ms","remote":"127.0.0.1:58118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:58:36.798971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.547314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:58:36.799Z","caller":"traceutil/trace.go:171","msg":"trace[1762878224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:892; }","duration":"481.588976ms","start":"2026-02-11T01:58:36.317403Z","end":"2026-02-11T01:58:36.798992Z","steps":["trace[1762878224] 'agreement among raft nodes before linearized reading' (duration: 481.537404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:36.799019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:36.317395Z","time spent":"481.619606ms","remote":"127.0.0.1:58020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:58:36.799224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.17161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T01:58:36.799243Z","caller":"traceutil/trace.go:171","msg":"trace[215672262] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:892; }","duration":"485.20251ms","start":"2026-02-11T01:58:36.314034Z","end":"2026-02-11T01:58:36.799237Z","steps":["trace[215672262] 'agreement among raft nodes before linearized reading' (duration: 484.871443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:36.799319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:36.314028Z","time spent":"485.285542ms","remote":"127.0.0.1:58046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-02-11T01:58:37.357504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.784022ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-11T01:58:37.358275Z","caller":"traceutil/trace.go:171","msg":"trace[861643595] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"195.177566ms","start":"2026-02-11T01:58:37.163067Z","end":"2026-02-11T01:58:37.358245Z","steps":["trace[861643595] 'process raft request' (duration: 194.550651ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:58:38.744432Z","caller":"traceutil/trace.go:171","msg":"trace[98101825] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"615.936541ms","start":"2026-02-11T01:58:38.128481Z","end":"2026-02-11T01:58:38.744418Z","steps":["trace[98101825] 'process raft request' (duration: 615.842369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:38.744551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:38.128472Z","time spent":"616.027483ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:58:38.747448Z","caller":"traceutil/trace.go:171","msg":"trace[2085936128] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"618.513002ms","start":"2026-02-11T01:58:38.128917Z","end":"2026-02-11T01:58:38.74743Z","steps":["trace[2085936128] 'process raft request' (duration: 618.268976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:38.74769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:38.12891Z","time spent":"618.719078ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:58:38.748044Z","caller":"traceutil/trace.go:171","msg":"trace[1066095749] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"619.081946ms","start":"2026-02-11T01:58:38.128955Z","end":"2026-02-11T01:58:38.748037Z","steps":["trace[1066095749] 'process raft request' (duration: 618.308407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:38.748091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:38.128951Z","time spent":"619.117537ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:58:38.748427Z","caller":"traceutil/trace.go:171","msg":"trace[1419630375] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"615.090123ms","start":"2026-02-11T01:58:38.13331Z","end":"2026-02-11T01:58:38.7484Z","steps":["trace[1419630375] 'process raft request' (duration: 614.702564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:38.748469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:38.133301Z","time spent":"615.146154ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:58:38.748555Z","caller":"traceutil/trace.go:171","msg":"trace[118889171] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"619.342632ms","start":"2026-02-11T01:58:38.129207Z","end":"2026-02-11T01:58:38.74855Z","steps":["trace[118889171] 'process raft request' (duration: 618.079632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:38.748597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:38.129194Z","time spent":"619.385463ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:58:47.185977Z","caller":"traceutil/trace.go:171","msg":"trace[1517399332] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:949; }","duration":"409.992924ms","start":"2026-02-11T01:58:46.77597Z","end":"2026-02-11T01:58:47.185963Z","steps":["trace[1517399332] 'read index received' (duration: 409.877311ms)","trace[1517399332] 'applied index is now lower than readState.Index' (duration: 115.063µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:58:47.186194Z","caller":"traceutil/trace.go:171","msg":"trace[1877731546] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"660.715844ms","start":"2026-02-11T01:58:46.525473Z","end":"2026-02-11T01:58:47.186188Z","steps":["trace[1877731546] 'process raft request' (duration: 660.413267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:47.186252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:46.52546Z","time spent":"660.748295ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:58:47.186389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.417794ms","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-11T01:58:47.186423Z","caller":"traceutil/trace.go:171","msg":"trace[766656129] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:915; }","duration":"410.476165ms","start":"2026-02-11T01:58:46.775943Z","end":"2026-02-11T01:58:47.186419Z","steps":["trace[766656129] 'agreement among raft nodes before linearized reading' (duration: 410.396044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:47.186437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:46.77593Z","time spent":"410.503566ms","remote":"127.0.0.1:58296","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-11T01:58:47.186524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.804847ms","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-11T01:58:47.186536Z","caller":"traceutil/trace.go:171","msg":"trace[1283598268] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:915; }","duration":"397.817047ms","start":"2026-02-11T01:58:46.788716Z","end":"2026-02-11T01:58:47.186533Z","steps":["trace[1283598268] 'agreement among raft nodes before linearized reading' (duration: 397.782336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:47.186547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:46.788679Z","time spent":"397.865408ms","remote":"127.0.0.1:58296","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":"warn","ts":"2026-02-11T01:58:47.186641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.716581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:58:47.186654Z","caller":"traceutil/trace.go:171","msg":"trace[852642445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:915; }","duration":"110.752363ms","start":"2026-02-11T01:58:47.075898Z","end":"2026-02-11T01:58:47.186651Z","steps":["trace[852642445] 'agreement among raft nodes before linearized reading' (duration: 110.731462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:47.186728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.412114ms","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-11T01:58:47.186742Z","caller":"traceutil/trace.go:171","msg":"trace[1480610313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:915; }","duration":"379.429415ms","start":"2026-02-11T01:58:46.807306Z","end":"2026-02-11T01:58:47.186736Z","steps":["trace[1480610313] 'agreement among raft nodes before linearized reading' (duration: 379.397384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:47.186754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:46.807304Z","time spent":"379.447335ms","remote":"127.0.0.1:58296","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-11T01:58:47.186827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.528887ms","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-11T01:58:47.186839Z","caller":"traceutil/trace.go:171","msg":"trace[995080050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:915; }","duration":"379.553527ms","start":"2026-02-11T01:58:46.807282Z","end":"2026-02-11T01:58:47.186835Z","steps":["trace[995080050] 'agreement among raft nodes before linearized reading' (duration: 379.519376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:47.186849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:46.807276Z","time spent":"379.570637ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T01:58:47.186918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.281176ms","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-11T01:58:47.186931Z","caller":"traceutil/trace.go:171","msg":"trace[1797070552] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:915; }","duration":"383.315226ms","start":"2026-02-11T01:58:46.803613Z","end":"2026-02-11T01:58:47.186928Z","steps":["trace[1797070552] 'agreement among raft nodes before linearized reading' (duration: 383.280825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:47.186942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:46.80359Z","time spent":"383.349247ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T01:58:47.399069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.077559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:58:47.399199Z","caller":"traceutil/trace.go:171","msg":"trace[1272100818] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"199.278332ms","start":"2026-02-11T01:58:47.199911Z","end":"2026-02-11T01:58:47.399189Z","steps":["trace[1272100818] 'process raft request' (duration: 199.262921ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:58:47.399349Z","caller":"traceutil/trace.go:171","msg":"trace[1627518035] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"204.400662ms","start":"2026-02-11T01:58:47.194943Z","end":"2026-02-11T01:58:47.399344Z","steps":["trace[1627518035] 'process raft request' (duration: 79.004156ms)","trace[1627518035] 'compare' (duration: 125.015598ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:58:47.399403Z","caller":"traceutil/trace.go:171","msg":"trace[1805777989] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"202.137439ms","start":"2026-02-11T01:58:47.197262Z","end":"2026-02-11T01:58:47.399399Z","steps":["trace[1805777989] 'process raft request' (duration: 201.862802ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:58:47.399449Z","caller":"traceutil/trace.go:171","msg":"trace[291836294] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"202.130879ms","start":"2026-02-11T01:58:47.197315Z","end":"2026-02-11T01:58:47.399446Z","steps":["trace[291836294] 'process raft request' (duration: 201.831242ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:58:47.399493Z","caller":"traceutil/trace.go:171","msg":"trace[1794721169] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"201.952075ms","start":"2026-02-11T01:58:47.197537Z","end":"2026-02-11T01:58:47.39949Z","steps":["trace[1794721169] 'process raft request' (duration: 201.623807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:47.772352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.675274ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:58:47.772467Z","caller":"traceutil/trace.go:171","msg":"trace[317829233] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"185.448407ms","start":"2026-02-11T01:58:47.587002Z","end":"2026-02-11T01:58:47.77245Z","steps":["trace[317829233] 'process raft request' (duration: 79.602519ms)","trace[317829233] 'compare' (duration: 105.367167ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:58:48.011677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.946651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:58:48.011748Z","caller":"traceutil/trace.go:171","msg":"trace[1730319887] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:921; }","duration":"115.075715ms","start":"2026-02-11T01:58:47.896656Z","end":"2026-02-11T01:58:48.011731Z","steps":["trace[1730319887] 'count revisions from in-memory index tree' (duration: 114.87571ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:58:49.550335Z","caller":"traceutil/trace.go:171","msg":"trace[866632757] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"145.289554ms","start":"2026-02-11T01:58:49.405007Z","end":"2026-02-11T01:58:49.550296Z","steps":["trace[866632757] 'process raft request' (duration: 145.191712ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:58:49.847496Z","caller":"traceutil/trace.go:171","msg":"trace[329153074] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"441.795171ms","start":"2026-02-11T01:58:49.405686Z","end":"2026-02-11T01:58:49.847481Z","steps":["trace[329153074] 'process raft request' (duration: 441.654567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:49.847584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:49.40568Z","time spent":"441.858532ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:58:49.847823Z","caller":"traceutil/trace.go:171","msg":"trace[537333611] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"441.231648ms","start":"2026-02-11T01:58:49.40657Z","end":"2026-02-11T01:58:49.847801Z","steps":["trace[537333611] 'process raft request' (duration: 440.87882ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:58:49.847881Z","caller":"traceutil/trace.go:171","msg":"trace[1265312658] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"441.278669ms","start":"2026-02-11T01:58:49.406597Z","end":"2026-02-11T01:58:49.847876Z","steps":["trace[1265312658] 'process raft request' (duration: 441.108845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:49.847912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:49.406594Z","time spent":"441.301719ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:58:49.84796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:49.406565Z","time spent":"441.32527ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:58:49.848033Z","caller":"traceutil/trace.go:171","msg":"trace[1938981173] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"440.923639ms","start":"2026-02-11T01:58:49.407105Z","end":"2026-02-11T01:58:49.848029Z","steps":["trace[1938981173] 'process raft request' (duration: 440.747665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:49.848059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:49.4071Z","time spent":"440.94501ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:58:54.08241Z","caller":"traceutil/trace.go:171","msg":"trace[1461117245] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:970; }","duration":"215.587356ms","start":"2026-02-11T01:58:53.866809Z","end":"2026-02-11T01:58:54.082397Z","steps":["trace[1461117245] 'read index received' (duration: 215.458463ms)","trace[1461117245] 'applied index is now lower than readState.Index' (duration: 128.253µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:58:54.082597Z","caller":"traceutil/trace.go:171","msg":"trace[1665474725] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"217.230694ms","start":"2026-02-11T01:58:53.865358Z","end":"2026-02-11T01:58:54.082589Z","steps":["trace[1665474725] 'process raft request' (duration: 216.950528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:54.082808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.336403ms","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-11T01:58:54.08287Z","caller":"traceutil/trace.go:171","msg":"trace[521243237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:934; }","duration":"213.421614ms","start":"2026-02-11T01:58:53.869426Z","end":"2026-02-11T01:58:54.082848Z","steps":["trace[521243237] 'agreement among raft nodes before linearized reading' (duration: 213.24255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:54.082926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.102118ms","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-11T01:58:54.082952Z","caller":"traceutil/trace.go:171","msg":"trace[1800859394] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:934; }","duration":"216.145869ms","start":"2026-02-11T01:58:53.866799Z","end":"2026-02-11T01:58:54.082945Z","steps":["trace[1800859394] 'agreement among raft nodes before linearized reading' (duration: 216.065147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:54.083124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.549074ms","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-11T01:58:54.083147Z","caller":"traceutil/trace.go:171","msg":"trace[572716519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:934; }","duration":"209.587355ms","start":"2026-02-11T01:58:53.873549Z","end":"2026-02-11T01:58:54.083136Z","steps":["trace[572716519] 'agreement among raft nodes before linearized reading' (duration: 209.495833ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:58:54.371484Z","caller":"traceutil/trace.go:171","msg":"trace[1722202608] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"281.705109ms","start":"2026-02-11T01:58:54.089758Z","end":"2026-02-11T01:58:54.371463Z","steps":["trace[1722202608] 'process raft request' (duration: 241.539855ms)","trace[1722202608] 'compare' (duration: 40.030811ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:58:54.405759Z","caller":"traceutil/trace.go:171","msg":"trace[1205230105] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"315.927403ms","start":"2026-02-11T01:58:54.089812Z","end":"2026-02-11T01:58:54.405739Z","steps":["trace[1205230105] 'process raft request' (duration: 315.516564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:54.405917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:54.089808Z","time spent":"316.021456ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:58:54.406813Z","caller":"traceutil/trace.go:171","msg":"trace[1024652154] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"316.949567ms","start":"2026-02-11T01:58:54.089846Z","end":"2026-02-11T01:58:54.406796Z","steps":["trace[1024652154] 'process raft request' (duration: 315.824831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:54.408149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:58:54.089843Z","time spent":"317.031199ms","remote":"127.0.0.1:58296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:58:55.839215Z","caller":"traceutil/trace.go:171","msg":"trace[1952156346] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"271.896288ms","start":"2026-02-11T01:58:55.567303Z","end":"2026-02-11T01:58:55.839199Z","steps":["trace[1952156346] 'process raft request' (duration: 271.792186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:58:56.159097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.502461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:58:56.159155Z","caller":"traceutil/trace.go:171","msg":"trace[401390526] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:938; }","duration":"200.623324ms","start":"2026-02-11T01:58:55.958519Z","end":"2026-02-11T01:58:56.159142Z","steps":["trace[401390526] 'count revisions from in-memory index tree' (duration: 200.44516ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:58:56.390847Z","caller":"traceutil/trace.go:171","msg":"trace[1526009007] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"229.180425ms","start":"2026-02-11T01:58:56.16165Z","end":"2026-02-11T01:58:56.390831Z","steps":["trace[1526009007] 'process raft request' (duration: 228.885988ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:58:56.564463Z","caller":"traceutil/trace.go:171","msg":"trace[1008359593] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"126.869602ms","start":"2026-02-11T01:58:56.437578Z","end":"2026-02-11T01:58:56.564447Z","steps":["trace[1008359593] 'process raft request' (duration: 126.600504ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:58:57.31807Z","caller":"traceutil/trace.go:171","msg":"trace[1480244467] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:979; }","duration":"126.068192ms","start":"2026-02-11T01:58:57.191984Z","end":"2026-02-11T01:58:57.318052Z","steps":["trace[1480244467] 'read index received' (duration: 119.409135ms)","trace[1480244467] 'applied index is now lower than readState.Index' (duration: 6.657017ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:58:57.318196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.195365ms","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-11T01:58:57.318254Z","caller":"traceutil/trace.go:171","msg":"trace[881691344] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:942; }","duration":"126.268376ms","start":"2026-02-11T01:58:57.191971Z","end":"2026-02-11T01:58:57.318239Z","steps":["trace[881691344] 'agreement among raft nodes before linearized reading' (duration: 126.152584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:59:00.062227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.650321ms","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-11T01:59:00.062274Z","caller":"traceutil/trace.go:171","msg":"trace[1813166300] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:965; }","duration":"199.733042ms","start":"2026-02-11T01:58:59.862531Z","end":"2026-02-11T01:59:00.062264Z","steps":["trace[1813166300] 'range keys from in-memory index tree' (duration: 199.549008ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:59:26.219511Z","caller":"traceutil/trace.go:171","msg":"trace[1498500879] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"258.419731ms","start":"2026-02-11T01:59:25.96106Z","end":"2026-02-11T01:59:26.219479Z","steps":["trace[1498500879] 'process raft request' (duration: 258.282128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:59:26.447253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.589192ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:59:26.44886Z","caller":"traceutil/trace.go:171","msg":"trace[1593320613] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"163.096522ms","start":"2026-02-11T01:59:26.285736Z","end":"2026-02-11T01:59:26.448832Z","steps":["trace[1593320613] 'process raft request' (duration: 52.854402ms)","trace[1593320613] 'compare' (duration: 108.50158ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:59:26.802925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.969756ms","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-11T01:59:26.80301Z","caller":"traceutil/trace.go:171","msg":"trace[1809492330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1107; }","duration":"116.090728ms","start":"2026-02-11T01:59:26.686897Z","end":"2026-02-11T01:59:26.802988Z","steps":["trace[1809492330] 'range keys from in-memory index tree' (duration: 115.795342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:59:26.803444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.031294ms","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-11T01:59:26.803484Z","caller":"traceutil/trace.go:171","msg":"trace[865178498] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1107; }","duration":"127.160617ms","start":"2026-02-11T01:59:26.676314Z","end":"2026-02-11T01:59:26.803474Z","steps":["trace[865178498] 'range keys from in-memory index tree' (duration: 126.879411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:59:26.803676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.217501ms","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-11T01:59:26.80371Z","caller":"traceutil/trace.go:171","msg":"trace[1956510460] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1107; }","duration":"119.293843ms","start":"2026-02-11T01:59:26.684408Z","end":"2026-02-11T01:59:26.803702Z","steps":["trace[1956510460] 'range keys from in-memory index tree' (duration: 119.105339ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:00:00.756799Z","caller":"traceutil/trace.go:171","msg":"trace[700969555] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"165.95754ms","start":"2026-02-11T02:00:00.590817Z","end":"2026-02-11T02:00:00.756775Z","steps":["trace[700969555] 'process raft request' (duration: 165.814767ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:00:06.422823Z","caller":"traceutil/trace.go:171","msg":"trace[492764293] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"132.373329ms","start":"2026-02-11T02:00:06.290421Z","end":"2026-02-11T02:00:06.422795Z","steps":["trace[492764293] 'process raft request' (duration: 131.943969ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:02:20.560081Z","caller":"traceutil/trace.go:171","msg":"trace[1134997229] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1927; }","duration":"201.648024ms","start":"2026-02-11T02:02:20.358416Z","end":"2026-02-11T02:02:20.560065Z","steps":["trace[1134997229] 'read index received' (duration: 201.50087ms)","trace[1134997229] 'applied index is now lower than readState.Index' (duration: 146.414µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:02:20.560234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.802027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:02:20.560266Z","caller":"traceutil/trace.go:171","msg":"trace[2049718107] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1849; }","duration":"201.878499ms","start":"2026-02-11T02:02:20.358381Z","end":"2026-02-11T02:02:20.560259Z","steps":["trace[2049718107] 'agreement among raft nodes before linearized reading' (duration: 201.767767ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:02:20.560644Z","caller":"traceutil/trace.go:171","msg":"trace[1862034339] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"221.586545ms","start":"2026-02-11T02:02:20.339029Z","end":"2026-02-11T02:02:20.560615Z","steps":["trace[1862034339] 'process raft request' (duration: 220.937869ms)"],"step_count":1}