{"level":"warn","ts":"2026-02-05T05:28:39.396886Z","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-05T05:28:39.396956Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.149: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://199.204.45.149:2380","--initial-cluster=instance=https://199.204.45.149:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.149:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.149: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-05T05:28:39.397035Z","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-05T05:28:39.397048Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.149:2380"]} {"level":"info","ts":"2026-02-05T05:28:39.397064Z","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-05T05:28:39.39767Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"]} {"level":"info","ts":"2026-02-05T05:28:39.397765Z","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://199.204.45.149:2380"],"listen-peer-urls":["https://199.204.45.149:2380"],"advertise-client-urls":["https://199.204.45.149:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.149: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-05T05:28:39.557067Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"159.045719ms"} {"level":"info","ts":"2026-02-05T05:28:39.799821Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b1b675f124322401","cluster-id":"913dfbf24a38e0aa"} {"level":"info","ts":"2026-02-05T05:28:39.799921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T05:28:39.799958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became follower at term 0"} {"level":"info","ts":"2026-02-05T05:28:39.799973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b1b675f124322401 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T05:28:39.799985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became follower at term 1"} {"level":"info","ts":"2026-02-05T05:28:39.800119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=(12805552269029417985)"} {"level":"warn","ts":"2026-02-05T05:28:39.877067Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T05:28:39.898442Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T05:28:39.932226Z","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-05T05:28:40.012471Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b1b675f124322401","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T05:28:40.018876Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b1b675f124322401","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T05:28:40.019089Z","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-05T05:28:40.019215Z","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-05T05:28:40.019228Z","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-05T05:28:40.020658Z","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-05T05:28:40.02083Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b1b675f124322401","initial-advertise-peer-urls":["https://199.204.45.149:2380"],"listen-peer-urls":["https://199.204.45.149:2380"],"advertise-client-urls":["https://199.204.45.149:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T05:28:40.020852Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T05:28:40.021192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=(12805552269029417985)"} {"level":"info","ts":"2026-02-05T05:28:40.021249Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"913dfbf24a38e0aa","local-member-id":"b1b675f124322401","added-peer-id":"b1b675f124322401","added-peer-peer-urls":["https://199.204.45.149:2380"]} {"level":"info","ts":"2026-02-05T05:28:40.023711Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.149:2380"} {"level":"info","ts":"2026-02-05T05:28:40.02373Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.149:2380"} {"level":"info","ts":"2026-02-05T05:28:40.108473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T05:28:40.108675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T05:28:40.108751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 received MsgPreVoteResp from b1b675f124322401 at term 1"} {"level":"info","ts":"2026-02-05T05:28:40.108798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became candidate at term 2"} {"level":"info","ts":"2026-02-05T05:28:40.108856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 received MsgVoteResp from b1b675f124322401 at term 2"} {"level":"info","ts":"2026-02-05T05:28:40.108898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became leader at term 2"} {"level":"info","ts":"2026-02-05T05:28:40.108979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b1b675f124322401 elected leader b1b675f124322401 at term 2"} {"level":"info","ts":"2026-02-05T05:28:40.142501Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:28:40.237223Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b1b675f124322401","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.149:2379]}","request-path":"/0/members/b1b675f124322401/attributes","cluster-id":"913dfbf24a38e0aa","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T05:28:40.237351Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T05:28:40.237443Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T05:28:40.239163Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T05:28:40.239562Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T05:28:40.239593Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T05:28:40.241379Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.149:2379"} {"level":"info","ts":"2026-02-05T05:28:40.270036Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"913dfbf24a38e0aa","local-member-id":"b1b675f124322401","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:28:40.270176Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:28:40.270234Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:28:43.503838Z","caller":"traceutil/trace.go:171","msg":"trace[1190203408] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"107.198407ms","start":"2026-02-05T05:28:43.396613Z","end":"2026-02-05T05:28:43.503811Z","steps":["trace[1190203408] 'process raft request' (duration: 23.925268ms)","trace[1190203408] 'compare' (duration: 83.157657ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:28:43.827383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.794569ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-05T05:28:43.827767Z","caller":"traceutil/trace.go:171","msg":"trace[769836468] transaction","detail":"{read_only:false; response_revision:122; number_of_response:1; }","duration":"265.490102ms","start":"2026-02-05T05:28:43.562263Z","end":"2026-02-05T05:28:43.827753Z","steps":["trace[769836468] 'process raft request' (duration: 149.706994ms)","trace[769836468] 'compare' (duration: 114.474793ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:28:43.82817Z","caller":"traceutil/trace.go:171","msg":"trace[1529963649] linearizableReadLoop","detail":"{readStateIndex:126; appliedIndex:125; }","duration":"191.788411ms","start":"2026-02-05T05:28:43.63593Z","end":"2026-02-05T05:28:43.827718Z","steps":["trace[1529963649] 'read index received' (duration: 75.991213ms)","trace[1529963649] 'applied index is now lower than readState.Index' (duration: 115.795548ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:28:43.828444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.501984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T05:28:43.828747Z","caller":"traceutil/trace.go:171","msg":"trace[1896682586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:122; }","duration":"192.843291ms","start":"2026-02-05T05:28:43.635892Z","end":"2026-02-05T05:28:43.828735Z","steps":["trace[1896682586] 'agreement among raft nodes before linearized reading' (duration: 192.429623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:43.828666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.199317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T05:28:43.828923Z","caller":"traceutil/trace.go:171","msg":"trace[615574600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:122; }","duration":"139.454482ms","start":"2026-02-05T05:28:43.68946Z","end":"2026-02-05T05:28:43.828914Z","steps":["trace[615574600] 'agreement among raft nodes before linearized reading' (duration: 139.167656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:43.828694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.513154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T05:28:43.82926Z","caller":"traceutil/trace.go:171","msg":"trace[2045842594] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:122; }","duration":"124.090764ms","start":"2026-02-05T05:28:43.705158Z","end":"2026-02-05T05:28:43.829249Z","steps":["trace[2045842594] 'agreement among raft nodes before linearized reading' (duration: 123.521413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:44.877983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.865186ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:28:44.87861Z","caller":"traceutil/trace.go:171","msg":"trace[1161183202] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"148.933109ms","start":"2026-02-05T05:28:44.72964Z","end":"2026-02-05T05:28:44.878573Z","steps":["trace[1161183202] 'process raft request' (duration: 45.244857ms)","trace[1161183202] 'compare' (duration: 102.736924ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:28:45.297323Z","caller":"traceutil/trace.go:171","msg":"trace[872260473] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"126.831035ms","start":"2026-02-05T05:28:45.170469Z","end":"2026-02-05T05:28:45.2973Z","steps":["trace[872260473] 'process raft request' (duration: 126.340545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:50.847665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.877834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:28:50.84816Z","caller":"traceutil/trace.go:171","msg":"trace[5575984] linearizableReadLoop","detail":"{readStateIndex:322; appliedIndex:319; }","duration":"394.390135ms","start":"2026-02-05T05:28:50.453748Z","end":"2026-02-05T05:28:50.848138Z","steps":["trace[5575984] 'read index received' (duration: 19.102908ms)","trace[5575984] 'applied index is now lower than readState.Index' (duration: 375.285937ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:28:50.848436Z","caller":"traceutil/trace.go:171","msg":"trace[1256884117] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"406.375289ms","start":"2026-02-05T05:28:50.442047Z","end":"2026-02-05T05:28:50.848422Z","steps":["trace[1256884117] 'process raft request' (duration: 405.969131ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:50.848488Z","caller":"traceutil/trace.go:171","msg":"trace[33193982] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"406.891518ms","start":"2026-02-05T05:28:50.44157Z","end":"2026-02-05T05:28:50.848461Z","steps":["trace[33193982] 'process raft request' (duration: 190.097169ms)","trace[33193982] 'compare' (duration: 214.814683ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:28:50.84863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:50.441545Z","time spent":"407.01333ms","remote":"127.0.0.1:40890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:28:50.848667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:50.44204Z","time spent":"406.504841ms","remote":"127.0.0.1:40632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T05:28:50.849249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.491576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-05T05:28:50.849359Z","caller":"traceutil/trace.go:171","msg":"trace[1703808840] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:313; }","duration":"395.625258ms","start":"2026-02-05T05:28:50.453719Z","end":"2026-02-05T05:28:50.849344Z","steps":["trace[1703808840] 'agreement among raft nodes before linearized reading' (duration: 395.431805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:50.849457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:50.453694Z","time spent":"395.75019ms","remote":"127.0.0.1:40734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-02-05T05:28:50.849813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.916437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-05T05:28:50.849936Z","caller":"traceutil/trace.go:171","msg":"trace[210263024] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:313; }","duration":"346.06994ms","start":"2026-02-05T05:28:50.503854Z","end":"2026-02-05T05:28:50.849924Z","steps":["trace[210263024] 'agreement among raft nodes before linearized reading' (duration: 345.887066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:50.850035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:50.503795Z","time spent":"346.229302ms","remote":"127.0.0.1:40734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-02-05T05:28:50.850552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.28035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-05T05:28:50.851765Z","caller":"traceutil/trace.go:171","msg":"trace[168074775] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:313; }","duration":"147.541463ms","start":"2026-02-05T05:28:50.704213Z","end":"2026-02-05T05:28:50.851754Z","steps":["trace[168074775] 'agreement among raft nodes before linearized reading' (duration: 146.106897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:50.851565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.899648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-05T05:28:50.852403Z","caller":"traceutil/trace.go:171","msg":"trace[1711661258] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:313; }","duration":"196.775083ms","start":"2026-02-05T05:28:50.65559Z","end":"2026-02-05T05:28:50.852365Z","steps":["trace[1711661258] 'agreement among raft nodes before linearized reading' (duration: 195.926977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:50.851613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.241479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-05T05:28:50.85285Z","caller":"traceutil/trace.go:171","msg":"trace[866553156] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:313; }","duration":"249.517502ms","start":"2026-02-05T05:28:50.603324Z","end":"2026-02-05T05:28:50.852842Z","steps":["trace[866553156] 'agreement among raft nodes before linearized reading' (duration: 248.26689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:50.851656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.381009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-05T05:28:50.853085Z","caller":"traceutil/trace.go:171","msg":"trace[1205547233] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:313; }","duration":"298.874196ms","start":"2026-02-05T05:28:50.554204Z","end":"2026-02-05T05:28:50.853079Z","steps":["trace[1205547233] 'agreement among raft nodes before linearized reading' (duration: 297.428139ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:51.029973Z","caller":"traceutil/trace.go:171","msg":"trace[854868522] linearizableReadLoop","detail":"{readStateIndex:324; appliedIndex:322; }","duration":"160.064966ms","start":"2026-02-05T05:28:50.869888Z","end":"2026-02-05T05:28:51.029953Z","steps":["trace[854868522] 'read index received' (duration: 103.155371ms)","trace[854868522] 'applied index is now lower than readState.Index' (duration: 56.908695ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:28:51.030345Z","caller":"traceutil/trace.go:171","msg":"trace[608020785] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"158.12833ms","start":"2026-02-05T05:28:50.872206Z","end":"2026-02-05T05:28:51.030334Z","steps":["trace[608020785] 'process raft request' (duration: 157.55732ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:51.030719Z","caller":"traceutil/trace.go:171","msg":"trace[1482776488] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"157.01715ms","start":"2026-02-05T05:28:50.873694Z","end":"2026-02-05T05:28:51.030711Z","steps":["trace[1482776488] 'process raft request' (duration: 156.096674ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:51.031066Z","caller":"traceutil/trace.go:171","msg":"trace[633505813] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"157.272715ms","start":"2026-02-05T05:28:50.873784Z","end":"2026-02-05T05:28:51.031056Z","steps":["trace[633505813] 'process raft request' (duration: 156.029042ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:51.031363Z","caller":"traceutil/trace.go:171","msg":"trace[2024022198] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"150.515229ms","start":"2026-02-05T05:28:50.880841Z","end":"2026-02-05T05:28:51.031356Z","steps":["trace[2024022198] 'process raft request' (duration: 148.99481ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:51.031622Z","caller":"traceutil/trace.go:171","msg":"trace[1523842292] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"146.753278ms","start":"2026-02-05T05:28:50.884862Z","end":"2026-02-05T05:28:51.031615Z","steps":["trace[1523842292] 'process raft request' (duration: 144.995906ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:51.031861Z","caller":"traceutil/trace.go:171","msg":"trace[1461354802] transaction","detail":"{read_only:false; number_of_response:1; response_revision:321; }","duration":"146.911501ms","start":"2026-02-05T05:28:50.884941Z","end":"2026-02-05T05:28:51.031853Z","steps":["trace[1461354802] 'process raft request' (duration: 144.938914ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:51.032151Z","caller":"traceutil/trace.go:171","msg":"trace[698339110] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"147.078964ms","start":"2026-02-05T05:28:50.885065Z","end":"2026-02-05T05:28:51.032144Z","steps":["trace[698339110] 'process raft request' (duration: 144.857983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:51.032406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.500082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:3475"} {"level":"info","ts":"2026-02-05T05:28:51.032694Z","caller":"traceutil/trace.go:171","msg":"trace[1514951537] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:322; }","duration":"162.817078ms","start":"2026-02-05T05:28:50.869858Z","end":"2026-02-05T05:28:51.032675Z","steps":["trace[1514951537] 'agreement among raft nodes before linearized reading' (duration: 162.505103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:51.03295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.434781ms","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-05T05:28:51.033059Z","caller":"traceutil/trace.go:171","msg":"trace[1777389948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:322; }","duration":"130.579484ms","start":"2026-02-05T05:28:50.902472Z","end":"2026-02-05T05:28:51.033052Z","steps":["trace[1777389948] 'agreement among raft nodes before linearized reading' (duration: 130.450332ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:51.034266Z","caller":"traceutil/trace.go:171","msg":"trace[2083989269] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"164.431708ms","start":"2026-02-05T05:28:50.869823Z","end":"2026-02-05T05:28:51.034254Z","steps":["trace[2083989269] 'process raft request' (duration: 159.883533ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:51.03452Z","caller":"traceutil/trace.go:171","msg":"trace[1201841552] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"170.760568ms","start":"2026-02-05T05:28:50.863749Z","end":"2026-02-05T05:28:51.034509Z","steps":["trace[1201841552] 'process raft request' (duration: 109.227985ms)","trace[1201841552] 'compare' (duration: 56.621681ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:28:51.035053Z","caller":"traceutil/trace.go:171","msg":"trace[1844971773] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"165.103241ms","start":"2026-02-05T05:28:50.869941Z","end":"2026-02-05T05:28:51.035045Z","steps":["trace[1844971773] 'process raft request' (duration: 159.796932ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:51.412022Z","caller":"traceutil/trace.go:171","msg":"trace[605649375] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"169.521365ms","start":"2026-02-05T05:28:51.242479Z","end":"2026-02-05T05:28:51.412Z","steps":["trace[605649375] 'process raft request' (duration: 169.377422ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:51.412228Z","caller":"traceutil/trace.go:171","msg":"trace[280723376] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"170.686496ms","start":"2026-02-05T05:28:51.241517Z","end":"2026-02-05T05:28:51.412204Z","steps":["trace[280723376] 'process raft request' (duration: 162.957571ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:51.412652Z","caller":"traceutil/trace.go:171","msg":"trace[992276394] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"166.272153ms","start":"2026-02-05T05:28:51.246368Z","end":"2026-02-05T05:28:51.41264Z","steps":["trace[992276394] 'process raft request' (duration: 165.585761ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:51.413926Z","caller":"traceutil/trace.go:171","msg":"trace[1646189587] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"171.318818ms","start":"2026-02-05T05:28:51.242594Z","end":"2026-02-05T05:28:51.413913Z","steps":["trace[1646189587] 'process raft request' (duration: 169.318331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:52.259497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.642668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4172"} {"level":"info","ts":"2026-02-05T05:28:52.259546Z","caller":"traceutil/trace.go:171","msg":"trace[2097200213] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"246.565917ms","start":"2026-02-05T05:28:52.01295Z","end":"2026-02-05T05:28:52.259515Z","steps":["trace[2097200213] 'process raft request' (duration: 223.132188ms)","trace[2097200213] 'compare' (duration: 23.288256ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:28:52.259589Z","caller":"traceutil/trace.go:171","msg":"trace[1640302233] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:351; }","duration":"246.74988ms","start":"2026-02-05T05:28:52.01282Z","end":"2026-02-05T05:28:52.25957Z","steps":["trace[1640302233] 'range keys from in-memory index tree' (duration: 246.527635ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:28:52.760888Z","caller":"traceutil/trace.go:171","msg":"trace[1558246914] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"164.727604ms","start":"2026-02-05T05:28:52.596141Z","end":"2026-02-05T05:28:52.760869Z","steps":["trace[1558246914] 'process raft request' (duration: 87.390216ms)","trace[1558246914] 'compare' (duration: 77.228546ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:28:53.230635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.009896ms","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-05T05:28:53.230944Z","caller":"traceutil/trace.go:171","msg":"trace[316747157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:358; }","duration":"106.336561ms","start":"2026-02-05T05:28:53.124564Z","end":"2026-02-05T05:28:53.230901Z","steps":["trace[316747157] 'range keys from in-memory index tree' (duration: 105.937994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:59.49307Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594526574311277421,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T05:28:59.707159Z","caller":"traceutil/trace.go:171","msg":"trace[1673080627] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"971.46186ms","start":"2026-02-05T05:28:58.735676Z","end":"2026-02-05T05:28:59.707138Z","steps":["trace[1673080627] 'process raft request' (duration: 971.252676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:28:59.707358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:58.735663Z","time spent":"971.579142ms","remote":"127.0.0.1:40714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6883,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:29:00.033693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.910458ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-05T05:29:00.033812Z","caller":"traceutil/trace.go:171","msg":"trace[328538628] linearizableReadLoop","detail":"{readStateIndex:390; appliedIndex:388; }","duration":"1.041608483s","start":"2026-02-05T05:28:58.992181Z","end":"2026-02-05T05:29:00.033789Z","steps":["trace[328538628] 'read index received' (duration: 714.683451ms)","trace[328538628] 'applied index is now lower than readState.Index' (duration: 326.923592ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:29:00.033945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041748506s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:29:00.033982Z","caller":"traceutil/trace.go:171","msg":"trace[77459512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:377; }","duration":"1.041833028s","start":"2026-02-05T05:28:58.992139Z","end":"2026-02-05T05:29:00.033972Z","steps":["trace[77459512] 'agreement among raft nodes before linearized reading' (duration: 1.041752856s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:00.03402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:58.992116Z","time spent":"1.041895208s","remote":"127.0.0.1:40538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:29:00.034016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.792916ms","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-05T05:29:00.034125Z","caller":"traceutil/trace.go:171","msg":"trace[146133516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:377; }","duration":"948.923298ms","start":"2026-02-05T05:28:59.085183Z","end":"2026-02-05T05:29:00.034107Z","steps":["trace[146133516] 'agreement among raft nodes before linearized reading' (duration: 948.732385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:00.034174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:59.085122Z","time spent":"949.04172ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T05:29:00.034452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.91476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"warn","ts":"2026-02-05T05:29:00.034476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.41276ms","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-05T05:29:00.034484Z","caller":"traceutil/trace.go:171","msg":"trace[308123536] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:377; }","duration":"225.998232ms","start":"2026-02-05T05:28:59.808475Z","end":"2026-02-05T05:29:00.034473Z","steps":["trace[308123536] 'agreement among raft nodes before linearized reading' (duration: 225.940071ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:00.03451Z","caller":"traceutil/trace.go:171","msg":"trace[2123640680] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:377; }","duration":"547.484481ms","start":"2026-02-05T05:28:59.487017Z","end":"2026-02-05T05:29:00.034501Z","steps":["trace[2123640680] 'agreement among raft nodes before linearized reading' (duration: 547.40765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:00.03454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:59.486996Z","time spent":"547.538553ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T05:29:00.03465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.615527ms","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-05T05:29:00.034679Z","caller":"traceutil/trace.go:171","msg":"trace[1174727891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:377; }","duration":"772.678278ms","start":"2026-02-05T05:28:59.261991Z","end":"2026-02-05T05:29:00.03467Z","steps":["trace[1174727891] 'agreement among raft nodes before linearized reading' (duration: 772.632307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:00.034696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.479367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"warn","ts":"2026-02-05T05:29:00.034707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:59.26197Z","time spent":"772.731189ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-05T05:29:00.034726Z","caller":"traceutil/trace.go:171","msg":"trace[612468002] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:377; }","duration":"323.535088ms","start":"2026-02-05T05:28:59.711181Z","end":"2026-02-05T05:29:00.034716Z","steps":["trace[612468002] 'agreement among raft nodes before linearized reading' (duration: 323.478117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:00.03476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:59.711165Z","time spent":"323.586479ms","remote":"127.0.0.1:40714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6922,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-05T05:29:00.035035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.446882ms","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-05T05:29:00.035069Z","caller":"traceutil/trace.go:171","msg":"trace[1141556975] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:377; }","duration":"437.543803ms","start":"2026-02-05T05:28:59.597514Z","end":"2026-02-05T05:29:00.035058Z","steps":["trace[1141556975] 'agreement among raft nodes before linearized reading' (duration: 437.480421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:00.035097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:28:59.597492Z","time spent":"437.598905ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-05T05:29:02.297531Z","caller":"traceutil/trace.go:171","msg":"trace[1492856851] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"224.002944ms","start":"2026-02-05T05:29:02.073509Z","end":"2026-02-05T05:29:02.297512Z","steps":["trace[1492856851] 'process raft request' (duration: 223.850081ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:02.327065Z","caller":"traceutil/trace.go:171","msg":"trace[109580175] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"248.547154ms","start":"2026-02-05T05:29:02.078498Z","end":"2026-02-05T05:29:02.327045Z","steps":["trace[109580175] 'process raft request' (duration: 248.483142ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:02.327316Z","caller":"traceutil/trace.go:171","msg":"trace[2068297101] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"252.459048ms","start":"2026-02-05T05:29:02.074852Z","end":"2026-02-05T05:29:02.327311Z","steps":["trace[2068297101] 'process raft request' (duration: 252.029969ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:02.327427Z","caller":"traceutil/trace.go:171","msg":"trace[746073112] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:427; }","duration":"251.048962ms","start":"2026-02-05T05:29:02.076373Z","end":"2026-02-05T05:29:02.327421Z","steps":["trace[746073112] 'read index received' (duration: 221.10336ms)","trace[746073112] 'applied index is now lower than readState.Index' (duration: 29.945022ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:29:02.327526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.133543ms","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-05T05:29:02.327541Z","caller":"traceutil/trace.go:171","msg":"trace[1434823001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:416; }","duration":"251.174344ms","start":"2026-02-05T05:29:02.076362Z","end":"2026-02-05T05:29:02.327536Z","steps":["trace[1434823001] 'agreement among raft nodes before linearized reading' (duration: 251.077642ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:02.327603Z","caller":"traceutil/trace.go:171","msg":"trace[1934645695] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"178.687626ms","start":"2026-02-05T05:29:02.148911Z","end":"2026-02-05T05:29:02.327599Z","steps":["trace[1934645695] 'process raft request' (duration: 178.091244ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:02.459732Z","caller":"traceutil/trace.go:171","msg":"trace[541291535] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"126.247764ms","start":"2026-02-05T05:29:02.333464Z","end":"2026-02-05T05:29:02.459712Z","steps":["trace[541291535] 'process raft request' (duration: 67.233809ms)","trace[541291535] 'compare' (duration: 58.929483ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:29:04.527042Z","caller":"traceutil/trace.go:171","msg":"trace[1442429992] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"190.353264ms","start":"2026-02-05T05:29:04.336666Z","end":"2026-02-05T05:29:04.527019Z","steps":["trace[1442429992] 'read index received' (duration: 45.061233ms)","trace[1442429992] 'applied index is now lower than readState.Index' (duration: 145.291271ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:29:04.527328Z","caller":"traceutil/trace.go:171","msg":"trace[115279667] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"213.769422ms","start":"2026-02-05T05:29:04.313548Z","end":"2026-02-05T05:29:04.527318Z","steps":["trace[115279667] 'process raft request' (duration: 186.742276ms)","trace[115279667] 'compare' (duration: 26.632469ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:29:04.527483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.797522ms","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-05T05:29:04.527506Z","caller":"traceutil/trace.go:171","msg":"trace[1226839948] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:419; }","duration":"190.860594ms","start":"2026-02-05T05:29:04.336638Z","end":"2026-02-05T05:29:04.527499Z","steps":["trace[1226839948] 'agreement among raft nodes before linearized reading' (duration: 190.803923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:04.5276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.604068ms","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-05T05:29:04.527616Z","caller":"traceutil/trace.go:171","msg":"trace[1119652524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:419; }","duration":"190.63734ms","start":"2026-02-05T05:29:04.336974Z","end":"2026-02-05T05:29:04.527612Z","steps":["trace[1119652524] 'agreement among raft nodes before linearized reading' (duration: 190.607849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:04.527788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.700629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-05T05:29:04.527805Z","caller":"traceutil/trace.go:171","msg":"trace[1343985457] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:419; }","duration":"144.73315ms","start":"2026-02-05T05:29:04.383067Z","end":"2026-02-05T05:29:04.5278Z","steps":["trace[1343985457] 'agreement among raft nodes before linearized reading' (duration: 144.700609ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:08.960483Z","caller":"traceutil/trace.go:171","msg":"trace[480301862] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"264.698167ms","start":"2026-02-05T05:29:08.695767Z","end":"2026-02-05T05:29:08.960466Z","steps":["trace[480301862] 'process raft request' (duration: 264.603715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:15.254249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.895206ms","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-05T05:29:15.254315Z","caller":"traceutil/trace.go:171","msg":"trace[1132568288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:475; }","duration":"205.000258ms","start":"2026-02-05T05:29:15.049298Z","end":"2026-02-05T05:29:15.254298Z","steps":["trace[1132568288] 'range keys from in-memory index tree' (duration: 204.810894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:15.254485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.630701ms","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-05T05:29:15.254499Z","caller":"traceutil/trace.go:171","msg":"trace[1135237746] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:475; }","duration":"549.665883ms","start":"2026-02-05T05:29:14.704828Z","end":"2026-02-05T05:29:15.254494Z","steps":["trace[1135237746] 'range keys from in-memory index tree' (duration: 549.585401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:15.254512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:14.704813Z","time spent":"549.694203ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T05:29:15.25458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.254308ms","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-05T05:29:15.254591Z","caller":"traceutil/trace.go:171","msg":"trace[1028283608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:475; }","duration":"499.289359ms","start":"2026-02-05T05:29:14.755298Z","end":"2026-02-05T05:29:15.254588Z","steps":["trace[1028283608] 'range keys from in-memory index tree' (duration: 499.199927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:15.254602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:14.755273Z","time spent":"499.32669ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:29:15.25466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.45027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:29:15.254671Z","caller":"traceutil/trace.go:171","msg":"trace[176531612] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:475; }","duration":"266.48444ms","start":"2026-02-05T05:29:14.988183Z","end":"2026-02-05T05:29:15.254667Z","steps":["trace[176531612] 'range keys from in-memory index tree' (duration: 266.391579ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:15.497128Z","caller":"traceutil/trace.go:171","msg":"trace[1280156321] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"237.218362ms","start":"2026-02-05T05:29:15.259886Z","end":"2026-02-05T05:29:15.497105Z","steps":["trace[1280156321] 'process raft request' (duration: 237.060639ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:15.681352Z","caller":"traceutil/trace.go:171","msg":"trace[1144296479] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"419.668287ms","start":"2026-02-05T05:29:15.261653Z","end":"2026-02-05T05:29:15.681322Z","steps":["trace[1144296479] 'process raft request' (duration: 330.194072ms)","trace[1144296479] 'compare' (duration: 89.029407ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:29:15.681433Z","caller":"traceutil/trace.go:171","msg":"trace[1986128937] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"418.562707ms","start":"2026-02-05T05:29:15.262831Z","end":"2026-02-05T05:29:15.681394Z","steps":["trace[1986128937] 'process raft request' (duration: 418.18707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:15.68152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:15.261647Z","time spent":"419.777119ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:29:15.68157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:15.262824Z","time spent":"418.66969ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:29:16.49357Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594526574311277618,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T05:29:16.576632Z","caller":"traceutil/trace.go:171","msg":"trace[1470036933] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:496; }","duration":"583.981785ms","start":"2026-02-05T05:29:15.992626Z","end":"2026-02-05T05:29:16.576608Z","steps":["trace[1470036933] 'read index received' (duration: 516.347529ms)","trace[1470036933] 'applied index is now lower than readState.Index' (duration: 67.633286ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:29:16.576767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.121067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:29:16.576798Z","caller":"traceutil/trace.go:171","msg":"trace[500617293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:479; }","duration":"584.197658ms","start":"2026-02-05T05:29:15.992589Z","end":"2026-02-05T05:29:16.576787Z","steps":["trace[500617293] 'agreement among raft nodes before linearized reading' (duration: 584.104856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:16.576828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:15.99257Z","time spent":"584.251269ms","remote":"127.0.0.1:40538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T05:29:16.577104Z","caller":"traceutil/trace.go:171","msg":"trace[394848561] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"886.394346ms","start":"2026-02-05T05:29:15.690698Z","end":"2026-02-05T05:29:16.577092Z","steps":["trace[394848561] 'process raft request' (duration: 818.296572ms)","trace[394848561] 'compare' (duration: 67.519884ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:29:16.577183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:15.690681Z","time spent":"886.449598ms","remote":"127.0.0.1:40704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4299,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:29:17.866794Z","caller":"traceutil/trace.go:171","msg":"trace[11803023] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"166.782013ms","start":"2026-02-05T05:29:17.699993Z","end":"2026-02-05T05:29:17.866775Z","steps":["trace[11803023] 'process raft request' (duration: 134.804584ms)","trace[11803023] 'compare' (duration: 31.913348ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:29:18.255267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.257524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:29:18.255332Z","caller":"traceutil/trace.go:171","msg":"trace[1910150693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:484; }","duration":"267.356546ms","start":"2026-02-05T05:29:17.987963Z","end":"2026-02-05T05:29:18.255319Z","steps":["trace[1910150693] 'range keys from in-memory index tree' (duration: 267.195153ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:24.04927Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594526574311277664,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T05:29:24.549652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594526574311277664,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T05:29:24.57151Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.032176768s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T05:29:24.571882Z","caller":"traceutil/trace.go:171","msg":"trace[1495212602] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:516; }","duration":"1.023099275s","start":"2026-02-05T05:29:23.548762Z","end":"2026-02-05T05:29:24.571861Z","steps":["trace[1495212602] 'read index received' (duration: 1.022882771s)","trace[1495212602] 'applied index is now lower than readState.Index' (duration: 215.534µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:29:24.571952Z","caller":"traceutil/trace.go:171","msg":"trace[1618614547] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"1.032684827s","start":"2026-02-05T05:29:23.539259Z","end":"2026-02-05T05:29:24.571943Z","steps":["trace[1618614547] 'process raft request' (duration: 1.032447823s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:24.572072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:23.53925Z","time spent":"1.032726738s","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:29:24.572234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023466921s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ff5dh\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-05T05:29:24.572283Z","caller":"traceutil/trace.go:171","msg":"trace[1766059842] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ff5dh; range_end:; response_count:1; response_revision:498; }","duration":"1.023509231s","start":"2026-02-05T05:29:23.548745Z","end":"2026-02-05T05:29:24.572254Z","steps":["trace[1766059842] 'agreement among raft nodes before linearized reading' (duration: 1.02344805s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:24.572311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:23.548738Z","time spent":"1.023566832s","remote":"127.0.0.1:40714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16680,"request content":"key:\"/registry/pods/kube-system/cilium-ff5dh\" "} {"level":"warn","ts":"2026-02-05T05:29:24.572595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.05494ms","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-05T05:29:24.572623Z","caller":"traceutil/trace.go:171","msg":"trace[1558523186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:498; }","duration":"856.114652ms","start":"2026-02-05T05:29:23.7165Z","end":"2026-02-05T05:29:24.572615Z","steps":["trace[1558523186] 'agreement among raft nodes before linearized reading' (duration: 856.0194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:24.572648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:23.716483Z","time spent":"856.158642ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:29:24.572873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.517171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.149\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-05T05:29:24.572907Z","caller":"traceutil/trace.go:171","msg":"trace[894959551] range","detail":"{range_begin:/registry/masterleases/199.204.45.149; range_end:; response_count:1; response_revision:498; }","duration":"281.585261ms","start":"2026-02-05T05:29:24.29131Z","end":"2026-02-05T05:29:24.572896Z","steps":["trace[894959551] 'agreement among raft nodes before linearized reading' (duration: 281.534571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:24.573039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.698746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:29:24.573065Z","caller":"traceutil/trace.go:171","msg":"trace[57268695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:498; }","duration":"582.745647ms","start":"2026-02-05T05:29:23.99031Z","end":"2026-02-05T05:29:24.573056Z","steps":["trace[57268695] 'agreement among raft nodes before linearized reading' (duration: 582.709097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:24.573088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:23.990297Z","time spent":"582.785518ms","remote":"127.0.0.1:40538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:29:24.57326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.475414ms","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-05T05:29:24.573286Z","caller":"traceutil/trace.go:171","msg":"trace[935821783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:498; }","duration":"684.550434ms","start":"2026-02-05T05:29:23.888728Z","end":"2026-02-05T05:29:24.573278Z","steps":["trace[935821783] 'agreement among raft nodes before linearized reading' (duration: 684.461703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:24.573309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:23.888708Z","time spent":"684.595386ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T05:29:24.999845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.384925ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-05T05:29:24.999935Z","caller":"traceutil/trace.go:171","msg":"trace[1515071284] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:517; }","duration":"422.546516ms","start":"2026-02-05T05:29:24.577372Z","end":"2026-02-05T05:29:24.999918Z","steps":["trace[1515071284] 'read index received' (duration: 221.08544ms)","trace[1515071284] 'applied index is now lower than readState.Index' (duration: 201.459636ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:29:25.000005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:24.575649Z","time spent":"424.353629ms","remote":"127.0.0.1:40554","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-05T05:29:25.000289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.907102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:29:25.000318Z","caller":"traceutil/trace.go:171","msg":"trace[720983218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:498; }","duration":"422.958133ms","start":"2026-02-05T05:29:24.577351Z","end":"2026-02-05T05:29:25.000309Z","steps":["trace[720983218] 'agreement among raft nodes before linearized reading' (duration: 422.900902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:25.000345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:24.577342Z","time spent":"422.997214ms","remote":"127.0.0.1:40546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T05:29:25.113741Z","caller":"traceutil/trace.go:171","msg":"trace[296806202] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"535.339771ms","start":"2026-02-05T05:29:24.578379Z","end":"2026-02-05T05:29:25.113719Z","steps":["trace[296806202] 'process raft request' (duration: 530.524904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:25.113906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:24.578365Z","time spent":"535.467314ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:29:25.114031Z","caller":"traceutil/trace.go:171","msg":"trace[1745741648] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"534.625188ms","start":"2026-02-05T05:29:24.579397Z","end":"2026-02-05T05:29:25.114022Z","steps":["trace[1745741648] 'process raft request' (duration: 534.19221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:25.114495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:24.579388Z","time spent":"534.670788ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:29:25.116366Z","caller":"traceutil/trace.go:171","msg":"trace[1338477335] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"113.912715ms","start":"2026-02-05T05:29:25.002438Z","end":"2026-02-05T05:29:25.116351Z","steps":["trace[1338477335] 'process raft request' (duration: 111.910229ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:25.116611Z","caller":"traceutil/trace.go:171","msg":"trace[201470722] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"529.183901ms","start":"2026-02-05T05:29:24.587417Z","end":"2026-02-05T05:29:25.116601Z","steps":["trace[201470722] 'process raft request' (duration: 526.219547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:25.116714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:24.587408Z","time spent":"529.270442ms","remote":"127.0.0.1:40714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:29:25.119455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.40308ms","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-05T05:29:25.119504Z","caller":"traceutil/trace.go:171","msg":"trace[1546271956] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:502; }","duration":"470.497611ms","start":"2026-02-05T05:29:24.648995Z","end":"2026-02-05T05:29:25.119493Z","steps":["trace[1546271956] 'agreement among raft nodes before linearized reading' (duration: 470.288157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:25.119541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:24.648956Z","time spent":"470.577933ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-05T05:29:25.12037Z","caller":"traceutil/trace.go:171","msg":"trace[1142913798] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:518; }","duration":"114.454486ms","start":"2026-02-05T05:29:24.999959Z","end":"2026-02-05T05:29:25.114413Z","steps":["trace[1142913798] 'read index received' (duration: 112.054422ms)","trace[1142913798] 'applied index is now lower than readState.Index' (duration: 2.399344ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:29:25.120643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.844729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:29:25.120677Z","caller":"traceutil/trace.go:171","msg":"trace[2043398360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:502; }","duration":"111.92155ms","start":"2026-02-05T05:29:25.008746Z","end":"2026-02-05T05:29:25.120668Z","steps":["trace[2043398360] 'agreement among raft nodes before linearized reading' (duration: 111.860749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:27.133196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.193548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:29:27.133269Z","caller":"traceutil/trace.go:171","msg":"trace[888158757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"141.31133ms","start":"2026-02-05T05:29:26.99194Z","end":"2026-02-05T05:29:27.133252Z","steps":["trace[888158757] 'range keys from in-memory index tree' (duration: 141.125606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:27.133728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.146939ms","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-05T05:29:27.13377Z","caller":"traceutil/trace.go:171","msg":"trace[89983938] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:513; }","duration":"161.227429ms","start":"2026-02-05T05:29:26.972532Z","end":"2026-02-05T05:29:27.13376Z","steps":["trace[89983938] 'range keys from in-memory index tree' (duration: 161.064247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:37.208518Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594526574311277911,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T05:29:37.21691Z","caller":"traceutil/trace.go:171","msg":"trace[734017055] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"769.140551ms","start":"2026-02-05T05:29:36.447755Z","end":"2026-02-05T05:29:37.216895Z","steps":["trace[734017055] 'process raft request' (duration: 768.977658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:37.217021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:36.447739Z","time spent":"769.213384ms","remote":"127.0.0.1:40704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4481,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:29:37.473332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.181674ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:29:37.473466Z","caller":"traceutil/trace.go:171","msg":"trace[1826365221] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"252.415217ms","start":"2026-02-05T05:29:37.221038Z","end":"2026-02-05T05:29:37.473454Z","steps":["trace[1826365221] 'process raft request' (duration: 252.376236ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:37.473533Z","caller":"traceutil/trace.go:171","msg":"trace[788605279] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"315.999884ms","start":"2026-02-05T05:29:37.157511Z","end":"2026-02-05T05:29:37.473511Z","steps":["trace[788605279] 'process raft request' (duration: 167.573205ms)","trace[788605279] 'compare' (duration: 148.072032ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:29:37.473646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:37.157496Z","time spent":"316.082035ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:29:37.473736Z","caller":"traceutil/trace.go:171","msg":"trace[59923740] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:649; }","duration":"765.275722ms","start":"2026-02-05T05:29:36.70845Z","end":"2026-02-05T05:29:37.473726Z","steps":["trace[59923740] 'read index received' (duration: 508.412616ms)","trace[59923740] 'applied index is now lower than readState.Index' (duration: 256.862226ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:29:37.47387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.83399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:29:37.473896Z","caller":"traceutil/trace.go:171","msg":"trace[1562046499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"484.88095ms","start":"2026-02-05T05:29:36.989007Z","end":"2026-02-05T05:29:37.473888Z","steps":["trace[1562046499] 'agreement among raft nodes before linearized reading' (duration: 484.840959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:37.473915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:36.988993Z","time spent":"484.918592ms","remote":"127.0.0.1:40538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:29:37.473925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.470086ms","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-05T05:29:37.473949Z","caller":"traceutil/trace.go:171","msg":"trace[802307809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"765.517536ms","start":"2026-02-05T05:29:36.708424Z","end":"2026-02-05T05:29:37.473941Z","steps":["trace[802307809] 'agreement among raft nodes before linearized reading' (duration: 765.392374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:37.473973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:36.70841Z","time spent":"765.557997ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T05:29:37.47406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.182214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-05T05:29:37.474105Z","caller":"traceutil/trace.go:171","msg":"trace[2002857078] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:629; }","duration":"199.227875ms","start":"2026-02-05T05:29:37.274848Z","end":"2026-02-05T05:29:37.474076Z","steps":["trace[2002857078] 'agreement among raft nodes before linearized reading' (duration: 199.150354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:37.474222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.886649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-05T05:29:37.474245Z","caller":"traceutil/trace.go:171","msg":"trace[2125150242] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"280.928769ms","start":"2026-02-05T05:29:37.193309Z","end":"2026-02-05T05:29:37.474238Z","steps":["trace[2125150242] 'agreement among raft nodes before linearized reading' (duration: 280.869878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:37.47435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.249195ms","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-05T05:29:37.474372Z","caller":"traceutil/trace.go:171","msg":"trace[462832966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:629; }","duration":"271.283195ms","start":"2026-02-05T05:29:37.203082Z","end":"2026-02-05T05:29:37.474365Z","steps":["trace[462832966] 'agreement among raft nodes before linearized reading' (duration: 271.225474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:37.474417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.41137ms","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-05T05:29:37.474438Z","caller":"traceutil/trace.go:171","msg":"trace[822931051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"270.460451ms","start":"2026-02-05T05:29:37.203971Z","end":"2026-02-05T05:29:37.474432Z","steps":["trace[822931051] 'agreement among raft nodes before linearized reading' (duration: 270.40584ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:39.684409Z","caller":"traceutil/trace.go:171","msg":"trace[445700984] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"175.457046ms","start":"2026-02-05T05:29:39.508938Z","end":"2026-02-05T05:29:39.684395Z","steps":["trace[445700984] 'process raft request' (duration: 175.435806ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:39.684632Z","caller":"traceutil/trace.go:171","msg":"trace[1674248788] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"177.527604ms","start":"2026-02-05T05:29:39.507099Z","end":"2026-02-05T05:29:39.684627Z","steps":["trace[1674248788] 'process raft request' (duration: 173.947899ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:39.6847Z","caller":"traceutil/trace.go:171","msg":"trace[528137718] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"177.038634ms","start":"2026-02-05T05:29:39.507657Z","end":"2026-02-05T05:29:39.684696Z","steps":["trace[528137718] 'process raft request' (duration: 176.650198ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:39.684772Z","caller":"traceutil/trace.go:171","msg":"trace[472175755] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"175.868085ms","start":"2026-02-05T05:29:39.5089Z","end":"2026-02-05T05:29:39.684768Z","steps":["trace[472175755] 'process raft request' (duration: 175.443857ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:29:46.199984Z","caller":"traceutil/trace.go:171","msg":"trace[165266971] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:716; }","duration":"484.232208ms","start":"2026-02-05T05:29:45.71573Z","end":"2026-02-05T05:29:46.199962Z","steps":["trace[165266971] 'read index received' (duration: 484.040755ms)","trace[165266971] 'applied index is now lower than readState.Index' (duration: 190.473µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:29:46.200225Z","caller":"traceutil/trace.go:171","msg":"trace[695851385] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"485.33727ms","start":"2026-02-05T05:29:45.714876Z","end":"2026-02-05T05:29:46.200214Z","steps":["trace[695851385] 'process raft request' (duration: 484.915802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:46.200325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:45.714868Z","time spent":"485.388841ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:29:46.200553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.81618ms","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-05T05:29:46.200583Z","caller":"traceutil/trace.go:171","msg":"trace[920188850] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:692; }","duration":"484.854211ms","start":"2026-02-05T05:29:45.71572Z","end":"2026-02-05T05:29:46.200574Z","steps":["trace[920188850] 'agreement among raft nodes before linearized reading' (duration: 484.751859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:46.200612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:45.715715Z","time spent":"484.890532ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T05:29:46.200818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.277863ms","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-05T05:29:46.200846Z","caller":"traceutil/trace.go:171","msg":"trace[753103633] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:692; }","duration":"457.315824ms","start":"2026-02-05T05:29:45.743522Z","end":"2026-02-05T05:29:46.200838Z","steps":["trace[753103633] 'agreement among raft nodes before linearized reading' (duration: 457.239702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:46.200875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:45.743517Z","time spent":"457.349784ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T05:29:46.201049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.201068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:29:46.201078Z","caller":"traceutil/trace.go:171","msg":"trace[803271908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:692; }","duration":"208.256199ms","start":"2026-02-05T05:29:45.992814Z","end":"2026-02-05T05:29:46.20107Z","steps":["trace[803271908] 'agreement among raft nodes before linearized reading' (duration: 208.213268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:46.201221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.636549ms","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-05T05:29:46.201256Z","caller":"traceutil/trace.go:171","msg":"trace[1966268325] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:692; }","duration":"242.69329ms","start":"2026-02-05T05:29:45.958551Z","end":"2026-02-05T05:29:46.201245Z","steps":["trace[1966268325] 'agreement among raft nodes before linearized reading' (duration: 242.642349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:46.201439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.162284ms","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-05T05:29:46.201465Z","caller":"traceutil/trace.go:171","msg":"trace[811749833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:692; }","duration":"450.209135ms","start":"2026-02-05T05:29:45.751248Z","end":"2026-02-05T05:29:46.201457Z","steps":["trace[811749833] 'agreement among raft nodes before linearized reading' (duration: 450.130213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:46.201489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:45.751237Z","time spent":"450.246325ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T05:29:46.646607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.708701ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:29:46.646701Z","caller":"traceutil/trace.go:171","msg":"trace[1413053519] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"436.549268ms","start":"2026-02-05T05:29:46.210134Z","end":"2026-02-05T05:29:46.646683Z","steps":["trace[1413053519] 'process raft request' (duration: 96.676804ms)","trace[1413053519] 'compare' (duration: 339.582129ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:29:46.646788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:46.210124Z","time spent":"436.636799ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:29:46.772189Z","caller":"traceutil/trace.go:171","msg":"trace[1738711707] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"560.303822ms","start":"2026-02-05T05:29:46.21187Z","end":"2026-02-05T05:29:46.772173Z","steps":["trace[1738711707] 'process raft request' (duration: 560.276012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:29:46.77229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:46.211862Z","time spent":"560.372833ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:29:46.772525Z","caller":"traceutil/trace.go:171","msg":"trace[974650280] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"561.726628ms","start":"2026-02-05T05:29:46.210793Z","end":"2026-02-05T05:29:46.772519Z","steps":["trace[974650280] 'process raft request' (duration: 536.309029ms)","trace[974650280] 'compare' (duration: 24.933401ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:29:46.772551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:29:46.210785Z","time spent":"561.751588ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:29:59.8021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.255695ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-05T05:29:59.802193Z","caller":"traceutil/trace.go:171","msg":"trace[941539864] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:763; }","duration":"170.402045ms","start":"2026-02-05T05:29:59.631776Z","end":"2026-02-05T05:29:59.802179Z","steps":["trace[941539864] 'read index received' (duration: 27.11µs)","trace[941539864] 'applied index is now lower than readState.Index' (duration: 170.373905ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:29:59.802341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.555777ms","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-05T05:29:59.80236Z","caller":"traceutil/trace.go:171","msg":"trace[865044854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:735; }","duration":"170.608559ms","start":"2026-02-05T05:29:59.631745Z","end":"2026-02-05T05:29:59.802354Z","steps":["trace[865044854] 'agreement among raft nodes before linearized reading' (duration: 170.493657ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:30:01.092248Z","caller":"traceutil/trace.go:171","msg":"trace[1814119852] linearizableReadLoop","detail":"{readStateIndex:767; appliedIndex:766; }","duration":"252.232923ms","start":"2026-02-05T05:30:00.839999Z","end":"2026-02-05T05:30:01.092232Z","steps":["trace[1814119852] 'read index received' (duration: 252.08887ms)","trace[1814119852] 'applied index is now lower than readState.Index' (duration: 142.123µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:30:01.09251Z","caller":"traceutil/trace.go:171","msg":"trace[1826342031] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"387.176478ms","start":"2026-02-05T05:30:00.705326Z","end":"2026-02-05T05:30:01.092503Z","steps":["trace[1826342031] 'process raft request' (duration: 386.806952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:01.092591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:00.705312Z","time spent":"387.222999ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:30:01.092808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.806463ms","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-05T05:30:01.092836Z","caller":"traceutil/trace.go:171","msg":"trace[1850727391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:738; }","duration":"252.856253ms","start":"2026-02-05T05:30:00.839972Z","end":"2026-02-05T05:30:01.092828Z","steps":["trace[1850727391] 'agreement among raft nodes before linearized reading' (duration: 252.776252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:01.092946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.427386ms","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-05T05:30:01.092968Z","caller":"traceutil/trace.go:171","msg":"trace[198263070] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:738; }","duration":"252.457526ms","start":"2026-02-05T05:30:00.840505Z","end":"2026-02-05T05:30:01.092962Z","steps":["trace[198263070] 'agreement among raft nodes before linearized reading' (duration: 252.406285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:01.093153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.320119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:30:01.093176Z","caller":"traceutil/trace.go:171","msg":"trace[1695748611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:738; }","duration":"101.372639ms","start":"2026-02-05T05:30:00.991798Z","end":"2026-02-05T05:30:01.093171Z","steps":["trace[1695748611] 'agreement among raft nodes before linearized reading' (duration: 101.342028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:04.729831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.063358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.149\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-05T05:30:04.729892Z","caller":"traceutil/trace.go:171","msg":"trace[1529982965] range","detail":"{range_begin:/registry/masterleases/199.204.45.149; range_end:; response_count:1; response_revision:748; }","duration":"437.154439ms","start":"2026-02-05T05:30:04.292722Z","end":"2026-02-05T05:30:04.729877Z","steps":["trace[1529982965] 'agreement among raft nodes before linearized reading' (duration: 85.565614ms)","trace[1529982965] 'range keys from in-memory index tree' (duration: 351.430882ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:30:04.729923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:04.292714Z","time spent":"437.202671ms","remote":"127.0.0.1:40554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.149\" "} {"level":"warn","ts":"2026-02-05T05:30:05.224602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.066596ms","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-05T05:30:05.224656Z","caller":"traceutil/trace.go:171","msg":"trace[1379849696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:750; }","duration":"100.158937ms","start":"2026-02-05T05:30:05.124484Z","end":"2026-02-05T05:30:05.224643Z","steps":["trace[1379849696] 'agreement among raft nodes before linearized reading' (duration: 99.997094ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:30:05.224777Z","caller":"traceutil/trace.go:171","msg":"trace[649428581] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"104.896183ms","start":"2026-02-05T05:30:05.119867Z","end":"2026-02-05T05:30:05.224763Z","steps":["trace[649428581] 'process raft request' (duration: 104.469956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:05.793008Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594526574311278204,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T05:30:06.463504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.173392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:30:06.463574Z","caller":"traceutil/trace.go:171","msg":"trace[1542202069] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"1.235094981s","start":"2026-02-05T05:30:05.228466Z","end":"2026-02-05T05:30:06.463561Z","steps":["trace[1542202069] 'process raft request' (duration: 885.812127ms)","trace[1542202069] 'compare' (duration: 348.793435ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:30:06.463613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:05.228454Z","time spent":"1.235140471s","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:30:06.836992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.070491ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:30:06.837059Z","caller":"traceutil/trace.go:171","msg":"trace[1495554568] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:780; }","duration":"1.544149788s","start":"2026-02-05T05:30:05.292898Z","end":"2026-02-05T05:30:06.837047Z","steps":["trace[1495554568] 'read index received' (duration: 821.477966ms)","trace[1495554568] 'applied index is now lower than readState.Index' (duration: 722.670462ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:30:06.837129Z","caller":"traceutil/trace.go:171","msg":"trace[690534374] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"1.608009471s","start":"2026-02-05T05:30:05.229108Z","end":"2026-02-05T05:30:06.837117Z","steps":["trace[690534374] 'process raft request' (duration: 1.402754237s)","trace[690534374] 'compare' (duration: 204.947899ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:30:06.837164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:05.2291Z","time spent":"1.608044262s","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:30:06.837181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.422653285s","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-05T05:30:06.837234Z","caller":"traceutil/trace.go:171","msg":"trace[588492169] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:752; }","duration":"1.422747427s","start":"2026-02-05T05:30:05.414473Z","end":"2026-02-05T05:30:06.837221Z","steps":["trace[588492169] 'agreement among raft nodes before linearized reading' (duration: 1.422659736s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:06.837262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:05.414457Z","time spent":"1.422799318s","remote":"127.0.0.1:40916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-05T05:30:06.837457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.162322ms","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-05T05:30:06.837482Z","caller":"traceutil/trace.go:171","msg":"trace[1243738403] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:752; }","duration":"456.212444ms","start":"2026-02-05T05:30:06.38126Z","end":"2026-02-05T05:30:06.837473Z","steps":["trace[1243738403] 'agreement among raft nodes before linearized reading' (duration: 456.120902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:06.837493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015416396s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-05T05:30:06.837504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:06.381245Z","time spent":"456.254464ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-05T05:30:06.837517Z","caller":"traceutil/trace.go:171","msg":"trace[1078854001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:752; }","duration":"1.015471186s","start":"2026-02-05T05:30:05.822039Z","end":"2026-02-05T05:30:06.83751Z","steps":["trace[1078854001] 'agreement among raft nodes before linearized reading' (duration: 1.015386745s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:06.837536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:05.82202Z","time spent":"1.015511757s","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:30:06.837628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.544729048s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:30:06.837648Z","caller":"traceutil/trace.go:171","msg":"trace[746507549] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:752; }","duration":"1.54478427s","start":"2026-02-05T05:30:05.292857Z","end":"2026-02-05T05:30:06.837642Z","steps":["trace[746507549] 'agreement among raft nodes before linearized reading' (duration: 1.544749429s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:06.837665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:05.292839Z","time spent":"1.54482163s","remote":"127.0.0.1:40690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-02-05T05:30:10.688327Z","caller":"traceutil/trace.go:171","msg":"trace[1619238827] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"204.46882ms","start":"2026-02-05T05:30:10.483835Z","end":"2026-02-05T05:30:10.688304Z","steps":["trace[1619238827] 'process raft request' (duration: 204.338968ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:30:11.127321Z","caller":"traceutil/trace.go:171","msg":"trace[388094266] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"211.268933ms","start":"2026-02-05T05:30:10.916004Z","end":"2026-02-05T05:30:11.127272Z","steps":["trace[388094266] 'process raft request' (duration: 211.05745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:11.571452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.406134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:30:11.571629Z","caller":"traceutil/trace.go:171","msg":"trace[1696327000] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:794; }","duration":"572.911259ms","start":"2026-02-05T05:30:10.998703Z","end":"2026-02-05T05:30:11.571614Z","steps":["trace[1696327000] 'read index received' (duration: 129.13019ms)","trace[1696327000] 'applied index is now lower than readState.Index' (duration: 443.780149ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:30:11.571859Z","caller":"traceutil/trace.go:171","msg":"trace[125387546] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"654.784976ms","start":"2026-02-05T05:30:10.917061Z","end":"2026-02-05T05:30:11.571846Z","steps":["trace[125387546] 'process raft request' (duration: 344.928284ms)","trace[125387546] 'compare' (duration: 308.924686ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:30:11.571922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:10.917053Z","time spent":"654.835048ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:30:11.57207Z","caller":"traceutil/trace.go:171","msg":"trace[1951399170] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"653.699888ms","start":"2026-02-05T05:30:10.918361Z","end":"2026-02-05T05:30:11.572061Z","steps":["trace[1951399170] 'process raft request' (duration: 653.172888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:11.572117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:10.918352Z","time spent":"653.738849ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:30:11.572269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.562651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:30:11.572299Z","caller":"traceutil/trace.go:171","msg":"trace[96079381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"573.626353ms","start":"2026-02-05T05:30:10.998662Z","end":"2026-02-05T05:30:11.572289Z","steps":["trace[96079381] 'agreement among raft nodes before linearized reading' (duration: 573.554902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:11.572326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:10.998624Z","time spent":"573.697494ms","remote":"127.0.0.1:40538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:30:11.572593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.210946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-05T05:30:11.572625Z","caller":"traceutil/trace.go:171","msg":"trace[174820969] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:765; }","duration":"327.274027ms","start":"2026-02-05T05:30:11.24534Z","end":"2026-02-05T05:30:11.572614Z","steps":["trace[174820969] 'agreement among raft nodes before linearized reading' (duration: 327.120854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:11.572655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:11.245322Z","time spent":"327.327068ms","remote":"127.0.0.1:40800","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-05T05:30:23.488069Z","caller":"traceutil/trace.go:171","msg":"trace[195802530] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"305.015455ms","start":"2026-02-05T05:30:23.18304Z","end":"2026-02-05T05:30:23.488055Z","steps":["trace[195802530] 'process raft request' (duration: 304.913843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:23.488184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:23.18303Z","time spent":"305.087447ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:30:25.994327Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594526574311278448,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T05:30:26.202987Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.440394738s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T05:30:26.203228Z","caller":"traceutil/trace.go:171","msg":"trace[1395575804] linearizableReadLoop","detail":"{readStateIndex:889; appliedIndex:888; }","duration":"709.532786ms","start":"2026-02-05T05:30:25.493681Z","end":"2026-02-05T05:30:26.203214Z","steps":["trace[1395575804] 'read index received' (duration: 709.388274ms)","trace[1395575804] 'applied index is now lower than readState.Index' (duration: 143.962µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:30:26.203417Z","caller":"traceutil/trace.go:171","msg":"trace[918452908] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"1.440875498s","start":"2026-02-05T05:30:24.762536Z","end":"2026-02-05T05:30:26.203411Z","steps":["trace[918452908] 'process raft request' (duration: 1.440575281s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:26.203477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:24.762526Z","time spent":"1.440906178s","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:30:26.203607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.925384ms","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-05T05:30:26.203623Z","caller":"traceutil/trace.go:171","msg":"trace[1483756035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:853; }","duration":"709.964135ms","start":"2026-02-05T05:30:25.493653Z","end":"2026-02-05T05:30:26.203618Z","steps":["trace[1483756035] 'agreement among raft nodes before linearized reading' (duration: 709.904974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:26.203636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:25.49364Z","time spent":"709.993315ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T05:30:26.203715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.801639ms","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-05T05:30:26.203726Z","caller":"traceutil/trace.go:171","msg":"trace[785341723] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:853; }","duration":"501.83488ms","start":"2026-02-05T05:30:25.701888Z","end":"2026-02-05T05:30:26.203723Z","steps":["trace[785341723] 'agreement among raft nodes before linearized reading' (duration: 501.802279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:26.203738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:25.701874Z","time spent":"501.86143ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T05:30:26.203821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.500555ms","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-05T05:30:26.203838Z","caller":"traceutil/trace.go:171","msg":"trace[832822138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:853; }","duration":"500.525656ms","start":"2026-02-05T05:30:25.703309Z","end":"2026-02-05T05:30:26.203834Z","steps":["trace[832822138] 'agreement among raft nodes before linearized reading' (duration: 500.487195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:26.20385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:25.703304Z","time spent":"500.543186ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:30:26.20397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.793428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-05T05:30:26.204025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.819303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:30:26.204044Z","caller":"traceutil/trace.go:171","msg":"trace[169007147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:853; }","duration":"214.857463ms","start":"2026-02-05T05:30:25.98918Z","end":"2026-02-05T05:30:26.204037Z","steps":["trace[169007147] 'agreement among raft nodes before linearized reading' (duration: 214.832923ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:30:26.204043Z","caller":"traceutil/trace.go:171","msg":"trace[1262210065] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:853; }","duration":"480.87946ms","start":"2026-02-05T05:30:25.723144Z","end":"2026-02-05T05:30:26.204023Z","steps":["trace[1262210065] 'agreement among raft nodes before linearized reading' (duration: 480.645016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:26.204089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:25.723112Z","time spent":"480.968392ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-05T05:30:26.902453Z","caller":"traceutil/trace.go:171","msg":"trace[2014268885] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"691.266262ms","start":"2026-02-05T05:30:26.211169Z","end":"2026-02-05T05:30:26.902436Z","steps":["trace[2014268885] 'process raft request' (duration: 691.15961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:26.902574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:26.211158Z","time spent":"691.350013ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:30:26.916179Z","caller":"traceutil/trace.go:171","msg":"trace[923422254] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"699.952504ms","start":"2026-02-05T05:30:26.216216Z","end":"2026-02-05T05:30:26.916168Z","steps":["trace[923422254] 'process raft request' (duration: 699.934934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:26.916259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:26.21621Z","time spent":"700.005165ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:30:26.916436Z","caller":"traceutil/trace.go:171","msg":"trace[1876008985] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"704.848431ms","start":"2026-02-05T05:30:26.211583Z","end":"2026-02-05T05:30:26.916431Z","steps":["trace[1876008985] 'process raft request' (duration: 704.492895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:26.916474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:26.211574Z","time spent":"704.877981ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:30:26.916531Z","caller":"traceutil/trace.go:171","msg":"trace[310846494] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"704.906021ms","start":"2026-02-05T05:30:26.211621Z","end":"2026-02-05T05:30:26.916527Z","steps":["trace[310846494] 'process raft request' (duration: 704.508095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:26.916555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:26.211616Z","time spent":"704.926002ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:30:33.491255Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594526574311278491,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T05:30:33.760806Z","caller":"traceutil/trace.go:171","msg":"trace[1249994166] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"802.373592ms","start":"2026-02-05T05:30:32.95841Z","end":"2026-02-05T05:30:33.760783Z","steps":["trace[1249994166] 'process raft request' (duration: 802.288871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:33.760907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:32.958397Z","time spent":"802.452324ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:30:33.761131Z","caller":"traceutil/trace.go:171","msg":"trace[785341439] linearizableReadLoop","detail":"{readStateIndex:907; appliedIndex:907; }","duration":"770.480022ms","start":"2026-02-05T05:30:32.990642Z","end":"2026-02-05T05:30:33.761122Z","steps":["trace[785341439] 'read index received' (duration: 770.477652ms)","trace[785341439] 'applied index is now lower than readState.Index' (duration: 2.06µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:30:33.761218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.334747ms","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-05T05:30:33.761238Z","caller":"traceutil/trace.go:171","msg":"trace[1907889455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:870; }","duration":"763.380568ms","start":"2026-02-05T05:30:32.997854Z","end":"2026-02-05T05:30:33.761234Z","steps":["trace[1907889455] 'agreement among raft nodes before linearized reading' (duration: 763.308077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:33.761255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:32.997837Z","time spent":"763.414419ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T05:30:33.76135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.711167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:30:33.761369Z","caller":"traceutil/trace.go:171","msg":"trace[347354696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:870; }","duration":"770.746018ms","start":"2026-02-05T05:30:32.990619Z","end":"2026-02-05T05:30:33.761365Z","steps":["trace[347354696] 'agreement among raft nodes before linearized reading' (duration: 770.712838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:33.76138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:32.990609Z","time spent":"770.768778ms","remote":"127.0.0.1:40538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:30:33.761464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.532386ms","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-05T05:30:33.761481Z","caller":"traceutil/trace.go:171","msg":"trace[596058530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:870; }","duration":"753.560436ms","start":"2026-02-05T05:30:33.007917Z","end":"2026-02-05T05:30:33.761477Z","steps":["trace[596058530] 'agreement among raft nodes before linearized reading' (duration: 753.520455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:33.761493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:33.007911Z","time spent":"753.579186ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:30:33.761572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.234758ms","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-05T05:30:33.761588Z","caller":"traceutil/trace.go:171","msg":"trace[1773448999] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:870; }","duration":"754.266028ms","start":"2026-02-05T05:30:33.007318Z","end":"2026-02-05T05:30:33.761584Z","steps":["trace[1773448999] 'agreement among raft nodes before linearized reading' (duration: 754.217937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:33.761599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:33.007309Z","time spent":"754.287379ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-05T05:30:33.892372Z","caller":"traceutil/trace.go:171","msg":"trace[2112758614] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"125.102337ms","start":"2026-02-05T05:30:33.767263Z","end":"2026-02-05T05:30:33.892365Z","steps":["trace[2112758614] 'process raft request' (duration: 124.850733ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:30:33.892348Z","caller":"traceutil/trace.go:171","msg":"trace[171513817] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"119.658161ms","start":"2026-02-05T05:30:33.772677Z","end":"2026-02-05T05:30:33.892335Z","steps":["trace[171513817] 'process raft request' (duration: 119.63188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:34.229104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.055644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:30:34.229196Z","caller":"traceutil/trace.go:171","msg":"trace[540974999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:874; }","duration":"237.178476ms","start":"2026-02-05T05:30:33.992003Z","end":"2026-02-05T05:30:34.229181Z","steps":["trace[540974999] 'range keys from in-memory index tree' (duration: 236.994243ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:30:34.408475Z","caller":"traceutil/trace.go:171","msg":"trace[1457784201] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"100.158769ms","start":"2026-02-05T05:30:34.308298Z","end":"2026-02-05T05:30:34.408457Z","steps":["trace[1457784201] 'process raft request' (duration: 24.950408ms)","trace[1457784201] 'compare' (duration: 75.12898ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:30:38.761609Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594526574311278532,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T05:30:38.819607Z","caller":"traceutil/trace.go:171","msg":"trace[1703064170] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"907.859125ms","start":"2026-02-05T05:30:37.911732Z","end":"2026-02-05T05:30:38.819592Z","steps":["trace[1703064170] 'process raft request' (duration: 907.757523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:38.819724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:37.911722Z","time spent":"907.949566ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:30:39.396441Z","caller":"traceutil/trace.go:171","msg":"trace[483159758] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:922; }","duration":"1.1358572s","start":"2026-02-05T05:30:38.260567Z","end":"2026-02-05T05:30:39.396425Z","steps":["trace[483159758] 'read index received' (duration: 559.431376ms)","trace[483159758] 'applied index is now lower than readState.Index' (duration: 576.424864ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:30:39.396516Z","caller":"traceutil/trace.go:171","msg":"trace[2038399415] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"1.483358543s","start":"2026-02-05T05:30:37.913152Z","end":"2026-02-05T05:30:39.396511Z","steps":["trace[2038399415] 'process raft request' (duration: 1.39719295s)","trace[2038399415] 'compare' (duration: 85.674585ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:30:39.396584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:37.913147Z","time spent":"1.483382933s","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:30:39.396604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136026522s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-05T05:30:39.396647Z","caller":"traceutil/trace.go:171","msg":"trace[1183210423] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:886; }","duration":"1.136102244s","start":"2026-02-05T05:30:38.260534Z","end":"2026-02-05T05:30:39.396636Z","steps":["trace[1183210423] 'agreement among raft nodes before linearized reading' (duration: 1.135975492s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:39.396668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:38.260513Z","time spent":"1.136150515s","remote":"127.0.0.1:40800","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-05T05:30:39.396701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.568298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:30:39.396719Z","caller":"traceutil/trace.go:171","msg":"trace[1282092408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:886; }","duration":"407.60995ms","start":"2026-02-05T05:30:38.989105Z","end":"2026-02-05T05:30:39.396715Z","steps":["trace[1282092408] 'agreement among raft nodes before linearized reading' (duration: 407.580649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:39.396733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:38.989083Z","time spent":"407.64752ms","remote":"127.0.0.1:40538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T05:30:39.396765Z","caller":"traceutil/trace.go:171","msg":"trace[2133449012] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"1.114727519s","start":"2026-02-05T05:30:38.282032Z","end":"2026-02-05T05:30:39.396759Z","steps":["trace[2133449012] 'process raft request' (duration: 1.114339573s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:39.396818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:38.282016Z","time spent":"1.114767741s","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:30:39.844659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.881028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:30:39.844879Z","caller":"traceutil/trace.go:171","msg":"trace[1824472809] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"444.07812ms","start":"2026-02-05T05:30:39.40077Z","end":"2026-02-05T05:30:39.844848Z","steps":["trace[1824472809] 'process raft request' (duration: 308.920236ms)","trace[1824472809] 'compare' (duration: 134.762346ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:30:39.845084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:39.400761Z","time spent":"444.232723ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:30:39.845476Z","caller":"traceutil/trace.go:171","msg":"trace[72140261] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:924; }","duration":"369.028591ms","start":"2026-02-05T05:30:39.476435Z","end":"2026-02-05T05:30:39.845464Z","steps":["trace[72140261] 'read index received' (duration: 233.269447ms)","trace[72140261] 'applied index is now lower than readState.Index' (duration: 135.757534ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:30:39.995775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.013593ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-02-05T05:30:39.996505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.053254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T05:30:39.996585Z","caller":"traceutil/trace.go:171","msg":"trace[1520380077] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:887; }","duration":"520.138686ms","start":"2026-02-05T05:30:39.476412Z","end":"2026-02-05T05:30:39.996551Z","steps":["trace[1520380077] 'agreement among raft nodes before linearized reading' (duration: 369.133914ms)","trace[1520380077] 'count revisions from in-memory index tree' (duration: 150.911181ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:30:39.996627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:39.476398Z","time spent":"520.218898ms","remote":"127.0.0.1:40632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-02-05T05:30:39.997202Z","caller":"traceutil/trace.go:171","msg":"trace[274217491] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"151.636414ms","start":"2026-02-05T05:30:39.845511Z","end":"2026-02-05T05:30:39.997148Z","steps":["trace[274217491] 'read index received' (duration: 52.941µs)","trace[274217491] 'applied index is now lower than readState.Index' (duration: 151.400639ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:30:39.998729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.402821ms","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-05T05:30:39.998795Z","caller":"traceutil/trace.go:171","msg":"trace[158338140] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:887; }","duration":"220.485782ms","start":"2026-02-05T05:30:39.778283Z","end":"2026-02-05T05:30:39.998769Z","steps":["trace[158338140] 'agreement among raft nodes before linearized reading' (duration: 219.77037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:39.99985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.714519ms","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-05T05:30:39.999946Z","caller":"traceutil/trace.go:171","msg":"trace[1255424047] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:887; }","duration":"108.839871ms","start":"2026-02-05T05:30:39.891084Z","end":"2026-02-05T05:30:39.999924Z","steps":["trace[1255424047] 'agreement among raft nodes before linearized reading' (duration: 108.620808ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:30:40.217142Z","caller":"traceutil/trace.go:171","msg":"trace[1765503708] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"202.677459ms","start":"2026-02-05T05:30:40.014442Z","end":"2026-02-05T05:30:40.21712Z","steps":["trace[1765503708] 'process raft request' (duration: 202.484036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:41.378467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.658604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:30:41.378997Z","caller":"traceutil/trace.go:171","msg":"trace[53397343] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:890; }","duration":"389.754035ms","start":"2026-02-05T05:30:40.988766Z","end":"2026-02-05T05:30:41.37852Z","steps":["trace[53397343] 'range keys from in-memory index tree' (duration: 389.613523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:41.37907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:40.988753Z","time spent":"390.290144ms","remote":"127.0.0.1:40546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T05:30:41.774397Z","caller":"traceutil/trace.go:171","msg":"trace[1994968438] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"367.917162ms","start":"2026-02-05T05:30:41.406455Z","end":"2026-02-05T05:30:41.774372Z","steps":["trace[1994968438] 'process raft request' (duration: 367.77308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:41.774549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:41.406439Z","time spent":"368.025924ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:30:41.99064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.200777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-05T05:30:41.990713Z","caller":"traceutil/trace.go:171","msg":"trace[65452211] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:891; }","duration":"142.31592ms","start":"2026-02-05T05:30:41.848381Z","end":"2026-02-05T05:30:41.990697Z","steps":["trace[65452211] 'range keys from in-memory index tree' (duration: 142.048115ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:30:42.360068Z","caller":"traceutil/trace.go:171","msg":"trace[1872921290] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:930; }","duration":"292.05539ms","start":"2026-02-05T05:30:42.067988Z","end":"2026-02-05T05:30:42.360044Z","steps":["trace[1872921290] 'read index received' (duration: 291.846517ms)","trace[1872921290] 'applied index is now lower than readState.Index' (duration: 207.723µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:30:42.360187Z","caller":"traceutil/trace.go:171","msg":"trace[809845722] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"361.051431ms","start":"2026-02-05T05:30:41.999125Z","end":"2026-02-05T05:30:42.360176Z","steps":["trace[809845722] 'process raft request' (duration: 360.744436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:42.360293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:41.999117Z","time spent":"361.095772ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:30:42.360334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.34758ms","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-05T05:30:42.36039Z","caller":"traceutil/trace.go:171","msg":"trace[1626338883] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:892; }","duration":"138.443202ms","start":"2026-02-05T05:30:42.221933Z","end":"2026-02-05T05:30:42.360376Z","steps":["trace[1626338883] 'agreement among raft nodes before linearized reading' (duration: 138.31573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:42.360485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.497378ms","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-05T05:30:42.360499Z","caller":"traceutil/trace.go:171","msg":"trace[574460162] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:892; }","duration":"292.532798ms","start":"2026-02-05T05:30:42.06796Z","end":"2026-02-05T05:30:42.360493Z","steps":["trace[574460162] 'agreement among raft nodes before linearized reading' (duration: 292.495247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:44.57294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.423064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:30:44.573105Z","caller":"traceutil/trace.go:171","msg":"trace[1190056206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:900; }","duration":"584.617059ms","start":"2026-02-05T05:30:43.988475Z","end":"2026-02-05T05:30:44.573092Z","steps":["trace[1190056206] 'range keys from in-memory index tree' (duration: 584.374473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:44.573144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:43.988461Z","time spent":"584.67395ms","remote":"127.0.0.1:40538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:30:44.573484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.885613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4783"} {"level":"warn","ts":"2026-02-05T05:30:44.573516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.181141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:30:44.573551Z","caller":"traceutil/trace.go:171","msg":"trace[1425364435] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:900; }","duration":"585.716707ms","start":"2026-02-05T05:30:43.987819Z","end":"2026-02-05T05:30:44.573535Z","steps":["trace[1425364435] 'range keys from in-memory index tree' (duration: 584.71269ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:30:44.573561Z","caller":"traceutil/trace.go:171","msg":"trace[1163852737] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"277.336531ms","start":"2026-02-05T05:30:44.296217Z","end":"2026-02-05T05:30:44.573554Z","steps":["trace[1163852737] 'read index received' (duration: 26.76µs)","trace[1163852737] 'applied index is now lower than readState.Index' (duration: 277.309211ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:30:44.57359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:43.987788Z","time spent":"585.793639ms","remote":"127.0.0.1:40704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4807,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-05T05:30:44.573703Z","caller":"traceutil/trace.go:171","msg":"trace[770165272] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"441.446653ms","start":"2026-02-05T05:30:44.132251Z","end":"2026-02-05T05:30:44.573698Z","steps":["trace[770165272] 'process raft request' (duration: 50.045888ms)","trace[770165272] 'compare' (duration: 390.748323ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:30:44.573732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:44.132232Z","time spent":"441.485113ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:30:44.573846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.628926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.149\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-05T05:30:44.573871Z","caller":"traceutil/trace.go:171","msg":"trace[376752947] range","detail":"{range_begin:/registry/masterleases/199.204.45.149; range_end:; response_count:1; response_revision:901; }","duration":"277.666227ms","start":"2026-02-05T05:30:44.296188Z","end":"2026-02-05T05:30:44.573854Z","steps":["trace[376752947] 'agreement among raft nodes before linearized reading' (duration: 277.603636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:44.574106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.969691ms","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-05T05:30:44.574122Z","caller":"traceutil/trace.go:171","msg":"trace[1384194573] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:901; }","duration":"184.006861ms","start":"2026-02-05T05:30:44.39011Z","end":"2026-02-05T05:30:44.574117Z","steps":["trace[1384194573] 'agreement among raft nodes before linearized reading' (duration: 183.94978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:44.574126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.011488ms","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-05T05:30:44.57416Z","caller":"traceutil/trace.go:171","msg":"trace[1585012396] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:901; }","duration":"210.06266ms","start":"2026-02-05T05:30:44.364087Z","end":"2026-02-05T05:30:44.57415Z","steps":["trace[1585012396] 'agreement among raft nodes before linearized reading' (duration: 209.942198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:44.574278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.609632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-05T05:30:44.574301Z","caller":"traceutil/trace.go:171","msg":"trace[1063822530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:901; }","duration":"188.653972ms","start":"2026-02-05T05:30:44.38564Z","end":"2026-02-05T05:30:44.574294Z","steps":["trace[1063822530] 'agreement among raft nodes before linearized reading' (duration: 188.587021ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:30:48.771223Z","caller":"traceutil/trace.go:171","msg":"trace[1404247296] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:975; }","duration":"152.418557ms","start":"2026-02-05T05:30:48.618787Z","end":"2026-02-05T05:30:48.771206Z","steps":["trace[1404247296] 'read index received' (duration: 152.286075ms)","trace[1404247296] 'applied index is now lower than readState.Index' (duration: 131.862µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:30:48.771444Z","caller":"traceutil/trace.go:171","msg":"trace[1749639252] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"160.02777ms","start":"2026-02-05T05:30:48.611409Z","end":"2026-02-05T05:30:48.771437Z","steps":["trace[1749639252] 'process raft request' (duration: 159.711545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:48.771618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.818204ms","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-05T05:30:48.771638Z","caller":"traceutil/trace.go:171","msg":"trace[1073902741] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:936; }","duration":"152.868255ms","start":"2026-02-05T05:30:48.618764Z","end":"2026-02-05T05:30:48.771632Z","steps":["trace[1073902741] 'agreement among raft nodes before linearized reading' (duration: 152.784123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:48.771744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.23818ms","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-05T05:30:48.77176Z","caller":"traceutil/trace.go:171","msg":"trace[799620460] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:936; }","duration":"145.273371ms","start":"2026-02-05T05:30:48.626482Z","end":"2026-02-05T05:30:48.771755Z","steps":["trace[799620460] 'agreement among raft nodes before linearized reading' (duration: 145.22967ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:30:52.888172Z","caller":"traceutil/trace.go:171","msg":"trace[1563097220] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"134.247258ms","start":"2026-02-05T05:30:52.753901Z","end":"2026-02-05T05:30:52.888149Z","steps":["trace[1563097220] 'process raft request' (duration: 133.941083ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:30:52.888936Z","caller":"traceutil/trace.go:171","msg":"trace[318129971] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"103.484587ms","start":"2026-02-05T05:30:52.784445Z","end":"2026-02-05T05:30:52.88793Z","steps":["trace[318129971] 'read index received' (duration: 103.359635ms)","trace[318129971] 'applied index is now lower than readState.Index' (duration: 124.322µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:30:52.889086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.638138ms","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-05T05:30:52.889109Z","caller":"traceutil/trace.go:171","msg":"trace[572791322] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:962; }","duration":"104.715699ms","start":"2026-02-05T05:30:52.784386Z","end":"2026-02-05T05:30:52.889102Z","steps":["trace[572791322] 'agreement among raft nodes before linearized reading' (duration: 104.624517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:58.388007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.048308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:30:58.388108Z","caller":"traceutil/trace.go:171","msg":"trace[674896760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1003; }","duration":"395.176121ms","start":"2026-02-05T05:30:57.992881Z","end":"2026-02-05T05:30:58.388057Z","steps":["trace[674896760] 'range keys from in-memory index tree' (duration: 394.954116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:30:58.388263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:30:57.992864Z","time spent":"395.379735ms","remote":"127.0.0.1:40546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T05:30:58.699083Z","caller":"traceutil/trace.go:171","msg":"trace[1994941834] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"138.84696ms","start":"2026-02-05T05:30:58.560208Z","end":"2026-02-05T05:30:58.699055Z","steps":["trace[1994941834] 'process raft request' (duration: 138.673066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:34:02.662872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.591145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-05T05:34:02.664006Z","caller":"traceutil/trace.go:171","msg":"trace[1839001980] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1786; }","duration":"120.754346ms","start":"2026-02-05T05:34:02.543233Z","end":"2026-02-05T05:34:02.663987Z","steps":["trace[1839001980] 'range keys from in-memory index tree' (duration: 119.478943ms)"],"step_count":1}