{"level":"warn","ts":"2026-02-08T22:08:53.740193Z","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-08T22:08:53.740436Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.49:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.49:2380","--initial-cluster=instance=https://162.253.55.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.49: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-08T22:08:53.740619Z","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-08T22:08:53.740693Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-02-08T22:08:53.745201Z","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-08T22:08:53.745779Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"]} {"level":"info","ts":"2026-02-08T22:08:53.745937Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.49: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-08T22:08:53.750739Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.465893ms"} {"level":"info","ts":"2026-02-08T22:08:53.756069Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6a9ba61e55fef5be","cluster-id":"9612db6e19ac6ce7"} {"level":"info","ts":"2026-02-08T22:08:53.756193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=()"} {"level":"info","ts":"2026-02-08T22:08:53.756274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 0"} {"level":"info","ts":"2026-02-08T22:08:53.756333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6a9ba61e55fef5be [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-08T22:08:53.756379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 1"} {"level":"info","ts":"2026-02-08T22:08:53.756452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"warn","ts":"2026-02-08T22:08:53.76547Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-08T22:08:53.807222Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-08T22:08:53.815501Z","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-08T22:08:53.821308Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6a9ba61e55fef5be","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-08T22:08:53.822983Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6a9ba61e55fef5be","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-08T22:08:53.823142Z","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-08T22:08:53.823186Z","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-08T22:08:53.823209Z","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-08T22:08:53.824145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"info","ts":"2026-02-08T22:08:53.824514Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","added-peer-id":"6a9ba61e55fef5be","added-peer-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-02-08T22:08:53.83138Z","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-08T22:08:53.83154Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-02-08T22:08:53.831573Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-02-08T22:08:53.833069Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6a9ba61e55fef5be","initial-advertise-peer-urls":["https://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-08T22:08:53.833107Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-08T22:08:53.957317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be is starting a new election at term 1"} {"level":"info","ts":"2026-02-08T22:08:53.957411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became pre-candidate at term 1"} {"level":"info","ts":"2026-02-08T22:08:53.957444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgPreVoteResp from 6a9ba61e55fef5be at term 1"} {"level":"info","ts":"2026-02-08T22:08:53.957502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became candidate at term 2"} {"level":"info","ts":"2026-02-08T22:08:53.957529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgVoteResp from 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-02-08T22:08:53.957571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became leader at term 2"} {"level":"info","ts":"2026-02-08T22:08:53.957609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6a9ba61e55fef5be elected leader 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-02-08T22:08:53.959595Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6a9ba61e55fef5be","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.49:2379]}","request-path":"/0/members/6a9ba61e55fef5be/attributes","cluster-id":"9612db6e19ac6ce7","publish-timeout":"7s"} {"level":"info","ts":"2026-02-08T22:08:53.959739Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T22:08:53.960025Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T22:08:53.966143Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-08T22:08:53.968348Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:08:53.969469Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-08T22:08:53.969512Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-08T22:08:53.974893Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.49:2379"} {"level":"info","ts":"2026-02-08T22:08:53.978728Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:08:53.97884Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:08:53.978894Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:09:06.380296Z","caller":"traceutil/trace.go:171","msg":"trace[2118419145] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:407; }","duration":"146.208271ms","start":"2026-02-08T22:09:06.234072Z","end":"2026-02-08T22:09:06.38028Z","steps":["trace[2118419145] 'read index received' (duration: 146.08482ms)","trace[2118419145] 'applied index is now lower than readState.Index' (duration: 122.791µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:09:06.380471Z","caller":"traceutil/trace.go:171","msg":"trace[484396994] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"188.146851ms","start":"2026-02-08T22:09:06.192317Z","end":"2026-02-08T22:09:06.380464Z","steps":["trace[484396994] 'process raft request' (duration: 187.880398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:06.380746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.657405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4012"} {"level":"info","ts":"2026-02-08T22:09:06.381199Z","caller":"traceutil/trace.go:171","msg":"trace[1899600126] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:397; }","duration":"147.12122ms","start":"2026-02-08T22:09:06.234067Z","end":"2026-02-08T22:09:06.381189Z","steps":["trace[1899600126] 'agreement among raft nodes before linearized reading' (duration: 146.605345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:06.382398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.004979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:09:06.382507Z","caller":"traceutil/trace.go:171","msg":"trace[1511064470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:397; }","duration":"147.126341ms","start":"2026-02-08T22:09:06.235373Z","end":"2026-02-08T22:09:06.3825Z","steps":["trace[1511064470] 'agreement among raft nodes before linearized reading' (duration: 147.006369ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:09:12.456974Z","caller":"traceutil/trace.go:171","msg":"trace[515960191] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"272.996482ms","start":"2026-02-08T22:09:12.183955Z","end":"2026-02-08T22:09:12.456952Z","steps":["trace[515960191] 'process raft request' (duration: 272.85925ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:09:12.457272Z","caller":"traceutil/trace.go:171","msg":"trace[989736320] linearizableReadLoop","detail":"{readStateIndex:461; appliedIndex:461; }","duration":"253.469292ms","start":"2026-02-08T22:09:12.203766Z","end":"2026-02-08T22:09:12.457236Z","steps":["trace[989736320] 'read index received' (duration: 253.458492ms)","trace[989736320] 'applied index is now lower than readState.Index' (duration: 8.88µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:09:12.457425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.653624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4256"} {"level":"info","ts":"2026-02-08T22:09:12.457457Z","caller":"traceutil/trace.go:171","msg":"trace[1786487739] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:448; }","duration":"253.725195ms","start":"2026-02-08T22:09:12.203724Z","end":"2026-02-08T22:09:12.457449Z","steps":["trace[1786487739] 'agreement among raft nodes before linearized reading' (duration: 253.658185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:12.459061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.08673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:09:12.465995Z","caller":"traceutil/trace.go:171","msg":"trace[534217803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:448; }","duration":"219.813528ms","start":"2026-02-08T22:09:12.23931Z","end":"2026-02-08T22:09:12.459124Z","steps":["trace[534217803] 'agreement among raft nodes before linearized reading' (duration: 218.084941ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:09:12.761282Z","caller":"traceutil/trace.go:171","msg":"trace[535182105] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"284.656865ms","start":"2026-02-08T22:09:12.476593Z","end":"2026-02-08T22:09:12.76125Z","steps":["trace[535182105] 'process raft request' (duration: 282.837897ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:09:12.765605Z","caller":"traceutil/trace.go:171","msg":"trace[1447342689] linearizableReadLoop","detail":"{readStateIndex:463; appliedIndex:461; }","duration":"189.814598ms","start":"2026-02-08T22:09:12.57578Z","end":"2026-02-08T22:09:12.765595Z","steps":["trace[1447342689] 'read index received' (duration: 183.795779ms)","trace[1447342689] 'applied index is now lower than readState.Index' (duration: 6.018299ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:09:12.765717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.929949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T22:09:12.765737Z","caller":"traceutil/trace.go:171","msg":"trace[557001043] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:450; }","duration":"190.011359ms","start":"2026-02-08T22:09:12.575718Z","end":"2026-02-08T22:09:12.765729Z","steps":["trace[557001043] 'agreement among raft nodes before linearized reading' (duration: 189.914128ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:09:12.765896Z","caller":"traceutil/trace.go:171","msg":"trace[233451883] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"288.991296ms","start":"2026-02-08T22:09:12.476897Z","end":"2026-02-08T22:09:12.765888Z","steps":["trace[233451883] 'process raft request' (duration: 288.641753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:27.962145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.616169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T22:09:27.962238Z","caller":"traceutil/trace.go:171","msg":"trace[1128965497] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"286.345428ms","start":"2026-02-08T22:09:27.675879Z","end":"2026-02-08T22:09:27.962225Z","steps":["trace[1128965497] 'process raft request' (duration: 99.193215ms)","trace[1128965497] 'compare' (duration: 186.317386ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:09:30.648509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.781937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:09:30.648794Z","caller":"traceutil/trace.go:171","msg":"trace[700698518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"411.09811ms","start":"2026-02-08T22:09:30.237665Z","end":"2026-02-08T22:09:30.648764Z","steps":["trace[700698518] 'range keys from in-memory index tree' (duration: 410.704336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:30.648848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:09:30.237642Z","time spent":"411.197711ms","remote":"127.0.0.1:58764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T22:09:30.650333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.360564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cnxm5\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-08T22:09:30.650448Z","caller":"traceutil/trace.go:171","msg":"trace[1711022136] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cnxm5; range_end:; response_count:1; response_revision:551; }","duration":"130.502405ms","start":"2026-02-08T22:09:30.519937Z","end":"2026-02-08T22:09:30.650439Z","steps":["trace[1711022136] 'range keys from in-memory index tree' (duration: 130.279853ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:09:31.198287Z","caller":"traceutil/trace.go:171","msg":"trace[349858454] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"536.683821ms","start":"2026-02-08T22:09:30.661567Z","end":"2026-02-08T22:09:31.198251Z","steps":["trace[349858454] 'process raft request' (duration: 536.49354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:31.198508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:09:30.661528Z","time spent":"536.872142ms","remote":"127.0.0.1:59064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:09:31.950625Z","caller":"traceutil/trace.go:171","msg":"trace[315033040] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:569; }","duration":"1.083442091s","start":"2026-02-08T22:09:30.86716Z","end":"2026-02-08T22:09:31.950602Z","steps":["trace[315033040] 'read index received' (duration: 331.887068ms)","trace[315033040] 'applied index is now lower than readState.Index' (duration: 751.554113ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:09:31.950942Z","caller":"traceutil/trace.go:171","msg":"trace[1848623940] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.285546232s","start":"2026-02-08T22:09:30.665385Z","end":"2026-02-08T22:09:31.950932Z","steps":["trace[1848623940] 'process raft request' (duration: 1.285052208s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:31.951164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:09:30.665351Z","time spent":"1.285735543s","remote":"127.0.0.1:58952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:09:31.951387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084227718s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T22:09:31.951925Z","caller":"traceutil/trace.go:171","msg":"trace[773711992] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:553; }","duration":"1.084253388s","start":"2026-02-08T22:09:30.867151Z","end":"2026-02-08T22:09:31.951405Z","steps":["trace[773711992] 'agreement among raft nodes before linearized reading' (duration: 1.084204288s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:31.951959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:09:30.867066Z","time spent":"1.084879654s","remote":"127.0.0.1:59064","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-08T22:09:31.952127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079115764s","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-08T22:09:31.952143Z","caller":"traceutil/trace.go:171","msg":"trace[663857896] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:553; }","duration":"1.079155005s","start":"2026-02-08T22:09:30.872983Z","end":"2026-02-08T22:09:31.952138Z","steps":["trace[663857896] 'agreement among raft nodes before linearized reading' (duration: 1.079121715s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:31.952157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:09:30.872974Z","time spent":"1.079180285s","remote":"127.0.0.1:59064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T22:09:31.953502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.590622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-08T22:09:31.953591Z","caller":"traceutil/trace.go:171","msg":"trace[834097412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:553; }","duration":"367.687003ms","start":"2026-02-08T22:09:31.585876Z","end":"2026-02-08T22:09:31.953563Z","steps":["trace[834097412] 'agreement among raft nodes before linearized reading' (duration: 367.527552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:31.953632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:09:31.585852Z","time spent":"367.773524ms","remote":"127.0.0.1:59064","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-08T22:09:31.954019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.85793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:09:31.954055Z","caller":"traceutil/trace.go:171","msg":"trace[1919985557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"717.9325ms","start":"2026-02-08T22:09:31.236114Z","end":"2026-02-08T22:09:31.954046Z","steps":["trace[1919985557] 'agreement among raft nodes before linearized reading' (duration: 717.88131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:31.954083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:09:31.236091Z","time spent":"717.984701ms","remote":"127.0.0.1:58760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T22:09:32.241379Z","caller":"traceutil/trace.go:171","msg":"trace[2117609330] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"270.401074ms","start":"2026-02-08T22:09:31.970838Z","end":"2026-02-08T22:09:32.241239Z","steps":["trace[2117609330] 'process raft request' (duration: 251.581237ms)","trace[2117609330] 'compare' (duration: 18.411844ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:09:32.315617Z","caller":"traceutil/trace.go:171","msg":"trace[2076143082] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"343.247714ms","start":"2026-02-08T22:09:31.972336Z","end":"2026-02-08T22:09:32.315584Z","steps":["trace[2076143082] 'process raft request' (duration: 343.032832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:32.315784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:09:31.972299Z","time spent":"343.393555ms","remote":"127.0.0.1:59064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:09:32.315937Z","caller":"traceutil/trace.go:171","msg":"trace[1665920971] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"341.964123ms","start":"2026-02-08T22:09:31.973951Z","end":"2026-02-08T22:09:32.315916Z","steps":["trace[1665920971] 'process raft request' (duration: 341.56288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:32.316071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:09:31.973944Z","time spent":"342.044264ms","remote":"127.0.0.1:59064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:09:33.88962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.647821ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-08T22:09:33.8897Z","caller":"traceutil/trace.go:171","msg":"trace[161891105] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"662.415072ms","start":"2026-02-08T22:09:33.227271Z","end":"2026-02-08T22:09:33.889686Z","steps":["trace[161891105] 'read index received' (duration: 46.33µs)","trace[161891105] 'applied index is now lower than readState.Index' (duration: 662.367372ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:09:33.889847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.570043ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:09:33.889946Z","caller":"traceutil/trace.go:171","msg":"trace[2084020348] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:556; }","duration":"585.680054ms","start":"2026-02-08T22:09:33.304249Z","end":"2026-02-08T22:09:33.889929Z","steps":["trace[2084020348] 'range keys from in-memory index tree' (duration: 585.552163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:33.890108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.820195ms","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-08T22:09:33.890128Z","caller":"traceutil/trace.go:171","msg":"trace[1970294035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"662.899546ms","start":"2026-02-08T22:09:33.227222Z","end":"2026-02-08T22:09:33.890122Z","steps":["trace[1970294035] 'agreement among raft nodes before linearized reading' (duration: 662.791905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:33.890146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:09:33.227198Z","time spent":"662.943766ms","remote":"127.0.0.1:59064","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-08T22:09:33.890263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.711281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:09:33.89029Z","caller":"traceutil/trace.go:171","msg":"trace[657128269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"652.759301ms","start":"2026-02-08T22:09:33.237521Z","end":"2026-02-08T22:09:33.89028Z","steps":["trace[657128269] 'agreement among raft nodes before linearized reading' (duration: 652.709541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:33.890311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:09:33.237511Z","time spent":"652.796242ms","remote":"127.0.0.1:58764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T22:09:34.895294Z","caller":"traceutil/trace.go:171","msg":"trace[1640497036] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"334.038494ms","start":"2026-02-08T22:09:34.561221Z","end":"2026-02-08T22:09:34.895259Z","steps":["trace[1640497036] 'process raft request' (duration: 333.871623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:34.895445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:09:34.561203Z","time spent":"334.172726ms","remote":"127.0.0.1:58834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":743,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-08T22:09:34.918037Z","caller":"traceutil/trace.go:171","msg":"trace[2007530712] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"340.132484ms","start":"2026-02-08T22:09:34.577882Z","end":"2026-02-08T22:09:34.918014Z","steps":["trace[2007530712] 'process raft request' (duration: 340.000683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:34.918171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:09:34.577867Z","time spent":"340.229336ms","remote":"127.0.0.1:58952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16897,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:09:36.136727Z","caller":"traceutil/trace.go:171","msg":"trace[232201600] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"232.937912ms","start":"2026-02-08T22:09:35.903757Z","end":"2026-02-08T22:09:36.136695Z","steps":["trace[232201600] 'process raft request' (duration: 232.77905ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:09:40.35397Z","caller":"traceutil/trace.go:171","msg":"trace[1705775368] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"146.297006ms","start":"2026-02-08T22:09:40.207647Z","end":"2026-02-08T22:09:40.353944Z","steps":["trace[1705775368] 'process raft request' (duration: 83.55542ms)","trace[1705775368] 'compare' (duration: 62.340252ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:09:40.586414Z","caller":"traceutil/trace.go:171","msg":"trace[713064165] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"126.492686ms","start":"2026-02-08T22:09:40.459904Z","end":"2026-02-08T22:09:40.586397Z","steps":["trace[713064165] 'process raft request' (duration: 123.815705ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:09:40.603685Z","caller":"traceutil/trace.go:171","msg":"trace[652453820] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:658; }","duration":"117.777876ms","start":"2026-02-08T22:09:40.485868Z","end":"2026-02-08T22:09:40.603646Z","steps":["trace[652453820] 'read index received' (duration: 87.549823ms)","trace[652453820] 'applied index is now lower than readState.Index' (duration: 30.227493ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:09:40.603835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.946487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-08T22:09:40.603852Z","caller":"traceutil/trace.go:171","msg":"trace[1643035300] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:641; }","duration":"117.998599ms","start":"2026-02-08T22:09:40.485848Z","end":"2026-02-08T22:09:40.603847Z","steps":["trace[1643035300] 'agreement among raft nodes before linearized reading' (duration: 117.879898ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:09:40.604148Z","caller":"traceutil/trace.go:171","msg":"trace[2114434542] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"118.512412ms","start":"2026-02-08T22:09:40.48563Z","end":"2026-02-08T22:09:40.604143Z","steps":["trace[2114434542] 'process raft request' (duration: 117.857837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:19.392472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.170814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-08T22:10:19.392583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.92744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:10:19.392594Z","caller":"traceutil/trace.go:171","msg":"trace[1507921473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:839; }","duration":"155.35168ms","start":"2026-02-08T22:10:19.237217Z","end":"2026-02-08T22:10:19.392569Z","steps":["trace[1507921473] 'range keys from in-memory index tree' (duration: 155.074981ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:19.392633Z","caller":"traceutil/trace.go:171","msg":"trace[696434918] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:839; }","duration":"128.028863ms","start":"2026-02-08T22:10:19.264593Z","end":"2026-02-08T22:10:19.392622Z","steps":["trace[696434918] 'count revisions from in-memory index tree' (duration: 127.882129ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:23.288342Z","caller":"traceutil/trace.go:171","msg":"trace[786068807] linearizableReadLoop","detail":"{readStateIndex:878; appliedIndex:877; }","duration":"258.661082ms","start":"2026-02-08T22:10:23.02966Z","end":"2026-02-08T22:10:23.288321Z","steps":["trace[786068807] 'read index received' (duration: 257.209678ms)","trace[786068807] 'applied index is now lower than readState.Index' (duration: 1.450704ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:10:23.288492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.827428ms","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-08T22:10:23.288514Z","caller":"traceutil/trace.go:171","msg":"trace[1651786987] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:849; }","duration":"258.89665ms","start":"2026-02-08T22:10:23.029613Z","end":"2026-02-08T22:10:23.288509Z","steps":["trace[1651786987] 'agreement among raft nodes before linearized reading' (duration: 258.791567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:24.702752Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.411013939s","expected-duration":"1s"} {"level":"info","ts":"2026-02-08T22:10:24.703105Z","caller":"traceutil/trace.go:171","msg":"trace[657277926] linearizableReadLoop","detail":"{readStateIndex:879; appliedIndex:878; }","duration":"212.678266ms","start":"2026-02-08T22:10:24.490406Z","end":"2026-02-08T22:10:24.703084Z","steps":["trace[657277926] 'read index received' (duration: 212.46821ms)","trace[657277926] 'applied index is now lower than readState.Index' (duration: 209.236µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:10:24.703406Z","caller":"traceutil/trace.go:171","msg":"trace[1715409695] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"1.411697139s","start":"2026-02-08T22:10:23.291691Z","end":"2026-02-08T22:10:24.703388Z","steps":["trace[1715409695] 'process raft request' (duration: 1.411235305s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:24.703501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:23.291673Z","time spent":"1.411761911s","remote":"127.0.0.1:59064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:10:24.703759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.347346ms","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-08T22:10:24.703794Z","caller":"traceutil/trace.go:171","msg":"trace[527463003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:850; }","duration":"213.383807ms","start":"2026-02-08T22:10:24.490401Z","end":"2026-02-08T22:10:24.703784Z","steps":["trace[527463003] 'agreement among raft nodes before linearized reading' (duration: 213.267874ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:25.077729Z","caller":"traceutil/trace.go:171","msg":"trace[581160363] linearizableReadLoop","detail":"{readStateIndex:881; appliedIndex:879; }","duration":"354.884946ms","start":"2026-02-08T22:10:24.722829Z","end":"2026-02-08T22:10:25.077714Z","steps":["trace[581160363] 'read index received' (duration: 316.545821ms)","trace[581160363] 'applied index is now lower than readState.Index' (duration: 38.338545ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:10:25.077866Z","caller":"traceutil/trace.go:171","msg":"trace[634559406] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"365.937899ms","start":"2026-02-08T22:10:24.71192Z","end":"2026-02-08T22:10:25.077858Z","steps":["trace[634559406] 'process raft request' (duration: 327.443729ms)","trace[634559406] 'compare' (duration: 38.218842ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:10:25.077929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:24.711906Z","time spent":"365.97472ms","remote":"127.0.0.1:59064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:10:25.078031Z","caller":"traceutil/trace.go:171","msg":"trace[498996777] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"366.051762ms","start":"2026-02-08T22:10:24.711975Z","end":"2026-02-08T22:10:25.078027Z","steps":["trace[498996777] 'process raft request' (duration: 365.692831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:25.078059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:24.711958Z","time spent":"366.085014ms","remote":"127.0.0.1:59064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:10:25.078169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.336579ms","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-08T22:10:25.078186Z","caller":"traceutil/trace.go:171","msg":"trace[1321279887] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:852; }","duration":"355.36739ms","start":"2026-02-08T22:10:24.722812Z","end":"2026-02-08T22:10:25.078179Z","steps":["trace[1321279887] 'agreement among raft nodes before linearized reading' (duration: 355.302698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:25.078199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:24.722804Z","time spent":"355.392321ms","remote":"127.0.0.1:59064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T22:10:25.078295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.054001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-08T22:10:25.07831Z","caller":"traceutil/trace.go:171","msg":"trace[1108323017] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:852; }","duration":"355.077782ms","start":"2026-02-08T22:10:24.723227Z","end":"2026-02-08T22:10:25.078305Z","steps":["trace[1108323017] 'agreement among raft nodes before linearized reading' (duration: 355.0358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:25.078326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:24.723223Z","time spent":"355.099652ms","remote":"127.0.0.1:59064","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-08T22:10:55.439556Z","caller":"traceutil/trace.go:171","msg":"trace[1064582641] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1004; }","duration":"122.823804ms","start":"2026-02-08T22:10:55.316712Z","end":"2026-02-08T22:10:55.439536Z","steps":["trace[1064582641] 'read index received' (duration: 122.684161ms)","trace[1064582641] 'applied index is now lower than readState.Index' (duration: 138.493µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:10:55.439681Z","caller":"traceutil/trace.go:171","msg":"trace[1035437855] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"147.366014ms","start":"2026-02-08T22:10:55.292305Z","end":"2026-02-08T22:10:55.439671Z","steps":["trace[1035437855] 'process raft request' (duration: 147.106148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:55.440346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.625003ms","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-08T22:10:55.440393Z","caller":"traceutil/trace.go:171","msg":"trace[188021082] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:968; }","duration":"123.728826ms","start":"2026-02-08T22:10:55.316653Z","end":"2026-02-08T22:10:55.440382Z","steps":["trace[188021082] 'agreement among raft nodes before linearized reading' (duration: 123.46347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:55.441023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.145492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-08T22:10:55.441191Z","caller":"traceutil/trace.go:171","msg":"trace[1399447606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:968; }","duration":"116.209004ms","start":"2026-02-08T22:10:55.324846Z","end":"2026-02-08T22:10:55.441055Z","steps":["trace[1399447606] 'agreement among raft nodes before linearized reading' (duration: 116.076731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:55.440113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.745076ms","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-08T22:10:55.443371Z","caller":"traceutil/trace.go:171","msg":"trace[790851599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:968; }","duration":"119.99558ms","start":"2026-02-08T22:10:55.323323Z","end":"2026-02-08T22:10:55.443318Z","steps":["trace[790851599] 'agreement among raft nodes before linearized reading' (duration: 116.664864ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:57.172493Z","caller":"traceutil/trace.go:171","msg":"trace[1048055772] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"181.96788ms","start":"2026-02-08T22:10:56.990502Z","end":"2026-02-08T22:10:57.17247Z","steps":["trace[1048055772] 'process raft request' (duration: 181.710805ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:57.736783Z","caller":"traceutil/trace.go:171","msg":"trace[1767120614] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1010; }","duration":"280.375326ms","start":"2026-02-08T22:10:57.45639Z","end":"2026-02-08T22:10:57.736766Z","steps":["trace[1767120614] 'read index received' (duration: 280.371476ms)","trace[1767120614] 'applied index is now lower than readState.Index' (duration: 3µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:10:57.736992Z","caller":"traceutil/trace.go:171","msg":"trace[1946508132] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"289.579353ms","start":"2026-02-08T22:10:57.447405Z","end":"2026-02-08T22:10:57.736984Z","steps":["trace[1946508132] 'process raft request' (duration: 289.269476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:57.737174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.769795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-08T22:10:57.737195Z","caller":"traceutil/trace.go:171","msg":"trace[2070149154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:973; }","duration":"280.817495ms","start":"2026-02-08T22:10:57.456371Z","end":"2026-02-08T22:10:57.737188Z","steps":["trace[2070149154] 'agreement among raft nodes before linearized reading' (duration: 280.730013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:57.737298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.195062ms","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-08T22:10:57.737314Z","caller":"traceutil/trace.go:171","msg":"trace[1959781129] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:973; }","duration":"276.221913ms","start":"2026-02-08T22:10:57.461087Z","end":"2026-02-08T22:10:57.737309Z","steps":["trace[1959781129] 'agreement among raft nodes before linearized reading' (duration: 276.177652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:57.737539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.31683ms","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-08T22:10:57.73757Z","caller":"traceutil/trace.go:171","msg":"trace[459625808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:973; }","duration":"274.34584ms","start":"2026-02-08T22:10:57.463207Z","end":"2026-02-08T22:10:57.737553Z","steps":["trace[459625808] 'agreement among raft nodes before linearized reading' (duration: 274.300499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:11:03.350992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.467658ms","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-08T22:11:03.351038Z","caller":"traceutil/trace.go:171","msg":"trace[1012437151] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:996; }","duration":"151.55951ms","start":"2026-02-08T22:11:03.199468Z","end":"2026-02-08T22:11:03.351027Z","steps":["trace[1012437151] 'range keys from in-memory index tree' (duration: 151.337466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:11:03.351147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.551095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:11:03.35116Z","caller":"traceutil/trace.go:171","msg":"trace[26511728] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:996; }","duration":"113.604076ms","start":"2026-02-08T22:11:03.237552Z","end":"2026-02-08T22:11:03.351156Z","steps":["trace[26511728] 'range keys from in-memory index tree' (duration: 113.488464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:11:04.280909Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707762580555302099,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-08T22:11:04.388212Z","caller":"traceutil/trace.go:171","msg":"trace[1762702279] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"608.29748ms","start":"2026-02-08T22:11:03.779895Z","end":"2026-02-08T22:11:04.388193Z","steps":["trace[1762702279] 'process raft request' (duration: 608.117596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:11:04.388716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:11:03.779885Z","time spent":"608.75389ms","remote":"127.0.0.1:59064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:11:04.518468Z","caller":"traceutil/trace.go:171","msg":"trace[1995364419] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1037; }","duration":"737.95244ms","start":"2026-02-08T22:11:03.7805Z","end":"2026-02-08T22:11:04.518453Z","steps":["trace[1995364419] 'read index received' (duration: 607.860531ms)","trace[1995364419] 'applied index is now lower than readState.Index' (duration: 130.091459ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:11:04.518645Z","caller":"traceutil/trace.go:171","msg":"trace[1191463219] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"738.672835ms","start":"2026-02-08T22:11:03.779966Z","end":"2026-02-08T22:11:04.518639Z","steps":["trace[1191463219] 'process raft request' (duration: 738.381069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:11:04.518706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:11:03.779959Z","time spent":"738.703365ms","remote":"127.0.0.1:59064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:11:04.518796Z","caller":"traceutil/trace.go:171","msg":"trace[838361176] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"737.222644ms","start":"2026-02-08T22:11:03.781569Z","end":"2026-02-08T22:11:04.518791Z","steps":["trace[838361176] 'process raft request' (duration: 736.861956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:11:04.518822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:11:03.781562Z","time spent":"737.247154ms","remote":"127.0.0.1:59064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:11:04.518916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.41376ms","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-08T22:11:04.518934Z","caller":"traceutil/trace.go:171","msg":"trace[2132842659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1000; }","duration":"738.44284ms","start":"2026-02-08T22:11:03.780487Z","end":"2026-02-08T22:11:04.51893Z","steps":["trace[2132842659] 'agreement among raft nodes before linearized reading' (duration: 738.381789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:11:04.518947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:11:03.780482Z","time spent":"738.4629ms","remote":"127.0.0.1:59064","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-08T22:11:04.519106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.387625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-08T22:11:04.519125Z","caller":"traceutil/trace.go:171","msg":"trace[138285593] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1000; }","duration":"395.441935ms","start":"2026-02-08T22:11:04.123678Z","end":"2026-02-08T22:11:04.51912Z","steps":["trace[138285593] 'agreement among raft nodes before linearized reading' (duration: 395.373614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:11:04.519138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:11:04.12366Z","time spent":"395.474566ms","remote":"127.0.0.1:59340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":121,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-08T22:11:04.519218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.713217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:11:04.519233Z","caller":"traceutil/trace.go:171","msg":"trace[397185771] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1000; }","duration":"149.749517ms","start":"2026-02-08T22:11:04.36948Z","end":"2026-02-08T22:11:04.51923Z","steps":["trace[397185771] 'agreement among raft nodes before linearized reading' (duration: 149.726197ms)"],"step_count":1}