{"level":"warn","ts":"2026-02-08T16:36:33.066342Z","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-08T16:36:33.067263Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.70:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.70:2380","--initial-cluster=instance=https://162.253.55.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.70:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-08T16:36:33.070115Z","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-08T16:36:33.07019Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-02-08T16:36:33.070495Z","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-08T16:36:33.081061Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"]} {"level":"info","ts":"2026-02-08T16:36:33.082262Z","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.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.70:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-08T16:36:33.092764Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.303848ms"} {"level":"info","ts":"2026-02-08T16:36:33.102355Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"23887246632736aa","cluster-id":"62cfeca3ef46d3ef"} {"level":"info","ts":"2026-02-08T16:36:33.102401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=()"} {"level":"info","ts":"2026-02-08T16:36:33.102415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 0"} {"level":"info","ts":"2026-02-08T16:36:33.102426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 23887246632736aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-08T16:36:33.102435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 1"} {"level":"info","ts":"2026-02-08T16:36:33.102463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"warn","ts":"2026-02-08T16:36:33.119663Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-08T16:36:33.12255Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-08T16:36:33.125607Z","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-08T16:36:33.127888Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"23887246632736aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-08T16:36:33.129637Z","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-08T16:36:33.129774Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"23887246632736aa","initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-08T16:36:33.129797Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-08T16:36:33.130331Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"23887246632736aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-08T16:36:33.130434Z","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-08T16:36:33.130456Z","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-08T16:36:33.130462Z","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-08T16:36:33.130621Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-02-08T16:36:33.130635Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-02-08T16:36:33.13997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"info","ts":"2026-02-08T16:36:33.140062Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","added-peer-id":"23887246632736aa","added-peer-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-02-08T16:36:33.706922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa is starting a new election at term 1"} {"level":"info","ts":"2026-02-08T16:36:33.706965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became pre-candidate at term 1"} {"level":"info","ts":"2026-02-08T16:36:33.706988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgPreVoteResp from 23887246632736aa at term 1"} {"level":"info","ts":"2026-02-08T16:36:33.707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became candidate at term 2"} {"level":"info","ts":"2026-02-08T16:36:33.707024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgVoteResp from 23887246632736aa at term 2"} {"level":"info","ts":"2026-02-08T16:36:33.707035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became leader at term 2"} {"level":"info","ts":"2026-02-08T16:36:33.707042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 23887246632736aa elected leader 23887246632736aa at term 2"} {"level":"info","ts":"2026-02-08T16:36:33.70821Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"23887246632736aa","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.70:2379]}","request-path":"/0/members/23887246632736aa/attributes","cluster-id":"62cfeca3ef46d3ef","publish-timeout":"7s"} {"level":"info","ts":"2026-02-08T16:36:33.708328Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T16:36:33.708565Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T16:36:33.708629Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T16:36:33.708744Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-08T16:36:33.708753Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-08T16:36:33.710577Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.70:2379"} {"level":"info","ts":"2026-02-08T16:36:33.710639Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T16:36:33.710679Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T16:36:33.71069Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T16:36:33.715775Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-08T16:36:47.172781Z","caller":"traceutil/trace.go:171","msg":"trace[681302131] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:375; }","duration":"172.437006ms","start":"2026-02-08T16:36:47.000328Z","end":"2026-02-08T16:36:47.172765Z","steps":["trace[681302131] 'read index received' (duration: 172.246521ms)","trace[681302131] 'applied index is now lower than readState.Index' (duration: 189.895µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:36:47.172889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.548378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-08T16:36:47.17292Z","caller":"traceutil/trace.go:171","msg":"trace[537244278] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:366; }","duration":"172.61672ms","start":"2026-02-08T16:36:47.000296Z","end":"2026-02-08T16:36:47.172913Z","steps":["trace[537244278] 'agreement among raft nodes before linearized reading' (duration: 172.531058ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:36:47.173078Z","caller":"traceutil/trace.go:171","msg":"trace[1791450871] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"260.843618ms","start":"2026-02-08T16:36:46.912229Z","end":"2026-02-08T16:36:47.173072Z","steps":["trace[1791450871] 'process raft request' (duration: 260.410239ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:03.974977Z","caller":"traceutil/trace.go:171","msg":"trace[1443683035] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:492; }","duration":"228.847487ms","start":"2026-02-08T16:37:03.746113Z","end":"2026-02-08T16:37:03.974961Z","steps":["trace[1443683035] 'read index received' (duration: 203.24543ms)","trace[1443683035] 'applied index is now lower than readState.Index' (duration: 25.601547ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:37:03.975197Z","caller":"traceutil/trace.go:171","msg":"trace[249488910] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"229.558983ms","start":"2026-02-08T16:37:03.745631Z","end":"2026-02-08T16:37:03.97519Z","steps":["trace[249488910] 'process raft request' (duration: 203.785832ms)","trace[249488910] 'compare' (duration: 25.480675ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:37:03.975361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.233177ms","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-08T16:37:03.97538Z","caller":"traceutil/trace.go:171","msg":"trace[1789578160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:478; }","duration":"229.272007ms","start":"2026-02-08T16:37:03.746101Z","end":"2026-02-08T16:37:03.975373Z","steps":["trace[1789578160] 'agreement among raft nodes before linearized reading' (duration: 229.227076ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:05.844035Z","caller":"traceutil/trace.go:171","msg":"trace[1626748664] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"108.76137ms","start":"2026-02-08T16:37:05.735256Z","end":"2026-02-08T16:37:05.844017Z","steps":["trace[1626748664] 'process raft request' (duration: 108.644107ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:09.969383Z","caller":"traceutil/trace.go:171","msg":"trace[961640301] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:506; }","duration":"110.669374ms","start":"2026-02-08T16:37:09.8587Z","end":"2026-02-08T16:37:09.969369Z","steps":["trace[961640301] 'read index received' (duration: 110.561852ms)","trace[961640301] 'applied index is now lower than readState.Index' (duration: 106.982µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:37:09.969468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.764967ms","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-08T16:37:09.969485Z","caller":"traceutil/trace.go:171","msg":"trace[517807212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:491; }","duration":"110.793357ms","start":"2026-02-08T16:37:09.858686Z","end":"2026-02-08T16:37:09.969479Z","steps":["trace[517807212] 'agreement among raft nodes before linearized reading' (duration: 110.734426ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:09.96962Z","caller":"traceutil/trace.go:171","msg":"trace[1047514502] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"298.984607ms","start":"2026-02-08T16:37:09.67063Z","end":"2026-02-08T16:37:09.969615Z","steps":["trace[1047514502] 'process raft request' (duration: 298.66795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:10.608498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.876421ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T16:37:10.608561Z","caller":"traceutil/trace.go:171","msg":"trace[1191274869] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:507; }","duration":"586.266261ms","start":"2026-02-08T16:37:10.022285Z","end":"2026-02-08T16:37:10.608551Z","steps":["trace[1191274869] 'read index received' (duration: 288.89533ms)","trace[1191274869] 'applied index is now lower than readState.Index' (duration: 297.370171ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:37:10.608693Z","caller":"traceutil/trace.go:171","msg":"trace[678330013] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"635.684985ms","start":"2026-02-08T16:37:09.973001Z","end":"2026-02-08T16:37:10.608686Z","steps":["trace[678330013] 'process raft request' (duration: 338.238092ms)","trace[678330013] 'compare' (duration: 296.729788ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:37:10.608788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:09.97299Z","time spent":"635.737727ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:37:10.60894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.657181ms","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-08T16:37:10.608958Z","caller":"traceutil/trace.go:171","msg":"trace[242153135] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:492; }","duration":"586.698181ms","start":"2026-02-08T16:37:10.022255Z","end":"2026-02-08T16:37:10.608953Z","steps":["trace[242153135] 'agreement among raft nodes before linearized reading' (duration: 586.661371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:10.608976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:10.022242Z","time spent":"586.730472ms","remote":"127.0.0.1:37150","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-08T16:37:10.609061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.531288ms","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-08T16:37:10.609076Z","caller":"traceutil/trace.go:171","msg":"trace[1081991192] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:492; }","duration":"582.572369ms","start":"2026-02-08T16:37:10.026499Z","end":"2026-02-08T16:37:10.609072Z","steps":["trace[1081991192] 'agreement among raft nodes before linearized reading' (duration: 582.545458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:10.609091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:10.026471Z","time spent":"582.61669ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T16:37:10.609267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.251546ms","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-08T16:37:10.609284Z","caller":"traceutil/trace.go:171","msg":"trace[232491534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:492; }","duration":"580.279057ms","start":"2026-02-08T16:37:10.029001Z","end":"2026-02-08T16:37:10.60928Z","steps":["trace[232491534] 'agreement among raft nodes before linearized reading' (duration: 580.249526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:10.6093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:10.028995Z","time spent":"580.301248ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T16:37:11.727813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.499251ms","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-08T16:37:11.72788Z","caller":"traceutil/trace.go:171","msg":"trace[1881716950] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:497; }","duration":"355.607804ms","start":"2026-02-08T16:37:11.372257Z","end":"2026-02-08T16:37:11.727865Z","steps":["trace[1881716950] 'range keys from in-memory index tree' (duration: 355.374128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:11.727917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:11.372245Z","time spent":"355.663935ms","remote":"127.0.0.1:37000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-08T16:37:24.459249Z","caller":"traceutil/trace.go:171","msg":"trace[1571152172] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"649.036276ms","start":"2026-02-08T16:37:23.810192Z","end":"2026-02-08T16:37:24.459228Z","steps":["trace[1571152172] 'process raft request' (duration: 648.844042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:24.459386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:23.810175Z","time spent":"649.142688ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:37:27.125274Z","caller":"traceutil/trace.go:171","msg":"trace[1696680599] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"393.479817ms","start":"2026-02-08T16:37:26.731771Z","end":"2026-02-08T16:37:27.125251Z","steps":["trace[1696680599] 'process raft request' (duration: 393.350844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:27.125428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:26.731759Z","time spent":"393.591469ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:37:27.216281Z","caller":"traceutil/trace.go:171","msg":"trace[1414154015] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:690; }","duration":"470.585765ms","start":"2026-02-08T16:37:26.745675Z","end":"2026-02-08T16:37:27.216261Z","steps":["trace[1414154015] 'read index received' (duration: 380.193487ms)","trace[1414154015] 'applied index is now lower than readState.Index' (duration: 90.391468ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:37:27.21666Z","caller":"traceutil/trace.go:171","msg":"trace[1074338126] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"483.948917ms","start":"2026-02-08T16:37:26.732699Z","end":"2026-02-08T16:37:27.216648Z","steps":["trace[1074338126] 'process raft request' (duration: 405.711384ms)","trace[1074338126] 'compare' (duration: 77.458185ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:37:27.216755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:26.732694Z","time spent":"483.994978ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:37:27.216988Z","caller":"traceutil/trace.go:171","msg":"trace[1186637849] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"424.081308ms","start":"2026-02-08T16:37:26.79286Z","end":"2026-02-08T16:37:27.216941Z","steps":["trace[1186637849] 'process raft request' (duration: 423.322381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:27.217044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:26.792847Z","time spent":"424.164809ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:37:27.217167Z","caller":"traceutil/trace.go:171","msg":"trace[1106108722] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"141.428867ms","start":"2026-02-08T16:37:27.07573Z","end":"2026-02-08T16:37:27.217159Z","steps":["trace[1106108722] 'process raft request' (duration: 140.493567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:27.217355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.673169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T16:37:27.217384Z","caller":"traceutil/trace.go:171","msg":"trace[896182940] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:673; }","duration":"471.71929ms","start":"2026-02-08T16:37:26.745657Z","end":"2026-02-08T16:37:27.217376Z","steps":["trace[896182940] 'agreement among raft nodes before linearized reading' (duration: 471.618098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:27.217412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:26.74565Z","time spent":"471.755491ms","remote":"127.0.0.1:37150","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-08T16:37:27.217582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.990954ms","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-08T16:37:27.217607Z","caller":"traceutil/trace.go:171","msg":"trace[1533880139] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:673; }","duration":"471.015204ms","start":"2026-02-08T16:37:26.746584Z","end":"2026-02-08T16:37:27.217599Z","steps":["trace[1533880139] 'agreement among raft nodes before linearized reading' (duration: 470.940703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:27.21763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:26.74657Z","time spent":"471.055666ms","remote":"127.0.0.1:37150","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-08T16:37:27.217953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.297637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-w4h4f\" ","response":"range_response_count:1 size:4823"} {"level":"info","ts":"2026-02-08T16:37:27.217982Z","caller":"traceutil/trace.go:171","msg":"trace[635933726] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-w4h4f; range_end:; response_count:1; response_revision:673; }","duration":"142.349938ms","start":"2026-02-08T16:37:27.075623Z","end":"2026-02-08T16:37:27.217973Z","steps":["trace[635933726] 'agreement among raft nodes before linearized reading' (duration: 142.270647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:27.218153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.605371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-08T16:37:27.218177Z","caller":"traceutil/trace.go:171","msg":"trace[1554963734] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:673; }","duration":"216.656632ms","start":"2026-02-08T16:37:27.001513Z","end":"2026-02-08T16:37:27.21817Z","steps":["trace[1554963734] 'agreement among raft nodes before linearized reading' (duration: 216.58642ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:27.433872Z","caller":"traceutil/trace.go:171","msg":"trace[550398060] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"210.192417ms","start":"2026-02-08T16:37:27.223663Z","end":"2026-02-08T16:37:27.433855Z","steps":["trace[550398060] 'process raft request' (duration: 210.168016ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:27.43402Z","caller":"traceutil/trace.go:171","msg":"trace[124687720] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"210.825771ms","start":"2026-02-08T16:37:27.223187Z","end":"2026-02-08T16:37:27.434013Z","steps":["trace[124687720] 'process raft request' (duration: 210.556635ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:27.4536Z","caller":"traceutil/trace.go:171","msg":"trace[1750726597] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"217.116423ms","start":"2026-02-08T16:37:27.23645Z","end":"2026-02-08T16:37:27.453566Z","steps":["trace[1750726597] 'process raft request' (duration: 216.9974ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:28.592096Z","caller":"traceutil/trace.go:171","msg":"trace[1157503939] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"319.133692ms","start":"2026-02-08T16:37:28.272947Z","end":"2026-02-08T16:37:28.59208Z","steps":["trace[1157503939] 'process raft request' (duration: 319.01426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:28.592184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:28.272922Z","time spent":"319.220864ms","remote":"127.0.0.1:36960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-08T16:37:28.738328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.059448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:37:28.738788Z","caller":"traceutil/trace.go:171","msg":"trace[328633272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:677; }","duration":"144.671181ms","start":"2026-02-08T16:37:28.594098Z","end":"2026-02-08T16:37:28.738769Z","steps":["trace[328633272] 'range keys from in-memory index tree' (duration: 144.016846ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:37:28.741921Z","caller":"traceutil/trace.go:171","msg":"trace[1158151876] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"144.969867ms","start":"2026-02-08T16:37:28.596935Z","end":"2026-02-08T16:37:28.741905Z","steps":["trace[1158151876] 'process raft request' (duration: 110.999602ms)","trace[1158151876] 'compare' (duration: 33.849483ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:37:33.869435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.542989443s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-08T16:37:33.869562Z","caller":"traceutil/trace.go:171","msg":"trace[1821144740] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:709; }","duration":"1.352453449s","start":"2026-02-08T16:37:32.51709Z","end":"2026-02-08T16:37:33.869543Z","steps":["trace[1821144740] 'read index received' (duration: 21.51µs)","trace[1821144740] 'applied index is now lower than readState.Index' (duration: 1.352429969s)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:37:33.875011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.35789388s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:37:33.875222Z","caller":"traceutil/trace.go:171","msg":"trace[133920301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:688; }","duration":"1.358002434s","start":"2026-02-08T16:37:32.517047Z","end":"2026-02-08T16:37:33.87505Z","steps":["trace[133920301] 'agreement among raft nodes before linearized reading' (duration: 1.357743997s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:33.87526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:32.51703Z","time spent":"1.358219789s","remote":"127.0.0.1:36858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T16:37:33.877089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124104643s","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-08T16:37:33.877124Z","caller":"traceutil/trace.go:171","msg":"trace[854938356] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:688; }","duration":"1.124162603s","start":"2026-02-08T16:37:32.752946Z","end":"2026-02-08T16:37:33.877109Z","steps":["trace[854938356] 'agreement among raft nodes before linearized reading' (duration: 1.124045131s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:33.877166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:32.752932Z","time spent":"1.124219595s","remote":"127.0.0.1:37150","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-08T16:37:33.87799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.767855ms","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-08T16:37:33.878019Z","caller":"traceutil/trace.go:171","msg":"trace[1082924874] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:688; }","duration":"389.811015ms","start":"2026-02-08T16:37:33.488195Z","end":"2026-02-08T16:37:33.878006Z","steps":["trace[1082924874] 'agreement among raft nodes before linearized reading' (duration: 389.60454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:33.878041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:33.488186Z","time spent":"389.849826ms","remote":"127.0.0.1:37150","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-08T16:37:33.878416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.351589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T16:37:33.878793Z","caller":"traceutil/trace.go:171","msg":"trace[1740583827] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:688; }","duration":"391.760458ms","start":"2026-02-08T16:37:33.487023Z","end":"2026-02-08T16:37:33.878783Z","steps":["trace[1740583827] 'agreement among raft nodes before linearized reading' (duration: 391.235387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:33.878927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:33.487002Z","time spent":"391.912572ms","remote":"127.0.0.1:37150","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-08T16:37:33.880132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.221005ms","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-08T16:37:33.880223Z","caller":"traceutil/trace.go:171","msg":"trace[742697021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:688; }","duration":"639.346878ms","start":"2026-02-08T16:37:33.240866Z","end":"2026-02-08T16:37:33.880213Z","steps":["trace[742697021] 'agreement among raft nodes before linearized reading' (duration: 639.213075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:33.8803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:33.240849Z","time spent":"639.43562ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T16:37:33.880797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.855954ms","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-08T16:37:33.880884Z","caller":"traceutil/trace.go:171","msg":"trace[1509574586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:688; }","duration":"739.973297ms","start":"2026-02-08T16:37:33.140901Z","end":"2026-02-08T16:37:33.880875Z","steps":["trace[1509574586] 'agreement among raft nodes before linearized reading' (duration: 739.568807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:33.880983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:33.14089Z","time spent":"740.083939ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T16:37:40.35738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.252874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T16:37:40.357443Z","caller":"traceutil/trace.go:171","msg":"trace[1485671147] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:711; }","duration":"362.350066ms","start":"2026-02-08T16:37:39.995083Z","end":"2026-02-08T16:37:40.357433Z","steps":["trace[1485671147] 'range keys from in-memory index tree' (duration: 362.09724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:40.357466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:39.995052Z","time spent":"362.409308ms","remote":"127.0.0.1:37150","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-08T16:37:40.357578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.393547ms","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-08T16:37:40.357591Z","caller":"traceutil/trace.go:171","msg":"trace[957802347] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:711; }","duration":"358.434658ms","start":"2026-02-08T16:37:39.999152Z","end":"2026-02-08T16:37:40.357586Z","steps":["trace[957802347] 'range keys from in-memory index tree' (duration: 358.333935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:40.357602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:39.999137Z","time spent":"358.461608ms","remote":"127.0.0.1:37150","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-08T16:37:40.357707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.275431ms","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-08T16:37:40.357733Z","caller":"traceutil/trace.go:171","msg":"trace[172694247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:711; }","duration":"337.313512ms","start":"2026-02-08T16:37:40.020404Z","end":"2026-02-08T16:37:40.357718Z","steps":["trace[172694247] 'range keys from in-memory index tree' (duration: 337.22623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:40.357745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:40.020398Z","time spent":"337.343642ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T16:37:40.357827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.754314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:37:40.35784Z","caller":"traceutil/trace.go:171","msg":"trace[743231198] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:711; }","duration":"334.792385ms","start":"2026-02-08T16:37:40.023043Z","end":"2026-02-08T16:37:40.357836Z","steps":["trace[743231198] 'count revisions from in-memory index tree' (duration: 334.725734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:40.357854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:40.023035Z","time spent":"334.814526ms","remote":"127.0.0.1:37104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-02-08T16:37:40.357937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.719746ms","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-08T16:37:40.357948Z","caller":"traceutil/trace.go:171","msg":"trace[2101823432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:711; }","duration":"327.743796ms","start":"2026-02-08T16:37:40.030201Z","end":"2026-02-08T16:37:40.357945Z","steps":["trace[2101823432] 'range keys from in-memory index tree' (duration: 327.675444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:40.35796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:40.030195Z","time spent":"327.762056ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T16:37:40.358026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.291774ms","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-08T16:37:40.358039Z","caller":"traceutil/trace.go:171","msg":"trace[1908163544] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:711; }","duration":"326.315264ms","start":"2026-02-08T16:37:40.031719Z","end":"2026-02-08T16:37:40.358034Z","steps":["trace[1908163544] 'range keys from in-memory index tree' (duration: 326.206502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:40.358051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:40.031713Z","time spent":"326.334324ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-08T16:37:40.358134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:40.041876Z","time spent":"316.255287ms","remote":"127.0.0.1:39318","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-08T16:37:40.742967Z","caller":"traceutil/trace.go:171","msg":"trace[1480808542] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"378.715645ms","start":"2026-02-08T16:37:40.364234Z","end":"2026-02-08T16:37:40.74295Z","steps":["trace[1480808542] 'process raft request' (duration: 378.579872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:40.743133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:40.364221Z","time spent":"378.830557ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:37:41.341632Z","caller":"traceutil/trace.go:171","msg":"trace[1759470509] linearizableReadLoop","detail":"{readStateIndex:739; appliedIndex:734; }","duration":"853.202137ms","start":"2026-02-08T16:37:40.488413Z","end":"2026-02-08T16:37:41.341615Z","steps":["trace[1759470509] 'read index received' (duration: 254.459374ms)","trace[1759470509] 'applied index is now lower than readState.Index' (duration: 598.742183ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:37:41.341779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.346181ms","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-08T16:37:41.341806Z","caller":"traceutil/trace.go:171","msg":"trace[1798552740] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:717; }","duration":"853.417892ms","start":"2026-02-08T16:37:40.488382Z","end":"2026-02-08T16:37:41.3418Z","steps":["trace[1798552740] 'agreement among raft nodes before linearized reading' (duration: 853.299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:41.34183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:40.488367Z","time spent":"853.457074ms","remote":"127.0.0.1:37068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":40,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-02-08T16:37:41.342084Z","caller":"traceutil/trace.go:171","msg":"trace[1523561752] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"977.668473ms","start":"2026-02-08T16:37:40.364408Z","end":"2026-02-08T16:37:41.342076Z","steps":["trace[1523561752] 'process raft request' (duration: 976.968397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:41.342158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:40.364402Z","time spent":"977.705723ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:37:41.342248Z","caller":"traceutil/trace.go:171","msg":"trace[1847737540] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"977.805665ms","start":"2026-02-08T16:37:40.364437Z","end":"2026-02-08T16:37:41.342243Z","steps":["trace[1847737540] 'process raft request' (duration: 977.058889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:41.342285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:40.364434Z","time spent":"977.834916ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:37:41.342367Z","caller":"traceutil/trace.go:171","msg":"trace[2119283955] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"977.903908ms","start":"2026-02-08T16:37:40.364457Z","end":"2026-02-08T16:37:41.342361Z","steps":["trace[2119283955] 'process raft request' (duration: 977.072759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:41.3424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:40.364455Z","time spent":"977.930238ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:37:41.342489Z","caller":"traceutil/trace.go:171","msg":"trace[472881475] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"976.68856ms","start":"2026-02-08T16:37:40.365795Z","end":"2026-02-08T16:37:41.342484Z","steps":["trace[472881475] 'process raft request' (duration: 975.765529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:41.342529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:40.36579Z","time spent":"976.72026ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:37:41.342607Z","caller":"traceutil/trace.go:171","msg":"trace[362776968] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"790.643047ms","start":"2026-02-08T16:37:40.551959Z","end":"2026-02-08T16:37:41.342602Z","steps":["trace[362776968] 'process raft request' (duration: 789.628135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:41.342644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:40.55194Z","time spent":"790.684348ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:37:52.730321Z","caller":"traceutil/trace.go:171","msg":"trace[536281874] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:770; }","duration":"212.370216ms","start":"2026-02-08T16:37:52.517917Z","end":"2026-02-08T16:37:52.730287Z","steps":["trace[536281874] 'read index received' (duration: 139.33935ms)","trace[536281874] 'applied index is now lower than readState.Index' (duration: 73.029816ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:37:52.730422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.490408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:37:52.730443Z","caller":"traceutil/trace.go:171","msg":"trace[1508166202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:745; }","duration":"212.530759ms","start":"2026-02-08T16:37:52.517906Z","end":"2026-02-08T16:37:52.730436Z","steps":["trace[1508166202] 'agreement among raft nodes before linearized reading' (duration: 212.469168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:54.532738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.061826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T16:37:54.532852Z","caller":"traceutil/trace.go:171","msg":"trace[2016517068] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:773; }","duration":"1.131660002s","start":"2026-02-08T16:37:53.40118Z","end":"2026-02-08T16:37:54.53284Z","steps":["trace[2016517068] 'read index received' (duration: 12.92241ms)","trace[2016517068] 'applied index is now lower than readState.Index' (duration: 1.118736512s)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:37:54.533363Z","caller":"traceutil/trace.go:171","msg":"trace[124699238] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"1.14529871s","start":"2026-02-08T16:37:53.388055Z","end":"2026-02-08T16:37:54.533354Z","steps":["trace[124699238] 'process raft request' (duration: 752.56813ms)","trace[124699238] 'compare' (duration: 391.651515ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:37:54.533461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:53.388038Z","time spent":"1.145372232s","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:37:54.533723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.132539952s","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-08T16:37:54.533764Z","caller":"traceutil/trace.go:171","msg":"trace[612826757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:748; }","duration":"1.132594674s","start":"2026-02-08T16:37:53.401164Z","end":"2026-02-08T16:37:54.533759Z","steps":["trace[612826757] 'agreement among raft nodes before linearized reading' (duration: 1.132461691s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:54.533806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:53.401157Z","time spent":"1.132643535s","remote":"127.0.0.1:37150","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-08T16:37:54.534039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.106769272s","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-08T16:37:54.534083Z","caller":"traceutil/trace.go:171","msg":"trace[1431879284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:748; }","duration":"1.106863124s","start":"2026-02-08T16:37:53.427214Z","end":"2026-02-08T16:37:54.534077Z","steps":["trace[1431879284] 'agreement among raft nodes before linearized reading' (duration: 1.106777142s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:54.534172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:53.427194Z","time spent":"1.106971876s","remote":"127.0.0.1:37150","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-08T16:37:54.534559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.631225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-08T16:37:54.534677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.968767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T16:37:54.534752Z","caller":"traceutil/trace.go:171","msg":"trace[1823786233] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:748; }","duration":"993.057909ms","start":"2026-02-08T16:37:53.541688Z","end":"2026-02-08T16:37:54.534746Z","steps":["trace[1823786233] 'agreement among raft nodes before linearized reading' (duration: 992.917665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:54.534809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:53.541658Z","time spent":"993.12625ms","remote":"127.0.0.1:37134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-02-08T16:37:54.534629Z","caller":"traceutil/trace.go:171","msg":"trace[327653320] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:748; }","duration":"233.745297ms","start":"2026-02-08T16:37:54.300868Z","end":"2026-02-08T16:37:54.534614Z","steps":["trace[327653320] 'agreement among raft nodes before linearized reading' (duration: 233.633464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:54.534991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.268074ms","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-08T16:37:54.535048Z","caller":"traceutil/trace.go:171","msg":"trace[280652185] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:748; }","duration":"804.362336ms","start":"2026-02-08T16:37:53.730679Z","end":"2026-02-08T16:37:54.535041Z","steps":["trace[280652185] 'agreement among raft nodes before linearized reading' (duration: 804.284384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:54.535084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:53.730659Z","time spent":"804.418658ms","remote":"127.0.0.1:37172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-08T16:37:55.253488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.176598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T16:37:55.253588Z","caller":"traceutil/trace.go:171","msg":"trace[1964530860] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"714.066343ms","start":"2026-02-08T16:37:54.539502Z","end":"2026-02-08T16:37:55.253568Z","steps":["trace[1964530860] 'process raft request' (duration: 526.742081ms)","trace[1964530860] 'compare' (duration: 186.941263ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:37:55.253643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:54.539491Z","time spent":"714.126104ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:37:55.422229Z","caller":"traceutil/trace.go:171","msg":"trace[1510846694] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:774; }","duration":"602.340715ms","start":"2026-02-08T16:37:54.819865Z","end":"2026-02-08T16:37:55.422205Z","steps":["trace[1510846694] 'read index received' (duration: 246.563417ms)","trace[1510846694] 'applied index is now lower than readState.Index' (duration: 355.776208ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:37:55.422517Z","caller":"traceutil/trace.go:171","msg":"trace[218085345] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"881.446184ms","start":"2026-02-08T16:37:54.541058Z","end":"2026-02-08T16:37:55.422504Z","steps":["trace[218085345] 'process raft request' (duration: 830.054546ms)","trace[218085345] 'compare' (duration: 50.975339ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:37:55.422617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:54.54105Z","time spent":"881.499765ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:37:55.422859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.990509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T16:37:55.422893Z","caller":"traceutil/trace.go:171","msg":"trace[687021566] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:750; }","duration":"603.04731ms","start":"2026-02-08T16:37:54.819834Z","end":"2026-02-08T16:37:55.422881Z","steps":["trace[687021566] 'agreement among raft nodes before linearized reading' (duration: 602.939917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:37:55.422921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:37:54.819819Z","time spent":"603.096521ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-08T16:38:08.748843Z","caller":"traceutil/trace.go:171","msg":"trace[1856350484] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"157.308854ms","start":"2026-02-08T16:38:08.591511Z","end":"2026-02-08T16:38:08.748819Z","steps":["trace[1856350484] 'process raft request' (duration: 157.175231ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:13.711019Z","caller":"traceutil/trace.go:171","msg":"trace[3105601] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"120.173342ms","start":"2026-02-08T16:38:13.590831Z","end":"2026-02-08T16:38:13.711005Z","steps":["trace[3105601] 'process raft request' (duration: 119.738193ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:13.825127Z","caller":"traceutil/trace.go:171","msg":"trace[2094900244] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"111.093939ms","start":"2026-02-08T16:38:13.714015Z","end":"2026-02-08T16:38:13.825109Z","steps":["trace[2094900244] 'process raft request' (duration: 77.540597ms)","trace[2094900244] 'compare' (duration: 33.46413ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:38:15.567377Z","caller":"traceutil/trace.go:171","msg":"trace[1939343835] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"228.011287ms","start":"2026-02-08T16:38:15.339353Z","end":"2026-02-08T16:38:15.567364Z","steps":["trace[1939343835] 'process raft request' (duration: 227.831973ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:19.955311Z","caller":"traceutil/trace.go:171","msg":"trace[391907689] linearizableReadLoop","detail":"{readStateIndex:913; appliedIndex:912; }","duration":"113.066714ms","start":"2026-02-08T16:38:19.842229Z","end":"2026-02-08T16:38:19.955296Z","steps":["trace[391907689] 'read index received' (duration: 112.935421ms)","trace[391907689] 'applied index is now lower than readState.Index' (duration: 130.643µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:19.955506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.260098ms","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-08T16:38:19.955533Z","caller":"traceutil/trace.go:171","msg":"trace[543965497] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:880; }","duration":"113.32453ms","start":"2026-02-08T16:38:19.842201Z","end":"2026-02-08T16:38:19.955526Z","steps":["trace[543965497] 'agreement among raft nodes before linearized reading' (duration: 113.206867ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:19.955724Z","caller":"traceutil/trace.go:171","msg":"trace[4072325] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"187.054371ms","start":"2026-02-08T16:38:19.768663Z","end":"2026-02-08T16:38:19.955718Z","steps":["trace[4072325] 'process raft request' (duration: 186.54375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:20.657957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.86254ms","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-08T16:38:20.658004Z","caller":"traceutil/trace.go:171","msg":"trace[846677508] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:881; }","duration":"437.945912ms","start":"2026-02-08T16:38:20.220048Z","end":"2026-02-08T16:38:20.657994Z","steps":["trace[846677508] 'count revisions from in-memory index tree' (duration: 437.813079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:20.658022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:20.220032Z","time spent":"437.984823ms","remote":"127.0.0.1:37152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-02-08T16:38:20.658322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.597063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T16:38:20.658342Z","caller":"traceutil/trace.go:171","msg":"trace[1418729526] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:881; }","duration":"183.653985ms","start":"2026-02-08T16:38:20.474684Z","end":"2026-02-08T16:38:20.658338Z","steps":["trace[1418729526] 'count revisions from in-memory index tree' (duration: 183.531752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:20.658413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.864486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:38:20.658434Z","caller":"traceutil/trace.go:171","msg":"trace[2107532961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:881; }","duration":"140.929268ms","start":"2026-02-08T16:38:20.517495Z","end":"2026-02-08T16:38:20.658425Z","steps":["trace[2107532961] 'range keys from in-memory index tree' (duration: 140.820705ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:21.386557Z","caller":"traceutil/trace.go:171","msg":"trace[348758765] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"534.446323ms","start":"2026-02-08T16:38:20.852068Z","end":"2026-02-08T16:38:21.386515Z","steps":["trace[348758765] 'process raft request' (duration: 534.32028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:21.386734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:20.852056Z","time spent":"534.590557ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:38:22.472802Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132614672045474,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T16:38:22.986187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.467814304s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:38:22.986246Z","caller":"traceutil/trace.go:171","msg":"trace[1429448768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:882; }","duration":"1.467911866s","start":"2026-02-08T16:38:21.518317Z","end":"2026-02-08T16:38:22.986229Z","steps":["trace[1429448768] 'range keys from in-memory index tree' (duration: 1.467719241s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:22.986324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:21.518293Z","time spent":"1.467973448s","remote":"127.0.0.1:36858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T16:38:22.986704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.350545236s","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-08T16:38:22.986726Z","caller":"traceutil/trace.go:171","msg":"trace[116609297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:882; }","duration":"1.350599988s","start":"2026-02-08T16:38:21.636118Z","end":"2026-02-08T16:38:22.986718Z","steps":["trace[116609297] 'range keys from in-memory index tree' (duration: 1.350331951s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:22.986742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:21.6361Z","time spent":"1.350637928s","remote":"127.0.0.1:37150","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-08T16:38:22.986869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.333568576s","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-08T16:38:22.986886Z","caller":"traceutil/trace.go:171","msg":"trace[132860756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:882; }","duration":"1.333609887s","start":"2026-02-08T16:38:21.653271Z","end":"2026-02-08T16:38:22.986881Z","steps":["trace[132860756] 'range keys from in-memory index tree' (duration: 1.333496755s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:22.986904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:21.653259Z","time spent":"1.333639058s","remote":"127.0.0.1:37150","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-08T16:38:22.987426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.762619ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T16:38:22.987514Z","caller":"traceutil/trace.go:171","msg":"trace[1392434075] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"1.08332141s","start":"2026-02-08T16:38:21.904176Z","end":"2026-02-08T16:38:22.987497Z","steps":["trace[1392434075] 'process raft request' (duration: 894.411448ms)","trace[1392434075] 'compare' (duration: 188.245127ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:22.987573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:21.90416Z","time spent":"1.083387531s","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:38:24.124342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.378031ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-08T16:38:24.124503Z","caller":"traceutil/trace.go:171","msg":"trace[1908702555] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:915; }","duration":"2.152055882s","start":"2026-02-08T16:38:21.972433Z","end":"2026-02-08T16:38:24.124489Z","steps":["trace[1908702555] 'read index received' (duration: 826.161448ms)","trace[1908702555] 'applied index is now lower than readState.Index' (duration: 1.325893514s)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:24.12485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.153033044s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T16:38:24.124871Z","caller":"traceutil/trace.go:171","msg":"trace[164119687] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:883; }","duration":"2.153074364s","start":"2026-02-08T16:38:21.97179Z","end":"2026-02-08T16:38:24.124864Z","steps":["trace[164119687] 'agreement among raft nodes before linearized reading' (duration: 2.152982792s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:24.124893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:21.971775Z","time spent":"2.153110816s","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-08T16:38:24.125289Z","caller":"traceutil/trace.go:171","msg":"trace[1510739563] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"1.132583293s","start":"2026-02-08T16:38:22.992697Z","end":"2026-02-08T16:38:24.12528Z","steps":["trace[1510739563] 'process raft request' (duration: 1.131720464s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:24.125351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:22.992686Z","time spent":"1.132624364s","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:38:24.23504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.669877ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T16:38:24.236246Z","caller":"traceutil/trace.go:171","msg":"trace[1100597519] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"1.240507151s","start":"2026-02-08T16:38:22.995717Z","end":"2026-02-08T16:38:24.236224Z","steps":["trace[1100597519] 'process raft request' (duration: 1.129554535s)","trace[1100597519] 'compare' (duration: 109.274348ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:24.236376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:22.995709Z","time spent":"1.240601233s","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:38:24.236669Z","caller":"traceutil/trace.go:171","msg":"trace[306776012] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"107.388306ms","start":"2026-02-08T16:38:24.129224Z","end":"2026-02-08T16:38:24.236612Z","steps":["trace[306776012] 'process raft request' (duration: 106.473326ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:24.236795Z","caller":"traceutil/trace.go:171","msg":"trace[432681751] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:917; }","duration":"112.260805ms","start":"2026-02-08T16:38:24.124521Z","end":"2026-02-08T16:38:24.236782Z","steps":["trace[432681751] 'read index received' (duration: 478.63µs)","trace[432681751] 'applied index is now lower than readState.Index' (duration: 111.780075ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:24.238404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.242791356s","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-08T16:38:24.238456Z","caller":"traceutil/trace.go:171","msg":"trace[99243989] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:886; }","duration":"2.242881698s","start":"2026-02-08T16:38:21.995562Z","end":"2026-02-08T16:38:24.238443Z","steps":["trace[99243989] 'agreement among raft nodes before linearized reading' (duration: 2.241301902s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:24.238488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:21.995548Z","time spent":"2.242933559s","remote":"127.0.0.1:37150","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-08T16:38:24.238945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.032884ms","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-08T16:38:24.238991Z","caller":"traceutil/trace.go:171","msg":"trace[1837755655] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:886; }","duration":"846.115615ms","start":"2026-02-08T16:38:23.392864Z","end":"2026-02-08T16:38:24.23898Z","steps":["trace[1837755655] 'agreement among raft nodes before linearized reading' (duration: 845.992072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:24.239978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:23.392839Z","time spent":"847.082667ms","remote":"127.0.0.1:37150","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-08T16:38:24.240103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.249098534s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:38:24.24014Z","caller":"traceutil/trace.go:171","msg":"trace[893110943] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:886; }","duration":"1.249157555s","start":"2026-02-08T16:38:22.990976Z","end":"2026-02-08T16:38:24.240134Z","steps":["trace[893110943] 'agreement among raft nodes before linearized reading' (duration: 1.249105654s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:24.2402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:22.990964Z","time spent":"1.249230597s","remote":"127.0.0.1:36872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T16:38:24.240485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.578101504s","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-08T16:38:24.240568Z","caller":"traceutil/trace.go:171","msg":"trace[99352717] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:886; }","duration":"1.578182386s","start":"2026-02-08T16:38:22.662325Z","end":"2026-02-08T16:38:24.240507Z","steps":["trace[99352717] 'agreement among raft nodes before linearized reading' (duration: 1.578129835s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:24.240599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:22.662307Z","time spent":"1.578282289s","remote":"127.0.0.1:37276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-02-08T16:38:24.477601Z","caller":"traceutil/trace.go:171","msg":"trace[793013749] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"225.026451ms","start":"2026-02-08T16:38:24.25255Z","end":"2026-02-08T16:38:24.477577Z","steps":["trace[793013749] 'process raft request' (duration: 166.357447ms)","trace[793013749] 'compare' (duration: 58.575262ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:38:24.490173Z","caller":"traceutil/trace.go:171","msg":"trace[7887325] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:920; }","duration":"237.405397ms","start":"2026-02-08T16:38:24.252747Z","end":"2026-02-08T16:38:24.490152Z","steps":["trace[7887325] 'read index received' (duration: 166.170322ms)","trace[7887325] 'applied index is now lower than readState.Index' (duration: 71.234015ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:24.490343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.572122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:38:24.490387Z","caller":"traceutil/trace.go:171","msg":"trace[1405750482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:888; }","duration":"237.645044ms","start":"2026-02-08T16:38:24.252729Z","end":"2026-02-08T16:38:24.490374Z","steps":["trace[1405750482] 'agreement among raft nodes before linearized reading' (duration: 237.506501ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:24.490739Z","caller":"traceutil/trace.go:171","msg":"trace[1945241293] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"238.130445ms","start":"2026-02-08T16:38:24.25259Z","end":"2026-02-08T16:38:24.490721Z","steps":["trace[1945241293] 'process raft request' (duration: 237.457299ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:33.446483Z","caller":"traceutil/trace.go:171","msg":"trace[279735767] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"379.887831ms","start":"2026-02-08T16:38:33.066579Z","end":"2026-02-08T16:38:33.446467Z","steps":["trace[279735767] 'process raft request' (duration: 379.781588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:33.446585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:33.066557Z","time spent":"379.976123ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:38:36.443816Z","caller":"traceutil/trace.go:171","msg":"trace[1423630588] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"146.606366ms","start":"2026-02-08T16:38:36.297197Z","end":"2026-02-08T16:38:36.443803Z","steps":["trace[1423630588] 'read index received' (duration: 146.517874ms)","trace[1423630588] 'applied index is now lower than readState.Index' (duration: 87.862µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:38:36.4439Z","caller":"traceutil/trace.go:171","msg":"trace[1009814419] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"147.741141ms","start":"2026-02-08T16:38:36.296152Z","end":"2026-02-08T16:38:36.443894Z","steps":["trace[1009814419] 'process raft request' (duration: 147.547066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:36.444078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.06669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-08T16:38:36.444151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.942023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T16:38:36.444151Z","caller":"traceutil/trace.go:171","msg":"trace[1841559729] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:917; }","duration":"120.224264ms","start":"2026-02-08T16:38:36.32391Z","end":"2026-02-08T16:38:36.444134Z","steps":["trace[1841559729] 'agreement among raft nodes before linearized reading' (duration: 120.099791ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:36.444172Z","caller":"traceutil/trace.go:171","msg":"trace[333064891] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:917; }","duration":"146.987604ms","start":"2026-02-08T16:38:36.297179Z","end":"2026-02-08T16:38:36.444167Z","steps":["trace[333064891] 'agreement among raft nodes before linearized reading' (duration: 146.903932ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:37.009816Z","caller":"traceutil/trace.go:171","msg":"trace[1084600490] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"562.862419ms","start":"2026-02-08T16:38:36.446937Z","end":"2026-02-08T16:38:37.0098Z","steps":["trace[1084600490] 'process raft request' (duration: 562.777417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:37.009908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:36.446925Z","time spent":"562.936792ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T16:38:37.025447Z","caller":"traceutil/trace.go:171","msg":"trace[486809431] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:953; }","duration":"511.164641ms","start":"2026-02-08T16:38:36.514255Z","end":"2026-02-08T16:38:37.025419Z","steps":["trace[486809431] 'read index received' (duration: 495.531101ms)","trace[486809431] 'applied index is now lower than readState.Index' (duration: 15.63297ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:38:37.025612Z","caller":"traceutil/trace.go:171","msg":"trace[1947473815] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"576.199488ms","start":"2026-02-08T16:38:36.449407Z","end":"2026-02-08T16:38:37.025606Z","steps":["trace[1947473815] 'process raft request' (duration: 575.947162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:37.025669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:36.449392Z","time spent":"576.234698ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T16:38:37.025764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.506698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:38:37.025779Z","caller":"traceutil/trace.go:171","msg":"trace[1515199792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:919; }","duration":"511.54726ms","start":"2026-02-08T16:38:36.514227Z","end":"2026-02-08T16:38:37.025774Z","steps":["trace[1515199792] 'agreement among raft nodes before linearized reading' (duration: 511.518309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:37.025793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:36.514214Z","time spent":"511.57646ms","remote":"127.0.0.1:36858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T16:38:37.025898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.722022ms","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-08T16:38:37.025934Z","caller":"traceutil/trace.go:171","msg":"trace[1342970746] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:919; }","duration":"473.786685ms","start":"2026-02-08T16:38:36.552119Z","end":"2026-02-08T16:38:37.025906Z","steps":["trace[1342970746] 'agreement among raft nodes before linearized reading' (duration: 473.732583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:37.025945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:36.552099Z","time spent":"473.843525ms","remote":"127.0.0.1:37150","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-08T16:38:37.026156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.225411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T16:38:37.026172Z","caller":"traceutil/trace.go:171","msg":"trace[2119460884] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:919; }","duration":"427.262571ms","start":"2026-02-08T16:38:36.598905Z","end":"2026-02-08T16:38:37.026168Z","steps":["trace[2119460884] 'agreement among raft nodes before linearized reading' (duration: 427.236021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:37.026184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:36.598894Z","time spent":"427.286203ms","remote":"127.0.0.1:37222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-08T16:38:37.026273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.679423ms","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-08T16:38:37.026285Z","caller":"traceutil/trace.go:171","msg":"trace[844489268] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:919; }","duration":"461.719243ms","start":"2026-02-08T16:38:36.564562Z","end":"2026-02-08T16:38:37.026281Z","steps":["trace[844489268] 'agreement among raft nodes before linearized reading' (duration: 461.679532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:37.026296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:36.564543Z","time spent":"461.750694ms","remote":"127.0.0.1:37150","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-08T16:38:37.157246Z","caller":"traceutil/trace.go:171","msg":"trace[272935867] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:955; }","duration":"124.827286ms","start":"2026-02-08T16:38:37.032405Z","end":"2026-02-08T16:38:37.157232Z","steps":["trace[272935867] 'read index received' (duration: 25.217115ms)","trace[272935867] 'applied index is now lower than readState.Index' (duration: 99.609631ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:37.157333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.910628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:38:37.157348Z","caller":"traceutil/trace.go:171","msg":"trace[2013388941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:921; }","duration":"124.956989ms","start":"2026-02-08T16:38:37.032386Z","end":"2026-02-08T16:38:37.157343Z","steps":["trace[2013388941] 'agreement among raft nodes before linearized reading' (duration: 124.900348ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:37.157428Z","caller":"traceutil/trace.go:171","msg":"trace[2000128238] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"125.155383ms","start":"2026-02-08T16:38:37.032257Z","end":"2026-02-08T16:38:37.157413Z","steps":["trace[2000128238] 'process raft request' (duration: 124.894507ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:37.157513Z","caller":"traceutil/trace.go:171","msg":"trace[1054022936] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"125.154043ms","start":"2026-02-08T16:38:37.032353Z","end":"2026-02-08T16:38:37.157507Z","steps":["trace[1054022936] 'process raft request' (duration: 124.860376ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:38.589352Z","caller":"traceutil/trace.go:171","msg":"trace[569852316] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"138.632126ms","start":"2026-02-08T16:38:38.450665Z","end":"2026-02-08T16:38:38.589297Z","steps":["trace[569852316] 'process raft request' (duration: 137.558922ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:38.939363Z","caller":"traceutil/trace.go:171","msg":"trace[2007280718] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"123.502036ms","start":"2026-02-08T16:38:38.815841Z","end":"2026-02-08T16:38:38.939343Z","steps":["trace[2007280718] 'process raft request' (duration: 123.379454ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:40.997042Z","caller":"traceutil/trace.go:171","msg":"trace[6975121] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"226.793081ms","start":"2026-02-08T16:38:40.770226Z","end":"2026-02-08T16:38:40.99702Z","steps":["trace[6975121] 'process raft request' (duration: 226.644957ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:43.726285Z","caller":"traceutil/trace.go:171","msg":"trace[738386404] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"117.751278ms","start":"2026-02-08T16:38:43.608513Z","end":"2026-02-08T16:38:43.726264Z","steps":["trace[738386404] 'process raft request' (duration: 117.340419ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:38:43.928192Z","caller":"traceutil/trace.go:171","msg":"trace[904072215] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"192.741878ms","start":"2026-02-08T16:38:43.735431Z","end":"2026-02-08T16:38:43.928173Z","steps":["trace[904072215] 'process raft request' (duration: 112.06088ms)","trace[904072215] 'compare' (duration: 80.589246ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:38:47.550705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.151913ms","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-08T16:38:47.550774Z","caller":"traceutil/trace.go:171","msg":"trace[987773483] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:969; }","duration":"354.249526ms","start":"2026-02-08T16:38:47.196505Z","end":"2026-02-08T16:38:47.550754Z","steps":["trace[987773483] 'range keys from in-memory index tree' (duration: 354.015511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:47.550809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:47.196491Z","time spent":"354.309627ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T16:38:47.55101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.815379ms","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-08T16:38:47.551039Z","caller":"traceutil/trace.go:171","msg":"trace[443769590] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:969; }","duration":"350.85503ms","start":"2026-02-08T16:38:47.200175Z","end":"2026-02-08T16:38:47.55103Z","steps":["trace[443769590] 'range keys from in-memory index tree' (duration: 350.748487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:38:47.551066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T16:38:47.20017Z","time spent":"350.890031ms","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-08T16:41:48.120034Z","caller":"traceutil/trace.go:171","msg":"trace[1349851565] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"137.598386ms","start":"2026-02-08T16:41:47.982418Z","end":"2026-02-08T16:41:48.120017Z","steps":["trace[1349851565] 'process raft request' (duration: 137.373371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:41:48.316417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.043289ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T16:41:48.316514Z","caller":"traceutil/trace.go:171","msg":"trace[299511948] linearizableReadLoop","detail":"{readStateIndex:1924; appliedIndex:1923; }","duration":"116.352503ms","start":"2026-02-08T16:41:48.200146Z","end":"2026-02-08T16:41:48.316498Z","steps":["trace[299511948] 'read index received' (duration: 20.381µs)","trace[299511948] 'applied index is now lower than readState.Index' (duration: 116.331122ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:41:48.316708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.547097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T16:41:48.316751Z","caller":"traceutil/trace.go:171","msg":"trace[327006072] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1848; }","duration":"116.625569ms","start":"2026-02-08T16:41:48.200113Z","end":"2026-02-08T16:41:48.316738Z","steps":["trace[327006072] 'agreement among raft nodes before linearized reading' (duration: 116.470406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:41:48.316773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.897583ms","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-08T16:41:48.316804Z","caller":"traceutil/trace.go:171","msg":"trace[737323287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1848; }","duration":"107.950644ms","start":"2026-02-08T16:41:48.208845Z","end":"2026-02-08T16:41:48.316795Z","steps":["trace[737323287] 'agreement among raft nodes before linearized reading' (duration: 107.861842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:41:48.316951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.259374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2722"} {"level":"info","ts":"2026-02-08T16:41:48.316976Z","caller":"traceutil/trace.go:171","msg":"trace[1147133231] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1848; }","duration":"100.302004ms","start":"2026-02-08T16:41:48.216666Z","end":"2026-02-08T16:41:48.316968Z","steps":["trace[1147133231] 'agreement among raft nodes before linearized reading' (duration: 100.213462ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:41:48.317042Z","caller":"traceutil/trace.go:171","msg":"trace[2024300430] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"192.829836ms","start":"2026-02-08T16:41:48.124198Z","end":"2026-02-08T16:41:48.317028Z","steps":["trace[2024300430] 'process raft request' (duration: 61.090642ms)","trace[2024300430] 'compare' (duration: 130.945927ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:41:50.518204Z","caller":"traceutil/trace.go:171","msg":"trace[384107995] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"150.083454ms","start":"2026-02-08T16:41:50.368103Z","end":"2026-02-08T16:41:50.518187Z","steps":["trace[384107995] 'process raft request' (duration: 149.978542ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:41:50.537412Z","caller":"traceutil/trace.go:171","msg":"trace[444439994] linearizableReadLoop","detail":"{readStateIndex:1936; appliedIndex:1934; }","duration":"167.917971ms","start":"2026-02-08T16:41:50.369479Z","end":"2026-02-08T16:41:50.537397Z","steps":["trace[444439994] 'read index received' (duration: 148.684533ms)","trace[444439994] 'applied index is now lower than readState.Index' (duration: 19.232128ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:41:50.537488Z","caller":"traceutil/trace.go:171","msg":"trace[598436843] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"169.320013ms","start":"2026-02-08T16:41:50.368161Z","end":"2026-02-08T16:41:50.537481Z","steps":["trace[598436843] 'process raft request' (duration: 169.071448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:41:50.537662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.172387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-02-08T16:41:50.537681Z","caller":"traceutil/trace.go:171","msg":"trace[380331933] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1860; }","duration":"168.221908ms","start":"2026-02-08T16:41:50.369453Z","end":"2026-02-08T16:41:50.537675Z","steps":["trace[380331933] 'agreement among raft nodes before linearized reading' (duration: 168.143907ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T16:41:50.837265Z","caller":"traceutil/trace.go:171","msg":"trace[1253509755] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1936; }","duration":"189.038642ms","start":"2026-02-08T16:41:50.648209Z","end":"2026-02-08T16:41:50.837248Z","steps":["trace[1253509755] 'read index received' (duration: 157.641063ms)","trace[1253509755] 'applied index is now lower than readState.Index' (duration: 31.397029ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T16:41:50.837336Z","caller":"traceutil/trace.go:171","msg":"trace[1899672653] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"292.131769ms","start":"2026-02-08T16:41:50.545196Z","end":"2026-02-08T16:41:50.837328Z","steps":["trace[1899672653] 'process raft request' (duration: 260.672288ms)","trace[1899672653] 'compare' (duration: 31.085902ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T16:41:50.837486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.264207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-08T16:41:50.837501Z","caller":"traceutil/trace.go:171","msg":"trace[1481607332] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1861; }","duration":"189.291707ms","start":"2026-02-08T16:41:50.648205Z","end":"2026-02-08T16:41:50.837497Z","steps":["trace[1481607332] 'agreement among raft nodes before linearized reading' (duration: 189.203355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:41:50.837598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.36015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-08T16:41:50.837609Z","caller":"traceutil/trace.go:171","msg":"trace[1910122345] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1861; }","duration":"146.390961ms","start":"2026-02-08T16:41:50.691215Z","end":"2026-02-08T16:41:50.837606Z","steps":["trace[1910122345] 'agreement among raft nodes before linearized reading' (duration: 146.35514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:41:54.496354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.328241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-02-08T16:41:54.496405Z","caller":"traceutil/trace.go:171","msg":"trace[1465513630] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1880; }","duration":"274.413033ms","start":"2026-02-08T16:41:54.221979Z","end":"2026-02-08T16:41:54.496393Z","steps":["trace[1465513630] 'range keys from in-memory index tree' (duration: 274.192039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:41:54.496547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.458589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-08T16:41:54.496561Z","caller":"traceutil/trace.go:171","msg":"trace[988172767] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1880; }","duration":"201.473659ms","start":"2026-02-08T16:41:54.295083Z","end":"2026-02-08T16:41:54.496557Z","steps":["trace[988172767] 'range keys from in-memory index tree' (duration: 201.398997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:41:54.496569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.10038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T16:41:54.496651Z","caller":"traceutil/trace.go:171","msg":"trace[270972511] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1880; }","duration":"223.214913ms","start":"2026-02-08T16:41:54.27341Z","end":"2026-02-08T16:41:54.496624Z","steps":["trace[270972511] 'count revisions from in-memory index tree' (duration: 223.045189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T16:41:54.496775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.239247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T16:41:54.496793Z","caller":"traceutil/trace.go:171","msg":"trace[1572032805] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1880; }","duration":"220.271748ms","start":"2026-02-08T16:41:54.276516Z","end":"2026-02-08T16:41:54.496788Z","steps":["trace[1572032805] 'count revisions from in-memory index tree' (duration: 220.216256ms)"],"step_count":1}