{"level":"warn","ts":"2026-01-29T18:40:36.916186Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T18:40:36.916358Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162: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.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-01-29T18:40:36.916475Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T18:40:36.916486Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-01-29T18:40:36.916528Z","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-01-29T18:40:36.917206Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-01-29T18:40:36.91728Z","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.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-01-29T18:40:36.925038Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.618219ms"} {"level":"info","ts":"2026-01-29T18:40:36.953545Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-01-29T18:40:36.953735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-01-29T18:40:36.953804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-01-29T18:40:36.953842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-29T18:40:36.953886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-01-29T18:40:36.953946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-01-29T18:40:36.968728Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-29T18:40:36.979537Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-29T18:40:36.987239Z","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-01-29T18:40:36.992058Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-29T18:40:36.996149Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-29T18:40:36.996286Z","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-01-29T18:40:36.996332Z","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-01-29T18:40:36.99634Z","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-01-29T18:40:36.996581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-01-29T18:40:36.99694Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-01-29T18:40:36.999867Z","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-01-29T18:40:37.000673Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-01-29T18:40:37.000688Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-01-29T18:40:37.0107Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-29T18:40:37.010662Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-29T18:40:37.255168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-01-29T18:40:37.25524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-01-29T18:40:37.255277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-01-29T18:40:37.255356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-01-29T18:40:37.255369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-01-29T18:40:37.255425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-01-29T18:40:37.255492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-01-29T18:40:37.25978Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-01-29T18:40:37.259818Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T18:40:37.260141Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T18:40:37.260841Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T18:40:37.261211Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-29T18:40:37.261289Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-29T18:40:37.263231Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-01-29T18:40:37.267451Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T18:40:37.267783Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T18:40:37.26793Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T18:40:37.275783Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-29T18:40:45.845399Z","caller":"traceutil/trace.go:171","msg":"trace[72669337] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"118.665264ms","start":"2026-01-29T18:40:45.726718Z","end":"2026-01-29T18:40:45.845383Z","steps":["trace[72669337] 'process raft request' (duration: 118.575283ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:40:46.119551Z","caller":"traceutil/trace.go:171","msg":"trace[880976927] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"227.570361ms","start":"2026-01-29T18:40:45.891957Z","end":"2026-01-29T18:40:46.119527Z","steps":["trace[880976927] 'process raft request' (duration: 227.41329ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:40:48.483825Z","caller":"traceutil/trace.go:171","msg":"trace[1644466016] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"124.186508ms","start":"2026-01-29T18:40:48.359612Z","end":"2026-01-29T18:40:48.483799Z","steps":["trace[1644466016] 'process raft request' (duration: 88.231375ms)","trace[1644466016] 'compare' (duration: 35.821863ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:40:48.685885Z","caller":"traceutil/trace.go:171","msg":"trace[1693231712] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"325.857938ms","start":"2026-01-29T18:40:48.36001Z","end":"2026-01-29T18:40:48.685868Z","steps":["trace[1693231712] 'process raft request' (duration: 324.013806ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:40:48.685975Z","caller":"traceutil/trace.go:171","msg":"trace[1450109809] linearizableReadLoop","detail":"{readStateIndex:404; appliedIndex:400; }","duration":"255.965817ms","start":"2026-01-29T18:40:48.429994Z","end":"2026-01-29T18:40:48.68596Z","steps":["trace[1450109809] 'read index received' (duration: 17.85526ms)","trace[1450109809] 'applied index is now lower than readState.Index' (duration: 238.109817ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:40:48.686205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:40:48.359981Z","time spent":"325.947798ms","remote":"127.0.0.1:36998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-29T18:40:48.68622Z","caller":"traceutil/trace.go:171","msg":"trace[216966397] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"325.665666ms","start":"2026-01-29T18:40:48.360537Z","end":"2026-01-29T18:40:48.686202Z","steps":["trace[216966397] 'process raft request' (duration: 325.274884ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:40:48.686319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:40:48.36042Z","time spent":"325.869158ms","remote":"127.0.0.1:37104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3505,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-29T18:40:48.68645Z","caller":"traceutil/trace.go:171","msg":"trace[939365867] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"325.813588ms","start":"2026-01-29T18:40:48.36063Z","end":"2026-01-29T18:40:48.686443Z","steps":["trace[939365867] 'process raft request' (duration: 325.251834ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:40:48.686538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:40:48.360622Z","time spent":"325.839688ms","remote":"127.0.0.1:37104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3979,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T18:40:48.686589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.600711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-01-29T18:40:48.686608Z","caller":"traceutil/trace.go:171","msg":"trace[1884015] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:392; }","duration":"256.670342ms","start":"2026-01-29T18:40:48.429928Z","end":"2026-01-29T18:40:48.686599Z","steps":["trace[1884015] 'agreement among raft nodes before linearized reading' (duration: 256.642942ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:40:48.902503Z","caller":"traceutil/trace.go:171","msg":"trace[1021923108] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"129.187063ms","start":"2026-01-29T18:40:48.773294Z","end":"2026-01-29T18:40:48.902482Z","steps":["trace[1021923108] 'process raft request' (duration: 88.486348ms)","trace[1021923108] 'compare' (duration: 40.622494ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:40:49.418576Z","caller":"traceutil/trace.go:171","msg":"trace[619717138] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:413; }","duration":"140.065063ms","start":"2026-01-29T18:40:49.278491Z","end":"2026-01-29T18:40:49.418556Z","steps":["trace[619717138] 'read index received' (duration: 139.939622ms)","trace[619717138] 'applied index is now lower than readState.Index' (duration: 122.871µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:40:49.418607Z","caller":"traceutil/trace.go:171","msg":"trace[449863518] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"170.552727ms","start":"2026-01-29T18:40:49.248026Z","end":"2026-01-29T18:40:49.418579Z","steps":["trace[449863518] 'process raft request' (duration: 170.419886ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:40:49.41873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.210184ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:40:49.418786Z","caller":"traceutil/trace.go:171","msg":"trace[1506144229] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:402; }","duration":"140.294975ms","start":"2026-01-29T18:40:49.278483Z","end":"2026-01-29T18:40:49.418778Z","steps":["trace[1506144229] 'agreement among raft nodes before linearized reading' (duration: 140.184404ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:40:49.62448Z","caller":"traceutil/trace.go:171","msg":"trace[167807727] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"194.207248ms","start":"2026-01-29T18:40:49.430255Z","end":"2026-01-29T18:40:49.624462Z","steps":["trace[167807727] 'process raft request' (duration: 194.146657ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:40:49.624814Z","caller":"traceutil/trace.go:171","msg":"trace[2109826892] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"194.630201ms","start":"2026-01-29T18:40:49.430176Z","end":"2026-01-29T18:40:49.624806Z","steps":["trace[2109826892] 'process raft request' (duration: 141.357792ms)","trace[2109826892] 'compare' (duration: 52.776485ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:40:50.205943Z","caller":"traceutil/trace.go:171","msg":"trace[1746092447] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:417; }","duration":"143.677957ms","start":"2026-01-29T18:40:50.062248Z","end":"2026-01-29T18:40:50.205926Z","steps":["trace[1746092447] 'read index received' (duration: 143.549986ms)","trace[1746092447] 'applied index is now lower than readState.Index' (duration: 127.241µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:40:50.206055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.793627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4069"} {"level":"info","ts":"2026-01-29T18:40:50.206082Z","caller":"traceutil/trace.go:171","msg":"trace[1766980954] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:406; }","duration":"143.834498ms","start":"2026-01-29T18:40:50.062239Z","end":"2026-01-29T18:40:50.206073Z","steps":["trace[1766980954] 'agreement among raft nodes before linearized reading' (duration: 143.752677ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:40:50.206322Z","caller":"traceutil/trace.go:171","msg":"trace[712377951] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"238.901747ms","start":"2026-01-29T18:40:49.967052Z","end":"2026-01-29T18:40:50.205953Z","steps":["trace[712377951] 'process raft request' (duration: 238.782186ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:40:50.720127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.032823ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-01-29T18:40:50.720248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:40:50.255035Z","time spent":"465.206887ms","remote":"127.0.0.1:37430","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-01-29T18:40:50.720328Z","caller":"traceutil/trace.go:171","msg":"trace[1173774277] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"226.029449ms","start":"2026-01-29T18:40:50.494282Z","end":"2026-01-29T18:40:50.720312Z","steps":["trace[1173774277] 'process raft request' (duration: 225.941618ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:40:50.720619Z","caller":"traceutil/trace.go:171","msg":"trace[844447196] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:418; }","duration":"370.162979ms","start":"2026-01-29T18:40:50.350445Z","end":"2026-01-29T18:40:50.720608Z","steps":["trace[844447196] 'read index received' (duration: 86.436782ms)","trace[844447196] 'applied index is now lower than readState.Index' (duration: 283.724797ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:40:50.72081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.675204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5270"} {"level":"info","ts":"2026-01-29T18:40:50.720854Z","caller":"traceutil/trace.go:171","msg":"trace[1826627832] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:407; }","duration":"223.750784ms","start":"2026-01-29T18:40:50.497088Z","end":"2026-01-29T18:40:50.720839Z","steps":["trace[1826627832] 'agreement among raft nodes before linearized reading' (duration: 223.616303ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:40:50.720869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.42266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-29T18:40:50.720903Z","caller":"traceutil/trace.go:171","msg":"trace[316162016] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:407; }","duration":"370.500931ms","start":"2026-01-29T18:40:50.350393Z","end":"2026-01-29T18:40:50.720894Z","steps":["trace[316162016] 'agreement among raft nodes before linearized reading' (duration: 370.41129ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:40:50.720934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:40:50.350374Z","time spent":"370.552711ms","remote":"127.0.0.1:36964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.162\" "} {"level":"warn","ts":"2026-01-29T18:40:51.327059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.282677ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-01-29T18:40:51.327325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:40:50.733814Z","time spent":"593.505596ms","remote":"127.0.0.1:36964","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-01-29T18:40:51.327376Z","caller":"traceutil/trace.go:171","msg":"trace[1828236981] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:421; }","duration":"583.933982ms","start":"2026-01-29T18:40:50.743425Z","end":"2026-01-29T18:40:51.327359Z","steps":["trace[1828236981] 'read index received' (duration: 24.306573ms)","trace[1828236981] 'applied index is now lower than readState.Index' (duration: 559.626339ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:40:51.327686Z","caller":"traceutil/trace.go:171","msg":"trace[1306509583] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"593.763479ms","start":"2026-01-29T18:40:50.733912Z","end":"2026-01-29T18:40:51.327675Z","steps":["trace[1306509583] 'process raft request' (duration: 593.308136ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:40:51.327752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.905432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-29T18:40:51.32777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:40:50.733904Z","time spent":"593.810429ms","remote":"127.0.0.1:37104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5083,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T18:40:51.327794Z","caller":"traceutil/trace.go:171","msg":"trace[1660362305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:409; }","duration":"470.976322ms","start":"2026-01-29T18:40:50.856803Z","end":"2026-01-29T18:40:51.327779Z","steps":["trace[1660362305] 'agreement among raft nodes before linearized reading' (duration: 470.898842ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:40:51.327829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:40:50.85679Z","time spent":"471.032712ms","remote":"127.0.0.1:36938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T18:40:51.328021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.594767ms","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-01-29T18:40:51.328051Z","caller":"traceutil/trace.go:171","msg":"trace[810167810] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:409; }","duration":"584.640747ms","start":"2026-01-29T18:40:50.743401Z","end":"2026-01-29T18:40:51.328042Z","steps":["trace[810167810] 'agreement among raft nodes before linearized reading' (duration: 584.573916ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:40:51.328078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:40:50.743392Z","time spent":"584.679957ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-29T18:40:51.328099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.280491ms","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-01-29T18:40:51.328139Z","caller":"traceutil/trace.go:171","msg":"trace[877628024] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:409; }","duration":"350.362061ms","start":"2026-01-29T18:40:50.977766Z","end":"2026-01-29T18:40:51.328128Z","steps":["trace[877628024] 'agreement among raft nodes before linearized reading' (duration: 350.295291ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:40:51.328168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:40:50.977736Z","time spent":"350.426242ms","remote":"127.0.0.1:37186","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-01-29T18:40:51.554251Z","caller":"traceutil/trace.go:171","msg":"trace[371363407] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"150.973123ms","start":"2026-01-29T18:40:51.403248Z","end":"2026-01-29T18:40:51.554221Z","steps":["trace[371363407] 'process raft request' (duration: 97.736116ms)","trace[371363407] 'compare' (duration: 52.977566ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:40:51.704604Z","caller":"traceutil/trace.go:171","msg":"trace[1266274248] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"136.99368ms","start":"2026-01-29T18:40:51.567568Z","end":"2026-01-29T18:40:51.704561Z","steps":["trace[1266274248] 'process raft request' (duration: 96.638669ms)","trace[1266274248] 'compare' (duration: 39.786898ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:40:51.705914Z","caller":"traceutil/trace.go:171","msg":"trace[676661210] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"136.601667ms","start":"2026-01-29T18:40:51.569294Z","end":"2026-01-29T18:40:51.705895Z","steps":["trace[676661210] 'process raft request' (duration: 134.854926ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:40:52.357493Z","caller":"traceutil/trace.go:171","msg":"trace[1842932253] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"141.972031ms","start":"2026-01-29T18:40:52.215495Z","end":"2026-01-29T18:40:52.357467Z","steps":["trace[1842932253] 'process raft request' (duration: 43.945224ms)","trace[1842932253] 'compare' (duration: 97.902156ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:40:52.589778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.234755ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T18:40:52.589872Z","caller":"traceutil/trace.go:171","msg":"trace[1815587750] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"130.783376ms","start":"2026-01-29T18:40:52.459072Z","end":"2026-01-29T18:40:52.589856Z","steps":["trace[1815587750] 'process raft request' (duration: 28.40038ms)","trace[1815587750] 'compare' (duration: 102.118155ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:40:55.924379Z","caller":"traceutil/trace.go:171","msg":"trace[1655336999] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"520.872566ms","start":"2026-01-29T18:40:55.40349Z","end":"2026-01-29T18:40:55.924363Z","steps":["trace[1655336999] 'process raft request' (duration: 520.791565ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:40:55.924469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:40:55.403473Z","time spent":"520.948676ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T18:40:55.927305Z","caller":"traceutil/trace.go:171","msg":"trace[350821299] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"523.656964ms","start":"2026-01-29T18:40:55.403641Z","end":"2026-01-29T18:40:55.927298Z","steps":["trace[350821299] 'process raft request' (duration: 523.476333ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:40:55.927354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:40:55.403633Z","time spent":"523.695254ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T18:40:55.929099Z","caller":"traceutil/trace.go:171","msg":"trace[941062736] linearizableReadLoop","detail":"{readStateIndex:442; appliedIndex:441; }","duration":"126.043001ms","start":"2026-01-29T18:40:55.803047Z","end":"2026-01-29T18:40:55.92909Z","steps":["trace[941062736] 'read index received' (duration: 121.665562ms)","trace[941062736] 'applied index is now lower than readState.Index' (duration: 4.376989ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:40:55.92923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.175952ms","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-01-29T18:40:55.929251Z","caller":"traceutil/trace.go:171","msg":"trace[713656937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:428; }","duration":"126.225002ms","start":"2026-01-29T18:40:55.80302Z","end":"2026-01-29T18:40:55.929245Z","steps":["trace[713656937] 'agreement among raft nodes before linearized reading' (duration: 126.180312ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:40:56.473504Z","caller":"traceutil/trace.go:171","msg":"trace[1369974660] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"147.948996ms","start":"2026-01-29T18:40:56.325529Z","end":"2026-01-29T18:40:56.473478Z","steps":["trace[1369974660] 'process raft request' (duration: 147.821115ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:40:58.03093Z","caller":"traceutil/trace.go:171","msg":"trace[352250796] linearizableReadLoop","detail":"{readStateIndex:447; appliedIndex:446; }","duration":"103.096566ms","start":"2026-01-29T18:40:57.927815Z","end":"2026-01-29T18:40:58.030911Z","steps":["trace[352250796] 'read index received' (duration: 102.918604ms)","trace[352250796] 'applied index is now lower than readState.Index' (duration: 177.072µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:40:58.031212Z","caller":"traceutil/trace.go:171","msg":"trace[505330458] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"149.822537ms","start":"2026-01-29T18:40:57.881204Z","end":"2026-01-29T18:40:58.031026Z","steps":["trace[505330458] 'process raft request' (duration: 149.594325ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:40:58.031556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.74443ms","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-01-29T18:40:58.031768Z","caller":"traceutil/trace.go:171","msg":"trace[280275031] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:432; }","duration":"100.933771ms","start":"2026-01-29T18:40:57.930767Z","end":"2026-01-29T18:40:58.031701Z","steps":["trace[280275031] 'agreement among raft nodes before linearized reading' (duration: 100.71977ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:40:58.032047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.185013ms","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-01-29T18:40:58.032096Z","caller":"traceutil/trace.go:171","msg":"trace[440316799] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:432; }","duration":"104.309123ms","start":"2026-01-29T18:40:57.927774Z","end":"2026-01-29T18:40:58.032083Z","steps":["trace[440316799] 'agreement among raft nodes before linearized reading' (duration: 104.140752ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:40:58.187631Z","caller":"traceutil/trace.go:171","msg":"trace[641013812] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"145.99695ms","start":"2026-01-29T18:40:58.041621Z","end":"2026-01-29T18:40:58.187618Z","steps":["trace[641013812] 'process raft request' (duration: 145.407686ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:40:58.372616Z","caller":"traceutil/trace.go:171","msg":"trace[99560811] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"177.001206ms","start":"2026-01-29T18:40:58.1956Z","end":"2026-01-29T18:40:58.372601Z","steps":["trace[99560811] 'process raft request' (duration: 176.975516ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:40:58.37281Z","caller":"traceutil/trace.go:171","msg":"trace[2022354390] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"330.012494ms","start":"2026-01-29T18:40:58.042791Z","end":"2026-01-29T18:40:58.372804Z","steps":["trace[2022354390] 'process raft request' (duration: 279.266395ms)","trace[2022354390] 'compare' (duration: 50.317485ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:40:58.372881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:40:58.042772Z","time spent":"330.066685ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T18:40:58.372956Z","caller":"traceutil/trace.go:171","msg":"trace[1125333801] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"326.838383ms","start":"2026-01-29T18:40:58.046112Z","end":"2026-01-29T18:40:58.372951Z","steps":["trace[1125333801] 'process raft request' (duration: 326.343449ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:40:58.372985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:40:58.046097Z","time spent":"326.873844ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T18:40:58.373036Z","caller":"traceutil/trace.go:171","msg":"trace[254019043] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"325.771246ms","start":"2026-01-29T18:40:58.047261Z","end":"2026-01-29T18:40:58.373032Z","steps":["trace[254019043] 'process raft request' (duration: 325.228022ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:40:58.37307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:40:58.04725Z","time spent":"325.799396ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T18:40:58.373122Z","caller":"traceutil/trace.go:171","msg":"trace[402949800] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"324.216675ms","start":"2026-01-29T18:40:58.048902Z","end":"2026-01-29T18:40:58.373119Z","steps":["trace[402949800] 'process raft request' (duration: 323.604101ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:40:58.373148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:40:58.048895Z","time spent":"324.241196ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T18:40:58.373202Z","caller":"traceutil/trace.go:171","msg":"trace[1770955435] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"179.716274ms","start":"2026-01-29T18:40:58.193481Z","end":"2026-01-29T18:40:58.373198Z","steps":["trace[1770955435] 'process raft request' (duration: 179.04628ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:40:58.373297Z","caller":"traceutil/trace.go:171","msg":"trace[1632604844] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"179.769134ms","start":"2026-01-29T18:40:58.193523Z","end":"2026-01-29T18:40:58.373292Z","steps":["trace[1632604844] 'process raft request' (duration: 179.02846ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:40:58.555087Z","caller":"traceutil/trace.go:171","msg":"trace[444869264] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"171.687581ms","start":"2026-01-29T18:40:58.383378Z","end":"2026-01-29T18:40:58.555066Z","steps":["trace[444869264] 'process raft request' (duration: 112.196305ms)","trace[444869264] 'compare' (duration: 58.819071ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:40:58.555227Z","caller":"traceutil/trace.go:171","msg":"trace[847730434] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"167.975857ms","start":"2026-01-29T18:40:58.387241Z","end":"2026-01-29T18:40:58.555216Z","steps":["trace[847730434] 'process raft request' (duration: 167.473084ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:40:58.555269Z","caller":"traceutil/trace.go:171","msg":"trace[816381485] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"167.946716ms","start":"2026-01-29T18:40:58.387312Z","end":"2026-01-29T18:40:58.555259Z","steps":["trace[816381485] 'process raft request' (duration: 167.443343ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:40:58.555427Z","caller":"traceutil/trace.go:171","msg":"trace[1006831907] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"168.226798ms","start":"2026-01-29T18:40:58.387193Z","end":"2026-01-29T18:40:58.55542Z","steps":["trace[1006831907] 'process raft request' (duration: 167.474694ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:40:58.760696Z","caller":"traceutil/trace.go:171","msg":"trace[1047929933] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"197.309272ms","start":"2026-01-29T18:40:58.563366Z","end":"2026-01-29T18:40:58.760675Z","steps":["trace[1047929933] 'process raft request' (duration: 158.045711ms)","trace[1047929933] 'compare' (duration: 39.11789ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:40:58.760913Z","caller":"traceutil/trace.go:171","msg":"trace[658343199] transaction","detail":"{read_only:false; number_of_response:1; response_revision:448; }","duration":"130.82872ms","start":"2026-01-29T18:40:58.630063Z","end":"2026-01-29T18:40:58.760892Z","steps":["trace[658343199] 'process raft request' (duration: 130.75011ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:40:58.761052Z","caller":"traceutil/trace.go:171","msg":"trace[695405775] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"194.351842ms","start":"2026-01-29T18:40:58.566691Z","end":"2026-01-29T18:40:58.761043Z","steps":["trace[695405775] 'process raft request' (duration: 193.94328ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:40:58.986338Z","caller":"traceutil/trace.go:171","msg":"trace[1681718704] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"144.106889ms","start":"2026-01-29T18:40:58.842205Z","end":"2026-01-29T18:40:58.986312Z","steps":["trace[1681718704] 'process raft request' (duration: 51.12399ms)","trace[1681718704] 'compare' (duration: 92.876848ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:40:58.988988Z","caller":"traceutil/trace.go:171","msg":"trace[643186614] linearizableReadLoop","detail":"{readStateIndex:473; appliedIndex:468; }","duration":"136.89622ms","start":"2026-01-29T18:40:58.852075Z","end":"2026-01-29T18:40:58.988972Z","steps":["trace[643186614] 'read index received' (duration: 41.242334ms)","trace[643186614] 'applied index is now lower than readState.Index' (duration: 95.653256ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:40:58.989089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.997711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:40:58.989118Z","caller":"traceutil/trace.go:171","msg":"trace[630883876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:456; }","duration":"137.053951ms","start":"2026-01-29T18:40:58.852056Z","end":"2026-01-29T18:40:58.98911Z","steps":["trace[630883876] 'agreement among raft nodes before linearized reading' (duration: 136.978551ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:40:58.989248Z","caller":"traceutil/trace.go:171","msg":"trace[246063664] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"146.623815ms","start":"2026-01-29T18:40:58.842617Z","end":"2026-01-29T18:40:58.989241Z","steps":["trace[246063664] 'process raft request' (duration: 146.199702ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:40:58.989383Z","caller":"traceutil/trace.go:171","msg":"trace[1381092048] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"146.698236ms","start":"2026-01-29T18:40:58.842678Z","end":"2026-01-29T18:40:58.989377Z","steps":["trace[1381092048] 'process raft request' (duration: 146.219923ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:40:58.989456Z","caller":"traceutil/trace.go:171","msg":"trace[1651392198] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"143.820196ms","start":"2026-01-29T18:40:58.845631Z","end":"2026-01-29T18:40:58.989451Z","steps":["trace[1651392198] 'process raft request' (duration: 143.288803ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:40:58.9896Z","caller":"traceutil/trace.go:171","msg":"trace[1297803771] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"142.671408ms","start":"2026-01-29T18:40:58.846907Z","end":"2026-01-29T18:40:58.989578Z","steps":["trace[1297803771] 'process raft request' (duration: 142.033434ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:06.492707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.441398ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-01-29T18:41:09.784306Z","caller":"traceutil/trace.go:171","msg":"trace[2064989619] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"255.094845ms","start":"2026-01-29T18:41:09.529191Z","end":"2026-01-29T18:41:09.784286Z","steps":["trace[2064989619] 'process raft request' (duration: 255.011214ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:41:09.784596Z","caller":"traceutil/trace.go:171","msg":"trace[700847502] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"276.530526ms","start":"2026-01-29T18:41:09.508049Z","end":"2026-01-29T18:41:09.784579Z","steps":["trace[700847502] 'process raft request' (duration: 236.948646ms)","trace[700847502] 'compare' (duration: 38.929366ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:41:09.951684Z","caller":"traceutil/trace.go:171","msg":"trace[1590950557] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"107.261945ms","start":"2026-01-29T18:41:09.844395Z","end":"2026-01-29T18:41:09.951657Z","steps":["trace[1590950557] 'process raft request' (duration: 72.040253ms)","trace[1590950557] 'compare' (duration: 35.084181ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:41:09.962402Z","caller":"traceutil/trace.go:171","msg":"trace[220469033] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"101.489036ms","start":"2026-01-29T18:41:09.860895Z","end":"2026-01-29T18:41:09.962384Z","steps":["trace[220469033] 'process raft request' (duration: 100.024527ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:41:09.962825Z","caller":"traceutil/trace.go:171","msg":"trace[1145545672] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"101.844239ms","start":"2026-01-29T18:41:09.860933Z","end":"2026-01-29T18:41:09.962777Z","steps":["trace[1145545672] 'process raft request' (duration: 100.118367ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:41:14.802671Z","caller":"traceutil/trace.go:171","msg":"trace[1969007190] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:584; }","duration":"213.473236ms","start":"2026-01-29T18:41:14.58918Z","end":"2026-01-29T18:41:14.802653Z","steps":["trace[1969007190] 'read index received' (duration: 213.408165ms)","trace[1969007190] 'applied index is now lower than readState.Index' (duration: 64.281µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:41:14.802864Z","caller":"traceutil/trace.go:171","msg":"trace[476890894] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"228.191712ms","start":"2026-01-29T18:41:14.574665Z","end":"2026-01-29T18:41:14.802857Z","steps":["trace[476890894] 'process raft request' (duration: 227.84098ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:14.803069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.872579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:41:14.803144Z","caller":"traceutil/trace.go:171","msg":"trace[2126882313] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:565; }","duration":"213.947209ms","start":"2026-01-29T18:41:14.589172Z","end":"2026-01-29T18:41:14.803119Z","steps":["trace[2126882313] 'agreement among raft nodes before linearized reading' (duration: 213.722378ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:14.816221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.60245ms","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-01-29T18:41:14.816275Z","caller":"traceutil/trace.go:171","msg":"trace[1937415262] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:566; }","duration":"195.68527ms","start":"2026-01-29T18:41:14.62058Z","end":"2026-01-29T18:41:14.816265Z","steps":["trace[1937415262] 'agreement among raft nodes before linearized reading' (duration: 195.57775ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:14.816377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.606949ms","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-01-29T18:41:14.816404Z","caller":"traceutil/trace.go:171","msg":"trace[1790008001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:566; }","duration":"181.645469ms","start":"2026-01-29T18:41:14.634742Z","end":"2026-01-29T18:41:14.816387Z","steps":["trace[1790008001] 'agreement among raft nodes before linearized reading' (duration: 181.619819ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:14.816489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.400082ms","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-01-29T18:41:14.816504Z","caller":"traceutil/trace.go:171","msg":"trace[278597488] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:566; }","duration":"185.426763ms","start":"2026-01-29T18:41:14.631073Z","end":"2026-01-29T18:41:14.816499Z","steps":["trace[278597488] 'agreement among raft nodes before linearized reading' (duration: 185.387802ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:14.817229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.716931ms","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-01-29T18:41:14.817289Z","caller":"traceutil/trace.go:171","msg":"trace[390342024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:566; }","duration":"195.799701ms","start":"2026-01-29T18:41:14.621477Z","end":"2026-01-29T18:41:14.817277Z","steps":["trace[390342024] 'agreement among raft nodes before linearized reading' (duration: 195.62623ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:41:17.102708Z","caller":"traceutil/trace.go:171","msg":"trace[1020836093] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"250.310654ms","start":"2026-01-29T18:41:16.852372Z","end":"2026-01-29T18:41:17.102683Z","steps":["trace[1020836093] 'read index received' (duration: 250.066173ms)","trace[1020836093] 'applied index is now lower than readState.Index' (duration: 243.971µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:41:17.102988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.603937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:41:17.103032Z","caller":"traceutil/trace.go:171","msg":"trace[1201962523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"250.662477ms","start":"2026-01-29T18:41:16.852342Z","end":"2026-01-29T18:41:17.103004Z","steps":["trace[1201962523] 'agreement among raft nodes before linearized reading' (duration: 250.601107ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:41:17.103155Z","caller":"traceutil/trace.go:171","msg":"trace[330376143] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"287.568477ms","start":"2026-01-29T18:41:16.815582Z","end":"2026-01-29T18:41:17.10315Z","steps":["trace[330376143] 'process raft request' (duration: 286.984264ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:17.103267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.246519ms","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-01-29T18:41:17.103314Z","caller":"traceutil/trace.go:171","msg":"trace[1764507951] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:601; }","duration":"240.3379ms","start":"2026-01-29T18:41:16.862964Z","end":"2026-01-29T18:41:17.103302Z","steps":["trace[1764507951] 'agreement among raft nodes before linearized reading' (duration: 240.161838ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:17.1035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.330968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-01-29T18:41:17.103517Z","caller":"traceutil/trace.go:171","msg":"trace[540440855] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:601; }","duration":"131.380088ms","start":"2026-01-29T18:41:16.97213Z","end":"2026-01-29T18:41:17.10351Z","steps":["trace[540440855] 'agreement among raft nodes before linearized reading' (duration: 131.322958ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:17.103607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.536998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-01-29T18:41:17.103615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.26978ms","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-01-29T18:41:17.103632Z","caller":"traceutil/trace.go:171","msg":"trace[1876126673] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:601; }","duration":"240.2981ms","start":"2026-01-29T18:41:16.863329Z","end":"2026-01-29T18:41:17.103627Z","steps":["trace[1876126673] 'agreement among raft nodes before linearized reading' (duration: 240.25801ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:41:17.103626Z","caller":"traceutil/trace.go:171","msg":"trace[204985436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:601; }","duration":"235.570878ms","start":"2026-01-29T18:41:16.86805Z","end":"2026-01-29T18:41:17.103621Z","steps":["trace[204985436] 'agreement among raft nodes before linearized reading' (duration: 235.469347ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:17.103719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.955385ms","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-01-29T18:41:17.103732Z","caller":"traceutil/trace.go:171","msg":"trace[1595763312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"222.994637ms","start":"2026-01-29T18:41:16.880733Z","end":"2026-01-29T18:41:17.103728Z","steps":["trace[1595763312] 'agreement among raft nodes before linearized reading' (duration: 222.942866ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:41:21.606513Z","caller":"traceutil/trace.go:171","msg":"trace[299576308] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"228.224646ms","start":"2026-01-29T18:41:21.378271Z","end":"2026-01-29T18:41:21.606496Z","steps":["trace[299576308] 'process raft request' (duration: 195.17331ms)","trace[299576308] 'compare' (duration: 32.814654ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:41:22.036889Z","caller":"traceutil/trace.go:171","msg":"trace[290400771] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"159.651959ms","start":"2026-01-29T18:41:21.877211Z","end":"2026-01-29T18:41:22.036863Z","steps":["trace[290400771] 'process raft request' (duration: 159.494898ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:41:22.414846Z","caller":"traceutil/trace.go:171","msg":"trace[1025847862] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"160.901568ms","start":"2026-01-29T18:41:22.253925Z","end":"2026-01-29T18:41:22.414826Z","steps":["trace[1025847862] 'process raft request' (duration: 157.298485ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:41:23.181738Z","caller":"traceutil/trace.go:171","msg":"trace[928363403] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"328.374066ms","start":"2026-01-29T18:41:22.853349Z","end":"2026-01-29T18:41:23.181723Z","steps":["trace[928363403] 'read index received' (duration: 328.245465ms)","trace[928363403] 'applied index is now lower than readState.Index' (duration: 128.061µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:41:23.18185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.484357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:41:23.181868Z","caller":"traceutil/trace.go:171","msg":"trace[902630441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"328.543367ms","start":"2026-01-29T18:41:22.853319Z","end":"2026-01-29T18:41:23.181863Z","steps":["trace[902630441] 'agreement among raft nodes before linearized reading' (duration: 328.462626ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:23.181893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:41:22.853305Z","time spent":"328.584208ms","remote":"127.0.0.1:36938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-29T18:41:23.182106Z","caller":"traceutil/trace.go:171","msg":"trace[209639508] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"563.010922ms","start":"2026-01-29T18:41:22.619088Z","end":"2026-01-29T18:41:23.182099Z","steps":["trace[209639508] 'process raft request' (duration: 562.5506ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:23.182156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:41:22.619068Z","time spent":"563.056872ms","remote":"127.0.0.1:37104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T18:41:23.729555Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085742355859398485,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T18:41:24.257251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.326512ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T18:41:24.25748Z","caller":"traceutil/trace.go:171","msg":"trace[1835978761] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:650; }","duration":"1.028348256s","start":"2026-01-29T18:41:23.229117Z","end":"2026-01-29T18:41:24.257465Z","steps":["trace[1835978761] 'read index received' (duration: 721.828093ms)","trace[1835978761] 'applied index is now lower than readState.Index' (duration: 306.519073ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:41:24.257691Z","caller":"traceutil/trace.go:171","msg":"trace[3004408] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"1.062088955s","start":"2026-01-29T18:41:23.195589Z","end":"2026-01-29T18:41:24.257678Z","steps":["trace[3004408] 'process raft request' (duration: 755.26835ms)","trace[3004408] 'compare' (duration: 306.164121ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:41:24.257761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:41:23.195578Z","time spent":"1.062147966s","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T18:41:24.259552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.810024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-01-29T18:41:24.259832Z","caller":"traceutil/trace.go:171","msg":"trace[1845540200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:630; }","duration":"905.066555ms","start":"2026-01-29T18:41:23.354726Z","end":"2026-01-29T18:41:24.259793Z","steps":["trace[1845540200] 'agreement among raft nodes before linearized reading' (duration: 904.723973ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:24.260042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:41:23.354677Z","time spent":"905.194737ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-29T18:41:24.260386Z","caller":"traceutil/trace.go:171","msg":"trace[2105734642] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"1.028578317s","start":"2026-01-29T18:41:23.231794Z","end":"2026-01-29T18:41:24.260372Z","steps":["trace[2105734642] 'process raft request' (duration: 1.025565138s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:24.260634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:41:23.231782Z","time spent":"1.028642408s","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T18:41:24.260947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031824079s","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-01-29T18:41:24.261123Z","caller":"traceutil/trace.go:171","msg":"trace[492153092] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:630; }","duration":"1.03189449s","start":"2026-01-29T18:41:23.229084Z","end":"2026-01-29T18:41:24.260979Z","steps":["trace[492153092] 'agreement among raft nodes before linearized reading' (duration: 1.031763349s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:24.261324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:41:23.229068Z","time spent":"1.032241232s","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-29T18:41:24.261839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.06505ms","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":"warn","ts":"2026-01-29T18:41:24.262456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.244684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:41:24.264303Z","caller":"traceutil/trace.go:171","msg":"trace[1250320772] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:630; }","duration":"220.13163ms","start":"2026-01-29T18:41:24.041741Z","end":"2026-01-29T18:41:24.261872Z","steps":["trace[1250320772] 'agreement among raft nodes before linearized reading' (duration: 220.005999ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:24.264561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.37917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-29T18:41:24.264589Z","caller":"traceutil/trace.go:171","msg":"trace[1330322688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:630; }","duration":"655.437522ms","start":"2026-01-29T18:41:23.609144Z","end":"2026-01-29T18:41:24.264582Z","steps":["trace[1330322688] 'agreement among raft nodes before linearized reading' (duration: 655.350671ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:41:24.262485Z","caller":"traceutil/trace.go:171","msg":"trace[654084148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"408.303614ms","start":"2026-01-29T18:41:23.854175Z","end":"2026-01-29T18:41:24.262478Z","steps":["trace[654084148] 'agreement among raft nodes before linearized reading' (duration: 408.261284ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:24.264784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:41:23.609114Z","time spent":"655.591832ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-29T18:41:24.26505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:41:23.854158Z","time spent":"410.682399ms","remote":"127.0.0.1:36938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-29T18:41:24.505942Z","caller":"traceutil/trace.go:171","msg":"trace[1757513932] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"234.564235ms","start":"2026-01-29T18:41:24.27136Z","end":"2026-01-29T18:41:24.505924Z","steps":["trace[1757513932] 'process raft request' (duration: 230.384998ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:41:24.50615Z","caller":"traceutil/trace.go:171","msg":"trace[1137388602] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"234.690636ms","start":"2026-01-29T18:41:24.271442Z","end":"2026-01-29T18:41:24.506133Z","steps":["trace[1137388602] 'process raft request' (duration: 234.448024ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:41:24.506262Z","caller":"traceutil/trace.go:171","msg":"trace[1412048481] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"234.785287ms","start":"2026-01-29T18:41:24.271469Z","end":"2026-01-29T18:41:24.506254Z","steps":["trace[1412048481] 'process raft request' (duration: 234.588085ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:41:27.855536Z","caller":"traceutil/trace.go:171","msg":"trace[1021509059] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"226.276079ms","start":"2026-01-29T18:41:27.629244Z","end":"2026-01-29T18:41:27.85552Z","steps":["trace[1021509059] 'process raft request' (duration: 226.201629ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:41:28.230467Z","caller":"traceutil/trace.go:171","msg":"trace[229361297] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"592.443373ms","start":"2026-01-29T18:41:27.638017Z","end":"2026-01-29T18:41:28.230461Z","steps":["trace[229361297] 'process raft request' (duration: 592.176382ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:28.230539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:41:27.638003Z","time spent":"592.493884ms","remote":"127.0.0.1:37104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17655,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T18:41:28.230289Z","caller":"traceutil/trace.go:171","msg":"trace[366376382] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:675; }","duration":"374.415089ms","start":"2026-01-29T18:41:27.85586Z","end":"2026-01-29T18:41:28.230275Z","steps":["trace[366376382] 'read index received' (duration: 374.278309ms)","trace[366376382] 'applied index is now lower than readState.Index' (duration: 136.24µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:41:28.230694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.831702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:41:28.230718Z","caller":"traceutil/trace.go:171","msg":"trace[1832856833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"374.847352ms","start":"2026-01-29T18:41:27.855857Z","end":"2026-01-29T18:41:28.230705Z","steps":["trace[1832856833] 'agreement among raft nodes before linearized reading' (duration: 374.818682ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:28.230733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:41:27.85583Z","time spent":"374.899222ms","remote":"127.0.0.1:36938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T18:41:28.555896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.324969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T18:41:28.556529Z","caller":"traceutil/trace.go:171","msg":"trace[1857112977] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"251.739663ms","start":"2026-01-29T18:41:28.304769Z","end":"2026-01-29T18:41:28.556509Z","steps":["trace[1857112977] 'read index received' (duration: 84.455468ms)","trace[1857112977] 'applied index is now lower than readState.Index' (duration: 167.281475ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:41:28.556646Z","caller":"traceutil/trace.go:171","msg":"trace[1284588137] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"281.852179ms","start":"2026-01-29T18:41:28.274778Z","end":"2026-01-29T18:41:28.55663Z","steps":["trace[1284588137] 'process raft request' (duration: 114.528513ms)","trace[1284588137] 'compare' (duration: 165.935407ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:41:28.557188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.411467ms","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-01-29T18:41:28.557232Z","caller":"traceutil/trace.go:171","msg":"trace[865981949] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:654; }","duration":"252.489807ms","start":"2026-01-29T18:41:28.304731Z","end":"2026-01-29T18:41:28.55722Z","steps":["trace[865981949] 'agreement among raft nodes before linearized reading' (duration: 252.359466ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:41:28.731198Z","caller":"traceutil/trace.go:171","msg":"trace[130766488] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"163.47356ms","start":"2026-01-29T18:41:28.567699Z","end":"2026-01-29T18:41:28.731172Z","steps":["trace[130766488] 'process raft request' (duration: 163.42557ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:41:28.731589Z","caller":"traceutil/trace.go:171","msg":"trace[1397337555] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"165.182321ms","start":"2026-01-29T18:41:28.566393Z","end":"2026-01-29T18:41:28.731575Z","steps":["trace[1397337555] 'process raft request' (duration: 97.634153ms)","trace[1397337555] 'compare' (duration: 66.948704ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:41:28.731771Z","caller":"traceutil/trace.go:171","msg":"trace[425366148] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"165.331342ms","start":"2026-01-29T18:41:28.56643Z","end":"2026-01-29T18:41:28.731761Z","steps":["trace[425366148] 'process raft request' (duration: 164.654907ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:41:29.262139Z","caller":"traceutil/trace.go:171","msg":"trace[1826219853] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"251.906583ms","start":"2026-01-29T18:41:29.0102Z","end":"2026-01-29T18:41:29.262106Z","steps":["trace[1826219853] 'process raft request' (duration: 251.772013ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:41:29.938774Z","caller":"traceutil/trace.go:171","msg":"trace[642025170] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"117.533092ms","start":"2026-01-29T18:41:29.821223Z","end":"2026-01-29T18:41:29.938756Z","steps":["trace[642025170] 'process raft request' (duration: 117.447222ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:41:30.072079Z","caller":"traceutil/trace.go:171","msg":"trace[54719773] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:684; }","duration":"219.453473ms","start":"2026-01-29T18:41:29.852606Z","end":"2026-01-29T18:41:30.07206Z","steps":["trace[54719773] 'read index received' (duration: 86.434461ms)","trace[54719773] 'applied index is now lower than readState.Index' (duration: 133.018182ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:41:30.072184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.559363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:41:30.072213Z","caller":"traceutil/trace.go:171","msg":"trace[1450341167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"219.623054ms","start":"2026-01-29T18:41:29.852581Z","end":"2026-01-29T18:41:30.072204Z","steps":["trace[1450341167] 'agreement among raft nodes before linearized reading' (duration: 219.552393ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:41:30.072473Z","caller":"traceutil/trace.go:171","msg":"trace[1581197416] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"242.201129ms","start":"2026-01-29T18:41:29.83026Z","end":"2026-01-29T18:41:30.072461Z","steps":["trace[1581197416] 'process raft request' (duration: 241.653256ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:41:30.072658Z","caller":"traceutil/trace.go:171","msg":"trace[538349577] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"145.834606ms","start":"2026-01-29T18:41:29.926815Z","end":"2026-01-29T18:41:30.07265Z","steps":["trace[538349577] 'process raft request' (duration: 145.202692ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:30.488202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.448161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-29T18:41:30.488297Z","caller":"traceutil/trace.go:171","msg":"trace[1958838095] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:687; }","duration":"136.412883ms","start":"2026-01-29T18:41:30.351872Z","end":"2026-01-29T18:41:30.488285Z","steps":["trace[1958838095] 'read index received' (duration: 26.69µs)","trace[1958838095] 'applied index is now lower than readState.Index' (duration: 136.385373ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:41:30.488413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.535275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-29T18:41:30.488431Z","caller":"traceutil/trace.go:171","msg":"trace[892334777] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:665; }","duration":"136.580315ms","start":"2026-01-29T18:41:30.351845Z","end":"2026-01-29T18:41:30.488425Z","steps":["trace[892334777] 'agreement among raft nodes before linearized reading' (duration: 136.471504ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:41:30.488459Z","caller":"traceutil/trace.go:171","msg":"trace[1474714039] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"216.244221ms","start":"2026-01-29T18:41:30.272191Z","end":"2026-01-29T18:41:30.488435Z","steps":["trace[1474714039] 'process raft request' (duration: 61.501028ms)","trace[1474714039] 'compare' (duration: 154.236799ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:41:45.079884Z","caller":"traceutil/trace.go:171","msg":"trace[1029526467] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"106.005903ms","start":"2026-01-29T18:41:44.973859Z","end":"2026-01-29T18:41:45.079865Z","steps":["trace[1029526467] 'process raft request' (duration: 25.462955ms)","trace[1029526467] 'compare' (duration: 80.461978ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:41:53.005561Z","caller":"traceutil/trace.go:171","msg":"trace[309404035] linearizableReadLoop","detail":"{readStateIndex:854; appliedIndex:853; }","duration":"153.92329ms","start":"2026-01-29T18:41:52.851623Z","end":"2026-01-29T18:41:53.005546Z","steps":["trace[309404035] 'read index received' (duration: 126.185271ms)","trace[309404035] 'applied index is now lower than readState.Index' (duration: 27.737419ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:41:53.005637Z","caller":"traceutil/trace.go:171","msg":"trace[1406885290] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"193.276873ms","start":"2026-01-29T18:41:52.812354Z","end":"2026-01-29T18:41:53.005631Z","steps":["trace[1406885290] 'process raft request' (duration: 165.417303ms)","trace[1406885290] 'compare' (duration: 27.596078ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:41:53.005755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.749396ms","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-01-29T18:41:53.005808Z","caller":"traceutil/trace.go:171","msg":"trace[1193169418] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:822; }","duration":"134.827307ms","start":"2026-01-29T18:41:52.870966Z","end":"2026-01-29T18:41:53.005793Z","steps":["trace[1193169418] 'agreement among raft nodes before linearized reading' (duration: 134.698366ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:53.005869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.229804ms","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-01-29T18:41:53.005891Z","caller":"traceutil/trace.go:171","msg":"trace[542911562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:822; }","duration":"114.273805ms","start":"2026-01-29T18:41:52.89161Z","end":"2026-01-29T18:41:53.005884Z","steps":["trace[542911562] 'agreement among raft nodes before linearized reading' (duration: 114.179105ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:53.005956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.667327ms","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-01-29T18:41:53.005977Z","caller":"traceutil/trace.go:171","msg":"trace[2020157713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:822; }","duration":"117.699747ms","start":"2026-01-29T18:41:52.88827Z","end":"2026-01-29T18:41:53.00597Z","steps":["trace[2020157713] 'agreement among raft nodes before linearized reading' (duration: 117.644746ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:41:53.005979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.361643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:41:53.005995Z","caller":"traceutil/trace.go:171","msg":"trace[108978309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:822; }","duration":"154.412303ms","start":"2026-01-29T18:41:52.851578Z","end":"2026-01-29T18:41:53.00599Z","steps":["trace[108978309] 'agreement among raft nodes before linearized reading' (duration: 154.387703ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:01.193017Z","caller":"traceutil/trace.go:171","msg":"trace[1988639829] linearizableReadLoop","detail":"{readStateIndex:879; appliedIndex:878; }","duration":"117.192753ms","start":"2026-01-29T18:42:01.075753Z","end":"2026-01-29T18:42:01.192946Z","steps":["trace[1988639829] 'read index received' (duration: 116.816889ms)","trace[1988639829] 'applied index is now lower than readState.Index' (duration: 374.054µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:01.193275Z","caller":"traceutil/trace.go:171","msg":"trace[1141375270] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"131.433263ms","start":"2026-01-29T18:42:01.061806Z","end":"2026-01-29T18:42:01.19324Z","steps":["trace[1141375270] 'process raft request' (duration: 130.869689ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:01.193288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.509844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-29T18:42:01.193363Z","caller":"traceutil/trace.go:171","msg":"trace[1340519880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:845; }","duration":"117.628175ms","start":"2026-01-29T18:42:01.075723Z","end":"2026-01-29T18:42:01.193351Z","steps":["trace[1340519880] 'agreement among raft nodes before linearized reading' (duration: 117.393844ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:23.220076Z","caller":"traceutil/trace.go:171","msg":"trace[2119517270] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"195.638982ms","start":"2026-01-29T18:42:23.024415Z","end":"2026-01-29T18:42:23.220054Z","steps":["trace[2119517270] 'process raft request' (duration: 195.504062ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:31.337298Z","caller":"traceutil/trace.go:171","msg":"trace[734768337] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"140.431308ms","start":"2026-01-29T18:42:31.196843Z","end":"2026-01-29T18:42:31.337275Z","steps":["trace[734768337] 'process raft request' (duration: 140.135416ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:31.4795Z","caller":"traceutil/trace.go:171","msg":"trace[1108253204] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"138.710917ms","start":"2026-01-29T18:42:31.34077Z","end":"2026-01-29T18:42:31.479481Z","steps":["trace[1108253204] 'process raft request' (duration: 100.781375ms)","trace[1108253204] 'compare' (duration: 37.681461ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:31.949283Z","caller":"traceutil/trace.go:171","msg":"trace[1894383815] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"145.29511ms","start":"2026-01-29T18:42:31.803967Z","end":"2026-01-29T18:42:31.949262Z","steps":["trace[1894383815] 'process raft request' (duration: 69.249983ms)","trace[1894383815] 'compare' (duration: 75.678694ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:33.693052Z","caller":"traceutil/trace.go:171","msg":"trace[2129881624] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"184.681361ms","start":"2026-01-29T18:42:33.508332Z","end":"2026-01-29T18:42:33.693014Z","steps":["trace[2129881624] 'process raft request' (duration: 178.778393ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:45.790939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.854544ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-01-29T18:42:45.799581Z","caller":"traceutil/trace.go:171","msg":"trace[693483811] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"220.717771ms","start":"2026-01-29T18:42:45.57884Z","end":"2026-01-29T18:42:45.799558Z","steps":["trace[693483811] 'read index received' (duration: 220.4809ms)","trace[693483811] 'applied index is now lower than readState.Index' (duration: 235.891µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:45.799688Z","caller":"traceutil/trace.go:171","msg":"trace[1105559902] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"234.63136ms","start":"2026-01-29T18:42:45.565043Z","end":"2026-01-29T18:42:45.799675Z","steps":["trace[1105559902] 'process raft request' (duration: 234.328108ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:45.800038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.083793ms","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-01-29T18:42:45.800087Z","caller":"traceutil/trace.go:171","msg":"trace[1499380904] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:998; }","duration":"221.297395ms","start":"2026-01-29T18:42:45.578775Z","end":"2026-01-29T18:42:45.800072Z","steps":["trace[1499380904] 'agreement among raft nodes before linearized reading' (duration: 221.029963ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:45.947737Z","caller":"traceutil/trace.go:171","msg":"trace[327343397] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"138.072944ms","start":"2026-01-29T18:42:45.809636Z","end":"2026-01-29T18:42:45.947709Z","steps":["trace[327343397] 'process raft request' (duration: 95.602401ms)","trace[327343397] 'compare' (duration: 42.26699ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:48.097351Z","caller":"traceutil/trace.go:171","msg":"trace[1217921396] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"138.007333ms","start":"2026-01-29T18:42:47.959318Z","end":"2026-01-29T18:42:48.097325Z","steps":["trace[1217921396] 'process raft request' (duration: 49.444267ms)","trace[1217921396] 'compare' (duration: 88.462686ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:48.099011Z","caller":"traceutil/trace.go:171","msg":"trace[272018157] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1049; }","duration":"128.616492ms","start":"2026-01-29T18:42:47.970371Z","end":"2026-01-29T18:42:48.098987Z","steps":["trace[272018157] 'read index received' (duration: 38.406006ms)","trace[272018157] 'applied index is now lower than readState.Index' (duration: 90.209436ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:48.099442Z","caller":"traceutil/trace.go:171","msg":"trace[193424874] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"139.953015ms","start":"2026-01-29T18:42:47.959472Z","end":"2026-01-29T18:42:48.099425Z","steps":["trace[193424874] 'process raft request' (duration: 139.355691ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:48.100003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.615039ms","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-01-29T18:42:48.10005Z","caller":"traceutil/trace.go:171","msg":"trace[1639211554] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1007; }","duration":"129.704839ms","start":"2026-01-29T18:42:47.970334Z","end":"2026-01-29T18:42:48.100039Z","steps":["trace[1639211554] 'agreement among raft nodes before linearized reading' (duration: 129.552519ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:48.50733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.994146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T18:42:48.507739Z","caller":"traceutil/trace.go:171","msg":"trace[950367100] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"401.139905ms","start":"2026-01-29T18:42:48.10629Z","end":"2026-01-29T18:42:48.50743Z","steps":["trace[950367100] 'process raft request' (duration: 158.523924ms)","trace[950367100] 'compare' (duration: 241.849106ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:42:48.507905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:48.106274Z","time spent":"401.526117ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T18:42:52.433648Z","caller":"traceutil/trace.go:171","msg":"trace[824813751] linearizableReadLoop","detail":"{readStateIndex:1065; appliedIndex:1064; }","duration":"306.050146ms","start":"2026-01-29T18:42:52.127569Z","end":"2026-01-29T18:42:52.433619Z","steps":["trace[824813751] 'read index received' (duration: 305.807795ms)","trace[824813751] 'applied index is now lower than readState.Index' (duration: 241.321µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:42:52.434047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.444569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-29T18:42:52.434099Z","caller":"traceutil/trace.go:171","msg":"trace[400850625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1020; }","duration":"306.563539ms","start":"2026-01-29T18:42:52.127523Z","end":"2026-01-29T18:42:52.434086Z","steps":["trace[400850625] 'agreement among raft nodes before linearized reading' (duration: 306.358128ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:52.434137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:52.127497Z","time spent":"306.631349ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-01-29T18:42:52.434651Z","caller":"traceutil/trace.go:171","msg":"trace[927029352] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"387.748698ms","start":"2026-01-29T18:42:52.046844Z","end":"2026-01-29T18:42:52.434593Z","steps":["trace[927029352] 'process raft request' (duration: 386.60469ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:52.434838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:52.046803Z","time spent":"387.905239ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T18:42:52.435227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.064569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-01-29T18:42:52.435276Z","caller":"traceutil/trace.go:171","msg":"trace[1921984772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1020; }","duration":"283.15307ms","start":"2026-01-29T18:42:52.15211Z","end":"2026-01-29T18:42:52.435263Z","steps":["trace[1921984772] 'agreement among raft nodes before linearized reading' (duration: 283.015459ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:52.435594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.118822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-01-29T18:42:52.435641Z","caller":"traceutil/trace.go:171","msg":"trace[601189810] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1020; }","duration":"116.236513ms","start":"2026-01-29T18:42:52.319394Z","end":"2026-01-29T18:42:52.435631Z","steps":["trace[601189810] 'agreement among raft nodes before linearized reading' (duration: 115.958461ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:52.436208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.186663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"info","ts":"2026-01-29T18:42:52.436604Z","caller":"traceutil/trace.go:171","msg":"trace[1924498233] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1020; }","duration":"188.647986ms","start":"2026-01-29T18:42:52.247929Z","end":"2026-01-29T18:42:52.436577Z","steps":["trace[1924498233] 'agreement among raft nodes before linearized reading' (duration: 188.036823ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:52.437501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.409638ms","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-01-29T18:42:52.437823Z","caller":"traceutil/trace.go:171","msg":"trace[210640647] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1020; }","duration":"256.754301ms","start":"2026-01-29T18:42:52.181033Z","end":"2026-01-29T18:42:52.437787Z","steps":["trace[210640647] 'agreement among raft nodes before linearized reading' (duration: 256.385639ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:52.676149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.306279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T18:42:52.676275Z","caller":"traceutil/trace.go:171","msg":"trace[120031672] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"228.42506ms","start":"2026-01-29T18:42:52.447826Z","end":"2026-01-29T18:42:52.676251Z","steps":["trace[120031672] 'process raft request' (duration: 96.877709ms)","trace[120031672] 'compare' (duration: 131.050298ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:52.714032Z","caller":"traceutil/trace.go:171","msg":"trace[1773668152] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"265.705898ms","start":"2026-01-29T18:42:52.448297Z","end":"2026-01-29T18:42:52.714003Z","steps":["trace[1773668152] 'process raft request' (duration: 265.387316ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:52.714627Z","caller":"traceutil/trace.go:171","msg":"trace[1359039830] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"266.267172ms","start":"2026-01-29T18:42:52.448336Z","end":"2026-01-29T18:42:52.714603Z","steps":["trace[1359039830] 'process raft request' (duration: 265.608758ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:52.714805Z","caller":"traceutil/trace.go:171","msg":"trace[883344169] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1065; }","duration":"265.619427ms","start":"2026-01-29T18:42:52.449168Z","end":"2026-01-29T18:42:52.714788Z","steps":["trace[883344169] 'read index received' (duration: 95.5522ms)","trace[883344169] 'applied index is now lower than readState.Index' (duration: 170.065277ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:52.71495Z","caller":"traceutil/trace.go:171","msg":"trace[573489810] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"265.835109ms","start":"2026-01-29T18:42:52.449104Z","end":"2026-01-29T18:42:52.714939Z","steps":["trace[573489810] 'process raft request' (duration: 265.129214ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:52.715018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.615754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:42:52.715062Z","caller":"traceutil/trace.go:171","msg":"trace[650656735] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1024; }","duration":"130.720515ms","start":"2026-01-29T18:42:52.584329Z","end":"2026-01-29T18:42:52.715049Z","steps":["trace[650656735] 'agreement among raft nodes before linearized reading' (duration: 130.629874ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:52.715373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.860397ms","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-01-29T18:42:52.715412Z","caller":"traceutil/trace.go:171","msg":"trace[1637196903] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1024; }","duration":"124.920868ms","start":"2026-01-29T18:42:52.590479Z","end":"2026-01-29T18:42:52.7154Z","steps":["trace[1637196903] 'agreement among raft nodes before linearized reading' (duration: 124.766107ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:52.716042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.864465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-96612996-e7ac-4574-a514-b59cf95d2cdb\" ","response":"range_response_count:1 size:1254"} {"level":"info","ts":"2026-01-29T18:42:52.716132Z","caller":"traceutil/trace.go:171","msg":"trace[1157959489] range","detail":"{range_begin:/registry/persistentvolumes/pvc-96612996-e7ac-4574-a514-b59cf95d2cdb; range_end:; response_count:1; response_revision:1024; }","duration":"266.938047ms","start":"2026-01-29T18:42:52.449137Z","end":"2026-01-29T18:42:52.716075Z","steps":["trace[1157959489] 'agreement among raft nodes before linearized reading' (duration: 266.406693ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:56.393484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.396805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:42:56.393648Z","caller":"traceutil/trace.go:171","msg":"trace[123225626] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1032; }","duration":"138.600986ms","start":"2026-01-29T18:42:56.254975Z","end":"2026-01-29T18:42:56.393576Z","steps":["trace[123225626] 'count revisions from in-memory index tree' (duration: 138.271344ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:57.078474Z","caller":"traceutil/trace.go:171","msg":"trace[1538194011] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"256.423348ms","start":"2026-01-29T18:42:56.82202Z","end":"2026-01-29T18:42:57.078443Z","steps":["trace[1538194011] 'process raft request' (duration: 256.237347ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:57.090363Z","caller":"traceutil/trace.go:171","msg":"trace[1375563317] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"268.080774ms","start":"2026-01-29T18:42:56.822246Z","end":"2026-01-29T18:42:57.090327Z","steps":["trace[1375563317] 'process raft request' (duration: 267.746492ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:01.136214Z","caller":"traceutil/trace.go:171","msg":"trace[382463863] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"308.554472ms","start":"2026-01-29T18:43:00.827628Z","end":"2026-01-29T18:43:01.136183Z","steps":["trace[382463863] 'process raft request' (duration: 308.3857ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:01.136721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:00.8276Z","time spent":"308.870573ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T18:43:01.137193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.6307ms","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-01-29T18:43:01.137254Z","caller":"traceutil/trace.go:171","msg":"trace[1726632081] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1050; }","duration":"297.750802ms","start":"2026-01-29T18:43:00.839491Z","end":"2026-01-29T18:43:01.137242Z","steps":["trace[1726632081] 'agreement among raft nodes before linearized reading' (duration: 297.40688ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:01.136778Z","caller":"traceutil/trace.go:171","msg":"trace[1181123441] linearizableReadLoop","detail":"{readStateIndex:1097; appliedIndex:1097; }","duration":"297.217378ms","start":"2026-01-29T18:43:00.839519Z","end":"2026-01-29T18:43:01.136737Z","steps":["trace[1181123441] 'read index received' (duration: 297.202128ms)","trace[1181123441] 'applied index is now lower than readState.Index' (duration: 11.54µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:01.137639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.957285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:43:01.13766Z","caller":"traceutil/trace.go:171","msg":"trace[258046497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1050; }","duration":"281.022775ms","start":"2026-01-29T18:43:00.856632Z","end":"2026-01-29T18:43:01.137655Z","steps":["trace[258046497] 'agreement among raft nodes before linearized reading' (duration: 280.983335ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:02.386554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.583365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-public/\" range_end:\"/registry/secrets/kube-public0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:43:02.386665Z","caller":"traceutil/trace.go:171","msg":"trace[833965490] range","detail":"{range_begin:/registry/secrets/kube-public/; range_end:/registry/secrets/kube-public0; response_count:0; response_revision:1054; }","duration":"133.720865ms","start":"2026-01-29T18:43:02.252916Z","end":"2026-01-29T18:43:02.386637Z","steps":["trace[833965490] 'range keys from in-memory index tree' (duration: 133.464353ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:04.699668Z","caller":"traceutil/trace.go:171","msg":"trace[273959328] linearizableReadLoop","detail":"{readStateIndex:1107; appliedIndex:1106; }","duration":"105.658886ms","start":"2026-01-29T18:43:04.593987Z","end":"2026-01-29T18:43:04.699646Z","steps":["trace[273959328] 'read index received' (duration: 105.459845ms)","trace[273959328] 'applied index is now lower than readState.Index' (duration: 196.571µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:43:04.699937Z","caller":"traceutil/trace.go:171","msg":"trace[1163539347] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"107.178656ms","start":"2026-01-29T18:43:04.59275Z","end":"2026-01-29T18:43:04.699928Z","steps":["trace[1163539347] 'process raft request' (duration: 106.767683ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:04.700103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.090628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:1 size:3548"} {"level":"info","ts":"2026-01-29T18:43:04.700122Z","caller":"traceutil/trace.go:171","msg":"trace[1393175048] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:1; response_revision:1060; }","duration":"106.142069ms","start":"2026-01-29T18:43:04.593975Z","end":"2026-01-29T18:43:04.700117Z","steps":["trace[1393175048] 'agreement among raft nodes before linearized reading' (duration: 106.077618ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:05.649913Z","caller":"traceutil/trace.go:171","msg":"trace[1810551686] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1111; }","duration":"127.090871ms","start":"2026-01-29T18:43:05.522778Z","end":"2026-01-29T18:43:05.649869Z","steps":["trace[1810551686] 'read index received' (duration: 75.25105ms)","trace[1810551686] 'applied index is now lower than readState.Index' (duration: 51.837831ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:05.650516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.712385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cilium\" ","response":"range_response_count:1 size:749"} {"level":"info","ts":"2026-01-29T18:43:05.650641Z","caller":"traceutil/trace.go:171","msg":"trace[128239330] range","detail":"{range_begin:/registry/clusterrolebindings/cilium; range_end:; response_count:1; response_revision:1064; }","duration":"127.865866ms","start":"2026-01-29T18:43:05.522724Z","end":"2026-01-29T18:43:05.650589Z","steps":["trace[128239330] 'agreement among raft nodes before linearized reading' (duration: 127.627955ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:05.650574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.875625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:1 size:2025"} {"level":"info","ts":"2026-01-29T18:43:05.650742Z","caller":"traceutil/trace.go:171","msg":"trace[1697972009] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:1; response_revision:1064; }","duration":"112.097666ms","start":"2026-01-29T18:43:05.538629Z","end":"2026-01-29T18:43:05.650727Z","steps":["trace[1697972009] 'agreement among raft nodes before linearized reading' (duration: 111.845274ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:08.896288Z","caller":"traceutil/trace.go:171","msg":"trace[1858772056] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1118; }","duration":"100.228991ms","start":"2026-01-29T18:43:08.796039Z","end":"2026-01-29T18:43:08.896268Z","steps":["trace[1858772056] 'read index received' (duration: 100.05892ms)","trace[1858772056] 'applied index is now lower than readState.Index' (duration: 168.941µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:43:08.896489Z","caller":"traceutil/trace.go:171","msg":"trace[2118343322] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"142.838012ms","start":"2026-01-29T18:43:08.753572Z","end":"2026-01-29T18:43:08.89641Z","steps":["trace[2118343322] 'process raft request' (duration: 142.573811ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:08.896822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.770744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:914"} {"level":"info","ts":"2026-01-29T18:43:08.896871Z","caller":"traceutil/trace.go:171","msg":"trace[918719855] range","detail":"{range_begin:/registry/clusterroles/system:controller:horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:1071; }","duration":"100.853124ms","start":"2026-01-29T18:43:08.796005Z","end":"2026-01-29T18:43:08.896859Z","steps":["trace[918719855] 'agreement among raft nodes before linearized reading' (duration: 100.747853ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:09.084022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.616487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:914"} {"level":"info","ts":"2026-01-29T18:43:09.084102Z","caller":"traceutil/trace.go:171","msg":"trace[1603255056] range","detail":"{range_begin:/registry/clusterroles/system:controller:horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:1071; }","duration":"110.743947ms","start":"2026-01-29T18:43:08.973333Z","end":"2026-01-29T18:43:09.084077Z","steps":["trace[1603255056] 'range keys from in-memory index tree' (duration: 110.468826ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:09.474084Z","caller":"traceutil/trace.go:171","msg":"trace[836452547] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"182.542666ms","start":"2026-01-29T18:43:09.291517Z","end":"2026-01-29T18:43:09.47406Z","steps":["trace[836452547] 'process raft request' (duration: 182.509326ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:09.474415Z","caller":"traceutil/trace.go:171","msg":"trace[508496303] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"183.282951ms","start":"2026-01-29T18:43:09.291112Z","end":"2026-01-29T18:43:09.474395Z","steps":["trace[508496303] 'process raft request' (duration: 182.862269ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:09.474569Z","caller":"traceutil/trace.go:171","msg":"trace[780485115] linearizableReadLoop","detail":"{readStateIndex:1121; appliedIndex:1120; }","duration":"183.878274ms","start":"2026-01-29T18:43:09.290489Z","end":"2026-01-29T18:43:09.474367Z","steps":["trace[780485115] 'read index received' (duration: 109.214858ms)","trace[780485115] 'applied index is now lower than readState.Index' (duration: 74.661416ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:43:09.475397Z","caller":"traceutil/trace.go:171","msg":"trace[1264841057] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"186.415611ms","start":"2026-01-29T18:43:09.288964Z","end":"2026-01-29T18:43:09.47538Z","steps":["trace[1264841057] 'process raft request' (duration: 110.729988ms)","trace[1264841057] 'compare' (duration: 74.183673ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:09.476004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.356529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:cronjob-controller\" ","response":"range_response_count:1 size:747"} {"level":"info","ts":"2026-01-29T18:43:09.477097Z","caller":"traceutil/trace.go:171","msg":"trace[82530624] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:cronjob-controller; range_end:; response_count:1; response_revision:1075; }","duration":"154.478607ms","start":"2026-01-29T18:43:09.322603Z","end":"2026-01-29T18:43:09.477082Z","steps":["trace[82530624] 'agreement among raft nodes before linearized reading' (duration: 153.352059ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:09.476053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.561155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:job-controller\" ","response":"range_response_count:1 size:782"} {"level":"info","ts":"2026-01-29T18:43:09.477535Z","caller":"traceutil/trace.go:171","msg":"trace[136056212] range","detail":"{range_begin:/registry/clusterroles/system:controller:job-controller; range_end:; response_count:1; response_revision:1075; }","duration":"187.024174ms","start":"2026-01-29T18:43:09.290467Z","end":"2026-01-29T18:43:09.477491Z","steps":["trace[136056212] 'agreement among raft nodes before linearized reading' (duration: 185.564495ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:10.611127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.374178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:expand-controller\" ","response":"range_response_count:1 size:743"} {"level":"info","ts":"2026-01-29T18:43:10.611288Z","caller":"traceutil/trace.go:171","msg":"trace[1525269746] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:expand-controller; range_end:; response_count:1; response_revision:1076; }","duration":"112.568479ms","start":"2026-01-29T18:43:10.498696Z","end":"2026-01-29T18:43:10.611264Z","steps":["trace[1525269746] 'range keys from in-memory index tree' (duration: 112.301196ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:11.587866Z","caller":"traceutil/trace.go:171","msg":"trace[326371982] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"103.731653ms","start":"2026-01-29T18:43:11.484117Z","end":"2026-01-29T18:43:11.587848Z","steps":["trace[326371982] 'process raft request' (duration: 103.669873ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:20.07687Z","caller":"traceutil/trace.go:171","msg":"trace[1920248222] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1151; }","duration":"352.805654ms","start":"2026-01-29T18:43:19.724049Z","end":"2026-01-29T18:43:20.076855Z","steps":["trace[1920248222] 'read index received' (duration: 352.721612ms)","trace[1920248222] 'applied index is now lower than readState.Index' (duration: 83.582µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:43:20.076943Z","caller":"traceutil/trace.go:171","msg":"trace[472701933] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"355.031308ms","start":"2026-01-29T18:43:19.721907Z","end":"2026-01-29T18:43:20.076938Z","steps":["trace[472701933] 'process raft request' (duration: 354.861116ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:20.077007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:19.721896Z","time spent":"355.067259ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T18:43:20.077148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.097416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-public/kube-root-ca.crt\" ","response":"range_response_count:1 size:1746"} {"level":"info","ts":"2026-01-29T18:43:20.07717Z","caller":"traceutil/trace.go:171","msg":"trace[1878954145] range","detail":"{range_begin:/registry/configmaps/kube-public/kube-root-ca.crt; range_end:; response_count:1; response_revision:1102; }","duration":"353.118246ms","start":"2026-01-29T18:43:19.724046Z","end":"2026-01-29T18:43:20.077164Z","steps":["trace[1878954145] 'agreement among raft nodes before linearized reading' (duration: 353.080026ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:20.077203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:19.724029Z","time spent":"353.169846ms","remote":"127.0.0.1:37030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":1768,"request content":"key:\"/registry/configmaps/kube-public/kube-root-ca.crt\" "} {"level":"warn","ts":"2026-01-29T18:43:20.077326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.670084ms","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-01-29T18:43:20.077343Z","caller":"traceutil/trace.go:171","msg":"trace[2102404970] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1102; }","duration":"349.699374ms","start":"2026-01-29T18:43:19.727639Z","end":"2026-01-29T18:43:20.077339Z","steps":["trace[2102404970] 'agreement among raft nodes before linearized reading' (duration: 349.635763ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:20.077356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:19.727633Z","time spent":"349.719684ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-29T18:43:20.07742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.552632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-public/\" range_end:\"/registry/pods/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:43:20.077437Z","caller":"traceutil/trace.go:171","msg":"trace[799831840] range","detail":"{range_begin:/registry/pods/kube-public/; range_end:/registry/pods/kube-public0; response_count:0; response_revision:1102; }","duration":"266.582502ms","start":"2026-01-29T18:43:19.810851Z","end":"2026-01-29T18:43:20.077434Z","steps":["trace[799831840] 'agreement among raft nodes before linearized reading' (duration: 266.555852ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:20.077515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.022794ms","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-01-29T18:43:20.077532Z","caller":"traceutil/trace.go:171","msg":"trace[348711776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1102; }","duration":"309.049804ms","start":"2026-01-29T18:43:19.768477Z","end":"2026-01-29T18:43:20.077527Z","steps":["trace[348711776] 'agreement among raft nodes before linearized reading' (duration: 309.011064ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:20.077544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:19.768473Z","time spent":"309.068094ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-29T18:43:20.077601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.724251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/kube-public/\" range_end:\"/registry/networkpolicies/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:43:20.077618Z","caller":"traceutil/trace.go:171","msg":"trace[774352006] range","detail":"{range_begin:/registry/networkpolicies/kube-public/; range_end:/registry/networkpolicies/kube-public0; response_count:0; response_revision:1102; }","duration":"322.764091ms","start":"2026-01-29T18:43:19.754849Z","end":"2026-01-29T18:43:20.077613Z","steps":["trace[774352006] 'agreement among raft nodes before linearized reading' (duration: 322.739231ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:20.077631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:19.754838Z","time spent":"322.790151ms","remote":"127.0.0.1:37196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/networkpolicies/kube-public/\" range_end:\"/registry/networkpolicies/kube-public0\" limit:500 "} {"level":"warn","ts":"2026-01-29T18:43:20.077504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.730792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:43:20.077724Z","caller":"traceutil/trace.go:171","msg":"trace[1941165992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1102; }","duration":"223.051655ms","start":"2026-01-29T18:43:19.854668Z","end":"2026-01-29T18:43:20.07772Z","steps":["trace[1941165992] 'agreement among raft nodes before linearized reading' (duration: 222.652883ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:20.301385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.446358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-node-lease/\" range_end:\"/registry/pods/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:43:20.301442Z","caller":"traceutil/trace.go:171","msg":"trace[1756970039] range","detail":"{range_begin:/registry/pods/kube-node-lease/; range_end:/registry/pods/kube-node-lease0; response_count:0; response_revision:1104; }","duration":"129.538098ms","start":"2026-01-29T18:43:20.171891Z","end":"2026-01-29T18:43:20.30143Z","steps":["trace[1756970039] 'range keys from in-memory index tree' (duration: 129.398788ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:23.743383Z","caller":"traceutil/trace.go:171","msg":"trace[558405416] linearizableReadLoop","detail":"{readStateIndex:1164; appliedIndex:1163; }","duration":"110.133173ms","start":"2026-01-29T18:43:23.63323Z","end":"2026-01-29T18:43:23.743363Z","steps":["trace[558405416] 'read index received' (duration: 109.904711ms)","trace[558405416] 'applied index is now lower than readState.Index' (duration: 227.192µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:43:23.743684Z","caller":"traceutil/trace.go:171","msg":"trace[1690238416] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"111.37275ms","start":"2026-01-29T18:43:23.632298Z","end":"2026-01-29T18:43:23.74367Z","steps":["trace[1690238416] 'process raft request' (duration: 110.915758ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:23.743878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.631486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-node-lease/\" range_end:\"/registry/events/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:43:23.743921Z","caller":"traceutil/trace.go:171","msg":"trace[1601372299] range","detail":"{range_begin:/registry/events/kube-node-lease/; range_end:/registry/events/kube-node-lease0; response_count:0; response_revision:1113; }","duration":"110.693777ms","start":"2026-01-29T18:43:23.633215Z","end":"2026-01-29T18:43:23.743909Z","steps":["trace[1601372299] 'agreement among raft nodes before linearized reading' (duration: 110.620336ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:24.414143Z","caller":"traceutil/trace.go:171","msg":"trace[1989848370] linearizableReadLoop","detail":"{readStateIndex:1165; appliedIndex:1164; }","duration":"287.789658ms","start":"2026-01-29T18:43:24.12633Z","end":"2026-01-29T18:43:24.41412Z","steps":["trace[1989848370] 'read index received' (duration: 287.505156ms)","trace[1989848370] 'applied index is now lower than readState.Index' (duration: 283.402µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:24.414641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.319142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/\" range_end:\"/registry/services/specs/kube-system0\" limit:500 ","response":"range_response_count:1 size:1211"} {"level":"info","ts":"2026-01-29T18:43:24.414712Z","caller":"traceutil/trace.go:171","msg":"trace[206487920] range","detail":"{range_begin:/registry/services/specs/kube-system/; range_end:/registry/services/specs/kube-system0; response_count:1; response_revision:1114; }","duration":"180.419233ms","start":"2026-01-29T18:43:24.234258Z","end":"2026-01-29T18:43:24.414677Z","steps":["trace[206487920] 'agreement among raft nodes before linearized reading' (duration: 180.298762ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:24.414928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.971908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-29T18:43:24.415222Z","caller":"traceutil/trace.go:171","msg":"trace[2070900471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1114; }","duration":"283.526011ms","start":"2026-01-29T18:43:24.131675Z","end":"2026-01-29T18:43:24.415201Z","steps":["trace[2070900471] 'agreement among raft nodes before linearized reading' (duration: 282.832517ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:24.415695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.945833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/sh.helm.release.v1.local-path-provisioner.v1\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-01-29T18:43:24.415908Z","caller":"traceutil/trace.go:171","msg":"trace[1814662456] range","detail":"{range_begin:/registry/secrets/local-path-storage/sh.helm.release.v1.local-path-provisioner.v1; range_end:; response_count:1; response_revision:1114; }","duration":"201.175806ms","start":"2026-01-29T18:43:24.214715Z","end":"2026-01-29T18:43:24.415891Z","steps":["trace[1814662456] 'agreement among raft nodes before linearized reading' (duration: 200.899043ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:24.415955Z","caller":"traceutil/trace.go:171","msg":"trace[2032800894] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"295.408867ms","start":"2026-01-29T18:43:24.12053Z","end":"2026-01-29T18:43:24.415939Z","steps":["trace[2032800894] 'process raft request' (duration: 293.384623ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:24.416334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.661374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/\" range_end:\"/registry/serviceaccounts/kube-system0\" limit:500 ","response":"range_response_count:37 size:8042"} {"level":"info","ts":"2026-01-29T18:43:24.417843Z","caller":"traceutil/trace.go:171","msg":"trace[243708681] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/; range_end:/registry/serviceaccounts/kube-system0; response_count:37; response_revision:1114; }","duration":"232.205134ms","start":"2026-01-29T18:43:24.185622Z","end":"2026-01-29T18:43:24.417827Z","steps":["trace[243708681] 'agreement among raft nodes before linearized reading' (duration: 230.470533ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:24.41642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.34124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-6f56467975-sppxf\" ","response":"range_response_count:1 size:3890"} {"level":"info","ts":"2026-01-29T18:43:24.423006Z","caller":"traceutil/trace.go:171","msg":"trace[844285958] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-6f56467975-sppxf; range_end:; response_count:1; response_revision:1114; }","duration":"267.936752ms","start":"2026-01-29T18:43:24.155053Z","end":"2026-01-29T18:43:24.422989Z","steps":["trace[844285958] 'agreement among raft nodes before linearized reading' (duration: 261.307199ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:24.417494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.15126ms","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-01-29T18:43:24.42771Z","caller":"traceutil/trace.go:171","msg":"trace[551766646] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1114; }","duration":"301.370125ms","start":"2026-01-29T18:43:24.126313Z","end":"2026-01-29T18:43:24.427683Z","steps":["trace[551766646] 'agreement among raft nodes before linearized reading' (duration: 289.6984ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:24.427756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:24.126302Z","time spent":"301.439435ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-01-29T18:43:24.663229Z","caller":"traceutil/trace.go:171","msg":"trace[2066230865] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1166; }","duration":"132.432456ms","start":"2026-01-29T18:43:24.530771Z","end":"2026-01-29T18:43:24.663203Z","steps":["trace[2066230865] 'read index received' (duration: 56.618801ms)","trace[2066230865] 'applied index is now lower than readState.Index' (duration: 75.812695ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:24.663588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.799488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-6f56467975-sppxf\" ","response":"range_response_count:1 size:3890"} {"level":"info","ts":"2026-01-29T18:43:24.663633Z","caller":"traceutil/trace.go:171","msg":"trace[972497904] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-6f56467975-sppxf; range_end:; response_count:1; response_revision:1116; }","duration":"132.895469ms","start":"2026-01-29T18:43:24.530726Z","end":"2026-01-29T18:43:24.663622Z","steps":["trace[972497904] 'agreement among raft nodes before linearized reading' (duration: 132.731477ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:24.663813Z","caller":"traceutil/trace.go:171","msg":"trace[925109260] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"229.765707ms","start":"2026-01-29T18:43:24.434035Z","end":"2026-01-29T18:43:24.6638Z","steps":["trace[925109260] 'process raft request' (duration: 153.381709ms)","trace[925109260] 'compare' (duration: 75.629543ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:24.663968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.540243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/sh.helm.release.v1.local-path-provisioner.v1\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-01-29T18:43:24.664063Z","caller":"traceutil/trace.go:171","msg":"trace[26804790] range","detail":"{range_begin:/registry/secrets/local-path-storage/sh.helm.release.v1.local-path-provisioner.v1; range_end:; response_count:1; response_revision:1116; }","duration":"109.008466ms","start":"2026-01-29T18:43:24.555032Z","end":"2026-01-29T18:43:24.664041Z","steps":["trace[26804790] 'agreement among raft nodes before linearized reading' (duration: 108.414592ms)"],"step_count":1}