{"level":"warn","ts":"2026-07-14T17:39:56.978079Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T17:39:56.978188Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.136: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.19.213.136:2380","--initial-cluster=instance=https://199.19.213.136:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.136:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.136: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-07-14T17:39:56.978271Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T17:39:56.978534Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.136:2380"]} {"level":"info","ts":"2026-07-14T17:39:56.97868Z","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-07-14T17:39:56.979863Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.136:2379"]} {"level":"info","ts":"2026-07-14T17:39:56.980053Z","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":8,"max-cpu-available":8,"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.19.213.136:2380"],"listen-peer-urls":["https://199.19.213.136:2380"],"advertise-client-urls":["https://199.19.213.136:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.136:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.136: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-07-14T17:39:57.005751Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"25.401217ms"} {"level":"info","ts":"2026-07-14T17:39:57.045666Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"bcbe0b88c51dc5e5","cluster-id":"e591ddd6f6a32da9"} {"level":"info","ts":"2026-07-14T17:39:57.045816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcbe0b88c51dc5e5 switched to configuration voters=()"} {"level":"info","ts":"2026-07-14T17:39:57.045848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcbe0b88c51dc5e5 became follower at term 0"} {"level":"info","ts":"2026-07-14T17:39:57.045876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft bcbe0b88c51dc5e5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-14T17:39:57.04589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcbe0b88c51dc5e5 became follower at term 1"} {"level":"info","ts":"2026-07-14T17:39:57.045953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcbe0b88c51dc5e5 switched to configuration voters=(13600320606755997157)"} {"level":"warn","ts":"2026-07-14T17:39:57.073811Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-14T17:39:57.079538Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-14T17:39:57.087806Z","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-07-14T17:39:57.091757Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"bcbe0b88c51dc5e5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-14T17:39:57.092267Z","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-07-14T17:39:57.092364Z","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-07-14T17:39:57.092381Z","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-07-14T17:39:57.092338Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"bcbe0b88c51dc5e5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-14T17:39:57.096137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcbe0b88c51dc5e5 switched to configuration voters=(13600320606755997157)"} {"level":"info","ts":"2026-07-14T17:39:57.096259Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e591ddd6f6a32da9","local-member-id":"bcbe0b88c51dc5e5","added-peer-id":"bcbe0b88c51dc5e5","added-peer-peer-urls":["https://199.19.213.136:2380"]} {"level":"info","ts":"2026-07-14T17:39:57.098143Z","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-07-14T17:39:57.098263Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.136:2380"} {"level":"info","ts":"2026-07-14T17:39:57.098318Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.136:2380"} {"level":"info","ts":"2026-07-14T17:39:57.09842Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"bcbe0b88c51dc5e5","initial-advertise-peer-urls":["https://199.19.213.136:2380"],"listen-peer-urls":["https://199.19.213.136:2380"],"advertise-client-urls":["https://199.19.213.136:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.136:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-14T17:39:57.098459Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-14T17:39:57.846967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcbe0b88c51dc5e5 is starting a new election at term 1"} {"level":"info","ts":"2026-07-14T17:39:57.847034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcbe0b88c51dc5e5 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-14T17:39:57.847058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcbe0b88c51dc5e5 received MsgPreVoteResp from bcbe0b88c51dc5e5 at term 1"} {"level":"info","ts":"2026-07-14T17:39:57.847078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcbe0b88c51dc5e5 became candidate at term 2"} {"level":"info","ts":"2026-07-14T17:39:57.84709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcbe0b88c51dc5e5 received MsgVoteResp from bcbe0b88c51dc5e5 at term 2"} {"level":"info","ts":"2026-07-14T17:39:57.847107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcbe0b88c51dc5e5 became leader at term 2"} {"level":"info","ts":"2026-07-14T17:39:57.847121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bcbe0b88c51dc5e5 elected leader bcbe0b88c51dc5e5 at term 2"} {"level":"info","ts":"2026-07-14T17:39:57.848588Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"bcbe0b88c51dc5e5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.136:2379]}","request-path":"/0/members/bcbe0b88c51dc5e5/attributes","cluster-id":"e591ddd6f6a32da9","publish-timeout":"7s"} {"level":"info","ts":"2026-07-14T17:39:57.848832Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T17:39:57.848861Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:39:57.849056Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T17:39:57.849452Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-14T17:39:57.849531Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-14T17:39:57.849984Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e591ddd6f6a32da9","local-member-id":"bcbe0b88c51dc5e5","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:39:57.850089Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:39:57.85013Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:39:57.852542Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.136:2379"} {"level":"info","ts":"2026-07-14T17:39:57.853435Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-14T17:40:00.632296Z","caller":"traceutil/trace.go:171","msg":"trace[785673614] linearizableReadLoop","detail":"{readStateIndex:122; appliedIndex:121; }","duration":"196.855493ms","start":"2026-07-14T17:40:00.435421Z","end":"2026-07-14T17:40:00.632276Z","steps":["trace[785673614] 'read index received' (duration: 98.973218ms)","trace[785673614] 'applied index is now lower than readState.Index' (duration: 97.881225ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:00.632542Z","caller":"traceutil/trace.go:171","msg":"trace[1426519907] transaction","detail":"{read_only:false; response_revision:117; number_of_response:1; }","duration":"214.960363ms","start":"2026-07-14T17:40:00.417567Z","end":"2026-07-14T17:40:00.632527Z","steps":["trace[1426519907] 'process raft request' (duration: 116.775239ms)","trace[1426519907] 'compare' (duration: 97.829644ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:00.632598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.166873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-14T17:40:00.632688Z","caller":"traceutil/trace.go:171","msg":"trace[2054055499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:117; }","duration":"197.307386ms","start":"2026-07-14T17:40:00.435367Z","end":"2026-07-14T17:40:00.632675Z","steps":["trace[2054055499] 'agreement among raft nodes before linearized reading' (duration: 197.173043ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:01.533305Z","caller":"traceutil/trace.go:171","msg":"trace[1836346807] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"170.661454ms","start":"2026-07-14T17:40:01.362617Z","end":"2026-07-14T17:40:01.533279Z","steps":["trace[1836346807] 'process raft request' (duration: 106.705432ms)","trace[1836346807] 'compare' (duration: 63.816458ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:01.588023Z","caller":"traceutil/trace.go:171","msg":"trace[215668674] linearizableReadLoop","detail":"{readStateIndex:211; appliedIndex:209; }","duration":"152.185067ms","start":"2026-07-14T17:40:01.435807Z","end":"2026-07-14T17:40:01.587992Z","steps":["trace[215668674] 'read index received' (duration: 33.53471ms)","trace[215668674] 'applied index is now lower than readState.Index' (duration: 118.649497ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:01.588152Z","caller":"traceutil/trace.go:171","msg":"trace[1022457735] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"188.918237ms","start":"2026-07-14T17:40:01.39918Z","end":"2026-07-14T17:40:01.588098Z","steps":["trace[1022457735] 'process raft request' (duration: 188.69286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:01.588221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.394252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:40:01.588258Z","caller":"traceutil/trace.go:171","msg":"trace[1503622583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:206; }","duration":"152.507396ms","start":"2026-07-14T17:40:01.435738Z","end":"2026-07-14T17:40:01.588245Z","steps":["trace[1503622583] 'agreement among raft nodes before linearized reading' (duration: 152.402563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:01.866057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.968493ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:40:01.866243Z","caller":"traceutil/trace.go:171","msg":"trace[1246667730] linearizableReadLoop","detail":"{readStateIndex:213; appliedIndex:211; }","duration":"266.81065ms","start":"2026-07-14T17:40:01.599417Z","end":"2026-07-14T17:40:01.866228Z","steps":["trace[1246667730] 'read index received' (duration: 39.182598ms)","trace[1246667730] 'applied index is now lower than readState.Index' (duration: 227.625522ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:01.866469Z","caller":"traceutil/trace.go:171","msg":"trace[1448880498] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"273.994322ms","start":"2026-07-14T17:40:01.592405Z","end":"2026-07-14T17:40:01.8664Z","steps":["trace[1448880498] 'process raft request' (duration: 46.218726ms)","trace[1448880498] 'compare' (duration: 226.780058ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:01.866773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.016368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-07-14T17:40:01.866764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.315745ms","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-07-14T17:40:01.86683Z","caller":"traceutil/trace.go:171","msg":"trace[170619270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:208; }","duration":"215.11638ms","start":"2026-07-14T17:40:01.6517Z","end":"2026-07-14T17:40:01.866816Z","steps":["trace[170619270] 'agreement among raft nodes before linearized reading' (duration: 214.951956ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:01.86677Z","caller":"traceutil/trace.go:171","msg":"trace[1216319689] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"270.083801ms","start":"2026-07-14T17:40:01.596595Z","end":"2026-07-14T17:40:01.866678Z","steps":["trace[1216319689] 'process raft request' (duration: 269.575997ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:01.866885Z","caller":"traceutil/trace.go:171","msg":"trace[214441291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:208; }","duration":"216.452589ms","start":"2026-07-14T17:40:01.650374Z","end":"2026-07-14T17:40:01.866826Z","steps":["trace[214441291] 'agreement among raft nodes before linearized reading' (duration: 216.264953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:01.866773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.357616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3283"} {"level":"info","ts":"2026-07-14T17:40:01.867079Z","caller":"traceutil/trace.go:171","msg":"trace[1556218664] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:208; }","duration":"267.691336ms","start":"2026-07-14T17:40:01.599365Z","end":"2026-07-14T17:40:01.867056Z","steps":["trace[1556218664] 'agreement among raft nodes before linearized reading' (duration: 267.129709ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:07.876034Z","caller":"traceutil/trace.go:171","msg":"trace[1855177054] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"100.111766ms","start":"2026-07-14T17:40:07.775893Z","end":"2026-07-14T17:40:07.876004Z","steps":["trace[1855177054] 'process raft request' (duration: 99.9473ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:07.876044Z","caller":"traceutil/trace.go:171","msg":"trace[1141915203] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"100.817195ms","start":"2026-07-14T17:40:07.775182Z","end":"2026-07-14T17:40:07.875999Z","steps":["trace[1141915203] 'process raft request' (duration: 95.784057ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:08.070579Z","caller":"traceutil/trace.go:171","msg":"trace[1961659061] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:361; }","duration":"135.953888ms","start":"2026-07-14T17:40:07.934602Z","end":"2026-07-14T17:40:08.070556Z","steps":["trace[1961659061] 'read index received' (duration: 67.300969ms)","trace[1961659061] 'applied index is now lower than readState.Index' (duration: 68.651729ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:08.070617Z","caller":"traceutil/trace.go:171","msg":"trace[1204192394] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"147.071181ms","start":"2026-07-14T17:40:07.923496Z","end":"2026-07-14T17:40:08.070568Z","steps":["trace[1204192394] 'process raft request' (duration: 78.393361ms)","trace[1204192394] 'compare' (duration: 68.29161ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:08.070749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.446543ms","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-07-14T17:40:08.07085Z","caller":"traceutil/trace.go:171","msg":"trace[490538436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:353; }","duration":"135.507495ms","start":"2026-07-14T17:40:07.935274Z","end":"2026-07-14T17:40:08.070781Z","steps":["trace[490538436] 'agreement among raft nodes before linearized reading' (duration: 135.419302ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:08.070639Z","caller":"traceutil/trace.go:171","msg":"trace[1883859543] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"142.461395ms","start":"2026-07-14T17:40:07.928157Z","end":"2026-07-14T17:40:08.070619Z","steps":["trace[1883859543] 'process raft request' (duration: 142.26284ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:08.070874Z","caller":"traceutil/trace.go:171","msg":"trace[1959486075] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"138.658771ms","start":"2026-07-14T17:40:07.932203Z","end":"2026-07-14T17:40:08.070862Z","steps":["trace[1959486075] 'process raft request' (duration: 138.304511ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:08.070749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.135462ms","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-07-14T17:40:08.0711Z","caller":"traceutil/trace.go:171","msg":"trace[160860181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:353; }","duration":"136.506833ms","start":"2026-07-14T17:40:07.934573Z","end":"2026-07-14T17:40:08.07108Z","steps":["trace[160860181] 'agreement among raft nodes before linearized reading' (duration: 136.114982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:08.071476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.10815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-07-14T17:40:08.071783Z","caller":"traceutil/trace.go:171","msg":"trace[1909691284] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:353; }","duration":"110.45822ms","start":"2026-07-14T17:40:07.961299Z","end":"2026-07-14T17:40:08.071757Z","steps":["trace[1909691284] 'agreement among raft nodes before linearized reading' (duration: 109.807272ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:08.349967Z","caller":"traceutil/trace.go:171","msg":"trace[1365581484] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"271.514787ms","start":"2026-07-14T17:40:08.078436Z","end":"2026-07-14T17:40:08.349951Z","steps":["trace[1365581484] 'process raft request' (duration: 271.351293ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:08.350067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.55085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-07-14T17:40:08.350128Z","caller":"traceutil/trace.go:171","msg":"trace[475345577] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"273.012729ms","start":"2026-07-14T17:40:08.077108Z","end":"2026-07-14T17:40:08.350121Z","steps":["trace[475345577] 'process raft request' (duration: 175.746252ms)","trace[475345577] 'compare' (duration: 96.722612ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:08.350193Z","caller":"traceutil/trace.go:171","msg":"trace[2120200219] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"272.935817ms","start":"2026-07-14T17:40:08.077253Z","end":"2026-07-14T17:40:08.350189Z","steps":["trace[2120200219] 'process raft request' (duration: 272.453194ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:08.350296Z","caller":"traceutil/trace.go:171","msg":"trace[809999801] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"272.468783ms","start":"2026-07-14T17:40:08.077822Z","end":"2026-07-14T17:40:08.350291Z","steps":["trace[809999801] 'process raft request' (duration: 271.921668ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:08.349966Z","caller":"traceutil/trace.go:171","msg":"trace[1750901011] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:364; }","duration":"238.442007ms","start":"2026-07-14T17:40:08.111508Z","end":"2026-07-14T17:40:08.34995Z","steps":["trace[1750901011] 'read index received' (duration: 18.939605ms)","trace[1750901011] 'applied index is now lower than readState.Index' (duration: 219.501382ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:08.350431Z","caller":"traceutil/trace.go:171","msg":"trace[547711076] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"271.60362ms","start":"2026-07-14T17:40:08.078818Z","end":"2026-07-14T17:40:08.350422Z","steps":["trace[547711076] 'process raft request' (duration: 271.006424ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:08.350322Z","caller":"traceutil/trace.go:171","msg":"trace[191223602] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:361; }","duration":"238.839368ms","start":"2026-07-14T17:40:08.111449Z","end":"2026-07-14T17:40:08.350288Z","steps":["trace[191223602] 'agreement among raft nodes before linearized reading' (duration: 238.559421ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:08.350686Z","caller":"traceutil/trace.go:171","msg":"trace[365827325] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"270.727856ms","start":"2026-07-14T17:40:08.079949Z","end":"2026-07-14T17:40:08.350677Z","steps":["trace[365827325] 'process raft request' (duration: 269.918124ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:08.350792Z","caller":"traceutil/trace.go:171","msg":"trace[1907594713] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"269.543764ms","start":"2026-07-14T17:40:08.081237Z","end":"2026-07-14T17:40:08.35078Z","steps":["trace[1907594713] 'process raft request' (duration: 268.66619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:08.35101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.035893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-14T17:40:08.351131Z","caller":"traceutil/trace.go:171","msg":"trace[1308377650] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:361; }","duration":"189.202888ms","start":"2026-07-14T17:40:08.161911Z","end":"2026-07-14T17:40:08.351114Z","steps":["trace[1308377650] 'agreement among raft nodes before linearized reading' (duration: 189.055004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:08.351219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.195427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-14T17:40:08.351255Z","caller":"traceutil/trace.go:171","msg":"trace[1544397071] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:361; }","duration":"139.28164ms","start":"2026-07-14T17:40:08.211965Z","end":"2026-07-14T17:40:08.351247Z","steps":["trace[1544397071] 'agreement among raft nodes before linearized reading' (duration: 139.167177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:08.351054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.414193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-14T17:40:08.35139Z","caller":"traceutil/trace.go:171","msg":"trace[571722806] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:361; }","duration":"121.802344ms","start":"2026-07-14T17:40:08.229568Z","end":"2026-07-14T17:40:08.351371Z","steps":["trace[571722806] 'agreement among raft nodes before linearized reading' (duration: 121.399103ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:08.517493Z","caller":"traceutil/trace.go:171","msg":"trace[1461331416] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"158.08551ms","start":"2026-07-14T17:40:08.359383Z","end":"2026-07-14T17:40:08.517468Z","steps":["trace[1461331416] 'process raft request' (duration: 121.638048ms)","trace[1461331416] 'compare' (duration: 36.135544ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:08.517533Z","caller":"traceutil/trace.go:171","msg":"trace[1439766707] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"157.015143ms","start":"2026-07-14T17:40:08.360491Z","end":"2026-07-14T17:40:08.517507Z","steps":["trace[1439766707] 'process raft request' (duration: 156.9486ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:08.517753Z","caller":"traceutil/trace.go:171","msg":"trace[1167211510] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"157.963489ms","start":"2026-07-14T17:40:08.359781Z","end":"2026-07-14T17:40:08.517745Z","steps":["trace[1167211510] 'process raft request' (duration: 157.624899ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:08.517843Z","caller":"traceutil/trace.go:171","msg":"trace[701086902] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"158.328048ms","start":"2026-07-14T17:40:08.359509Z","end":"2026-07-14T17:40:08.517837Z","steps":["trace[701086902] 'process raft request' (duration: 157.826984ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:08.518401Z","caller":"traceutil/trace.go:171","msg":"trace[101204008] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:372; }","duration":"156.717504ms","start":"2026-07-14T17:40:08.361673Z","end":"2026-07-14T17:40:08.518391Z","steps":["trace[101204008] 'read index received' (duration: 69.919662ms)","trace[101204008] 'applied index is now lower than readState.Index' (duration: 86.797032ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:08.518414Z","caller":"traceutil/trace.go:171","msg":"trace[1103823932] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"156.872408ms","start":"2026-07-14T17:40:08.36152Z","end":"2026-07-14T17:40:08.518393Z","steps":["trace[1103823932] 'process raft request' (duration: 155.958843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:08.518537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.841277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-14T17:40:08.518494Z","caller":"traceutil/trace.go:171","msg":"trace[2128571538] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"156.840957ms","start":"2026-07-14T17:40:08.361559Z","end":"2026-07-14T17:40:08.5184Z","steps":["trace[2128571538] 'process raft request' (duration: 156.689153ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:08.518745Z","caller":"traceutil/trace.go:171","msg":"trace[1790389901] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"154.38976ms","start":"2026-07-14T17:40:08.364347Z","end":"2026-07-14T17:40:08.518737Z","steps":["trace[1790389901] 'process raft request' (duration: 154.00732ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:08.518572Z","caller":"traceutil/trace.go:171","msg":"trace[1288919962] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:370; }","duration":"156.90689ms","start":"2026-07-14T17:40:08.361654Z","end":"2026-07-14T17:40:08.518561Z","steps":["trace[1288919962] 'agreement among raft nodes before linearized reading' (duration: 156.779246ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:08.51861Z","caller":"traceutil/trace.go:171","msg":"trace[320844961] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"156.024936ms","start":"2026-07-14T17:40:08.362577Z","end":"2026-07-14T17:40:08.518602Z","steps":["trace[320844961] 'process raft request' (duration: 155.739168ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:08.957961Z","caller":"traceutil/trace.go:171","msg":"trace[529760145] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"429.602499ms","start":"2026-07-14T17:40:08.52834Z","end":"2026-07-14T17:40:08.957942Z","steps":["trace[529760145] 'process raft request' (duration: 343.66208ms)","trace[529760145] 'compare' (duration: 85.322892ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:08.958297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:08.528316Z","time spent":"429.777824ms","remote":"127.0.0.1:48328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2259,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:40:08.958148Z","caller":"traceutil/trace.go:171","msg":"trace[728271513] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"429.63293ms","start":"2026-07-14T17:40:08.528447Z","end":"2026-07-14T17:40:08.95808Z","steps":["trace[728271513] 'process raft request' (duration: 429.379543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:08.95865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:08.528439Z","time spent":"430.117012ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:40:08.958964Z","caller":"traceutil/trace.go:171","msg":"trace[1708537609] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"378.380025ms","start":"2026-07-14T17:40:08.580567Z","end":"2026-07-14T17:40:08.958947Z","steps":["trace[1708537609] 'process raft request' (duration: 378.129458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:08.959084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:08.580538Z","time spent":"378.486028ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3424,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:40:08.95912Z","caller":"traceutil/trace.go:171","msg":"trace[1304096534] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:382; }","duration":"103.021223ms","start":"2026-07-14T17:40:08.856086Z","end":"2026-07-14T17:40:08.959107Z","steps":["trace[1304096534] 'read index received' (duration: 15.926694ms)","trace[1304096534] 'applied index is now lower than readState.Index' (duration: 87.093499ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:08.959321Z","caller":"traceutil/trace.go:171","msg":"trace[738808307] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"378.550769ms","start":"2026-07-14T17:40:08.58072Z","end":"2026-07-14T17:40:08.959271Z","steps":["trace[738808307] 'process raft request' (duration: 378.306173ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:08.959524Z","caller":"traceutil/trace.go:171","msg":"trace[1036292582] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"374.857299ms","start":"2026-07-14T17:40:08.584654Z","end":"2026-07-14T17:40:08.959511Z","steps":["trace[1036292582] 'process raft request' (duration: 374.416677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:08.959601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:08.584633Z","time spent":"374.940832ms","remote":"127.0.0.1:48520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:40:08.959454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:08.580643Z","time spent":"378.749105ms","remote":"127.0.0.1:48166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:40:08.959772Z","caller":"traceutil/trace.go:171","msg":"trace[96369860] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"379.032422ms","start":"2026-07-14T17:40:08.580722Z","end":"2026-07-14T17:40:08.959755Z","steps":["trace[96369860] 'process raft request' (duration: 378.17994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:08.959347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.259809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-07-14T17:40:08.959901Z","caller":"traceutil/trace.go:171","msg":"trace[1473296447] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:377; }","duration":"103.835275ms","start":"2026-07-14T17:40:08.856036Z","end":"2026-07-14T17:40:08.959871Z","steps":["trace[1473296447] 'agreement among raft nodes before linearized reading' (duration: 103.163997ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:08.959894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:08.580696Z","time spent":"379.122154ms","remote":"127.0.0.1:48550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2886,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:40:09.145843Z","caller":"traceutil/trace.go:171","msg":"trace[986667120] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"124.267427ms","start":"2026-07-14T17:40:09.021567Z","end":"2026-07-14T17:40:09.145834Z","steps":["trace[986667120] 'process raft request' (duration: 124.098043ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:09.145958Z","caller":"traceutil/trace.go:171","msg":"trace[1759458137] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"127.265578ms","start":"2026-07-14T17:40:09.01868Z","end":"2026-07-14T17:40:09.145946Z","steps":["trace[1759458137] 'process raft request' (duration: 61.716432ms)","trace[1759458137] 'compare' (duration: 64.892439ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:09.145786Z","caller":"traceutil/trace.go:171","msg":"trace[723599206] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"123.298061ms","start":"2026-07-14T17:40:09.022468Z","end":"2026-07-14T17:40:09.145766Z","steps":["trace[723599206] 'process raft request' (duration: 123.251079ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:09.172123Z","caller":"traceutil/trace.go:171","msg":"trace[1240679358] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"121.262245ms","start":"2026-07-14T17:40:09.05083Z","end":"2026-07-14T17:40:09.172093Z","steps":["trace[1240679358] 'process raft request' (duration: 121.034149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:09.234605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.875963ms","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-07-14T17:40:09.234699Z","caller":"traceutil/trace.go:171","msg":"trace[1629123469] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:389; }","duration":"149.004828ms","start":"2026-07-14T17:40:09.085674Z","end":"2026-07-14T17:40:09.234679Z","steps":["trace[1629123469] 'agreement among raft nodes before linearized reading' (duration: 148.830622ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:09.339493Z","caller":"traceutil/trace.go:171","msg":"trace[76392257] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"161.971088ms","start":"2026-07-14T17:40:09.177495Z","end":"2026-07-14T17:40:09.339466Z","steps":["trace[76392257] 'process raft request' (duration: 87.449189ms)","trace[76392257] 'compare' (duration: 74.315704ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:09.33953Z","caller":"traceutil/trace.go:171","msg":"trace[169175448] linearizableReadLoop","detail":"{readStateIndex:401; appliedIndex:400; }","duration":"103.409251ms","start":"2026-07-14T17:40:09.236092Z","end":"2026-07-14T17:40:09.339501Z","steps":["trace[169175448] 'read index received' (duration: 28.867462ms)","trace[169175448] 'applied index is now lower than readState.Index' (duration: 74.539079ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:09.339727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.613397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-kkp84\" ","response":"range_response_count:1 size:4466"} {"level":"info","ts":"2026-07-14T17:40:09.339816Z","caller":"traceutil/trace.go:171","msg":"trace[1117254459] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-kkp84; range_end:; response_count:1; response_revision:391; }","duration":"103.759491ms","start":"2026-07-14T17:40:09.236035Z","end":"2026-07-14T17:40:09.339795Z","steps":["trace[1117254459] 'agreement among raft nodes before linearized reading' (duration: 103.613797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:09.339953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.49172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-07-14T17:40:09.340012Z","caller":"traceutil/trace.go:171","msg":"trace[1470054540] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:391; }","duration":"101.615393ms","start":"2026-07-14T17:40:09.238381Z","end":"2026-07-14T17:40:09.339996Z","steps":["trace[1470054540] 'agreement among raft nodes before linearized reading' (duration: 101.298834ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:17.730478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.155066ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-14T17:40:27.064604Z","caller":"traceutil/trace.go:171","msg":"trace[587927699] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"124.609024ms","start":"2026-07-14T17:40:26.93997Z","end":"2026-07-14T17:40:27.064579Z","steps":["trace[587927699] 'process raft request' (duration: 124.45388ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:40.386975Z","caller":"traceutil/trace.go:171","msg":"trace[349628587] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"281.738781ms","start":"2026-07-14T17:40:40.105196Z","end":"2026-07-14T17:40:40.386934Z","steps":["trace[349628587] 'process raft request' (duration: 273.467162ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:40.388864Z","caller":"traceutil/trace.go:171","msg":"trace[1730610091] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"239.320183ms","start":"2026-07-14T17:40:40.146348Z","end":"2026-07-14T17:40:40.385668Z","steps":["trace[1730610091] 'process raft request' (duration: 239.147949ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:42.439471Z","caller":"traceutil/trace.go:171","msg":"trace[323231748] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"159.816172ms","start":"2026-07-14T17:40:42.279615Z","end":"2026-07-14T17:40:42.439431Z","steps":["trace[323231748] 'process raft request' (duration: 149.67517ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:42.439652Z","caller":"traceutil/trace.go:171","msg":"trace[770927168] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"151.019562ms","start":"2026-07-14T17:40:42.288601Z","end":"2026-07-14T17:40:42.439621Z","steps":["trace[770927168] 'process raft request' (duration: 150.839727ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:42.439703Z","caller":"traceutil/trace.go:171","msg":"trace[330318002] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"128.571058ms","start":"2026-07-14T17:40:42.31112Z","end":"2026-07-14T17:40:42.439691Z","steps":["trace[330318002] 'process raft request' (duration: 128.529137ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:42.439734Z","caller":"traceutil/trace.go:171","msg":"trace[77256121] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"150.135121ms","start":"2026-07-14T17:40:42.289581Z","end":"2026-07-14T17:40:42.439716Z","steps":["trace[77256121] 'process raft request' (duration: 149.924276ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:42.439785Z","caller":"traceutil/trace.go:171","msg":"trace[403652615] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"145.92256ms","start":"2026-07-14T17:40:42.293817Z","end":"2026-07-14T17:40:42.43974Z","steps":["trace[403652615] 'process raft request' (duration: 145.736426ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:42.439845Z","caller":"traceutil/trace.go:171","msg":"trace[479105978] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"150.300026ms","start":"2026-07-14T17:40:42.289534Z","end":"2026-07-14T17:40:42.439834Z","steps":["trace[479105978] 'process raft request' (duration: 149.935537ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:42.439861Z","caller":"traceutil/trace.go:171","msg":"trace[489066192] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"153.081831ms","start":"2026-07-14T17:40:42.286771Z","end":"2026-07-14T17:40:42.439853Z","steps":["trace[489066192] 'process raft request' (duration: 152.62394ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:42.439828Z","caller":"traceutil/trace.go:171","msg":"trace[1310085702] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"158.168942ms","start":"2026-07-14T17:40:42.281648Z","end":"2026-07-14T17:40:42.439817Z","steps":["trace[1310085702] 'process raft request' (duration: 157.69832ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:42.439938Z","caller":"traceutil/trace.go:171","msg":"trace[307002317] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"143.930924ms","start":"2026-07-14T17:40:42.295996Z","end":"2026-07-14T17:40:42.439927Z","steps":["trace[307002317] 'process raft request' (duration: 143.610026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:43.080347Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14259979037165877957,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:40:44.000704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.179932ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:40:44.001162Z","caller":"traceutil/trace.go:171","msg":"trace[247598626] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"1.614389444s","start":"2026-07-14T17:40:42.386747Z","end":"2026-07-14T17:40:44.001136Z","steps":["trace[247598626] 'process raft request' (duration: 1.614101437s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:44.001256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:42.386725Z","time spent":"1.614492057s","remote":"127.0.0.1:48600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:40:44.001261Z","caller":"traceutil/trace.go:171","msg":"trace[2062190679] transaction","detail":"{read_only:false; number_of_response:1; response_revision:556; }","duration":"1.613436912s","start":"2026-07-14T17:40:42.387803Z","end":"2026-07-14T17:40:44.00124Z","steps":["trace[2062190679] 'process raft request' (duration: 1.613302828s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:44.001349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:42.387787Z","time spent":"1.613511364s","remote":"127.0.0.1:48142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10529,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:40:44.001439Z","caller":"traceutil/trace.go:171","msg":"trace[908431456] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.629684619s","start":"2026-07-14T17:40:42.371742Z","end":"2026-07-14T17:40:44.001426Z","steps":["trace[908431456] 'process raft request' (duration: 711.688798ms)","trace[908431456] 'compare' (duration: 916.898835ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:44.001492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:42.37172Z","time spent":"1.62974361s","remote":"127.0.0.1:48600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:40:44.00153Z","caller":"traceutil/trace.go:171","msg":"trace[34957856] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"1.614667291s","start":"2026-07-14T17:40:42.386847Z","end":"2026-07-14T17:40:44.001514Z","steps":["trace[34957856] 'process raft request' (duration: 1.614084807s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:44.001648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:42.38682Z","time spent":"1.614763513s","remote":"127.0.0.1:48142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10479,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:40:44.001669Z","caller":"traceutil/trace.go:171","msg":"trace[571178802] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"1.600805901s","start":"2026-07-14T17:40:42.400836Z","end":"2026-07-14T17:40:44.001642Z","steps":["trace[571178802] 'process raft request' (duration: 1.60034551s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:44.001804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:42.400817Z","time spent":"1.600915133s","remote":"127.0.0.1:48142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:40:44.01872Z","caller":"traceutil/trace.go:171","msg":"trace[1972566038] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"1.560950751s","start":"2026-07-14T17:40:42.457753Z","end":"2026-07-14T17:40:44.018704Z","steps":["trace[1972566038] 'process raft request' (duration: 1.560842039s)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:44.019256Z","caller":"traceutil/trace.go:171","msg":"trace[922424223] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"1.56134201s","start":"2026-07-14T17:40:42.457884Z","end":"2026-07-14T17:40:44.019226Z","steps":["trace[922424223] 'process raft request' (duration: 1.560733276s)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:44.019196Z","caller":"traceutil/trace.go:171","msg":"trace[677074987] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"1.571233635s","start":"2026-07-14T17:40:42.44744Z","end":"2026-07-14T17:40:44.018674Z","steps":["trace[677074987] 'process raft request' (duration: 1.571051651s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:44.019428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:42.457732Z","time spent":"1.561548955s","remote":"127.0.0.1:48600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T17:40:44.019462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:42.457866Z","time spent":"1.561445072s","remote":"127.0.0.1:48142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:40:44.019482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:42.447414Z","time spent":"1.571980264s","remote":"127.0.0.1:48600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:40:44.020514Z","caller":"traceutil/trace.go:171","msg":"trace[1871235006] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"1.560804787s","start":"2026-07-14T17:40:42.459694Z","end":"2026-07-14T17:40:44.020498Z","steps":["trace[1871235006] 'process raft request' (duration: 1.559346182s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:44.020685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:42.459687Z","time spent":"1.560859999s","remote":"127.0.0.1:48142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:40:44.021701Z","caller":"traceutil/trace.go:171","msg":"trace[1277490090] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"1.56178914s","start":"2026-07-14T17:40:42.459884Z","end":"2026-07-14T17:40:44.021673Z","steps":["trace[1277490090] 'process raft request' (duration: 1.560183633s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:44.02179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:42.459878Z","time spent":"1.561862252s","remote":"127.0.0.1:48600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:40:44.026422Z","caller":"traceutil/trace.go:171","msg":"trace[371373844] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.562679701s","start":"2026-07-14T17:40:42.463712Z","end":"2026-07-14T17:40:44.026392Z","steps":["trace[371373844] 'process raft request' (duration: 1.557187821s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:44.026546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:42.463692Z","time spent":"1.562784524s","remote":"127.0.0.1:48142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:40:44.026813Z","caller":"traceutil/trace.go:171","msg":"trace[1281498677] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:573; }","duration":"1.446553349s","start":"2026-07-14T17:40:42.580209Z","end":"2026-07-14T17:40:44.026763Z","steps":["trace[1281498677] 'read index received' (duration: 503.26032ms)","trace[1281498677] 'applied index is now lower than readState.Index' (duration: 943.292249ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:44.026822Z","caller":"traceutil/trace.go:171","msg":"trace[1555053708] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"1.559322921s","start":"2026-07-14T17:40:42.46749Z","end":"2026-07-14T17:40:44.026813Z","steps":["trace[1555053708] 'process raft request' (duration: 1.5588668s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:44.026954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.446719293s","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-07-14T17:40:44.027005Z","caller":"traceutil/trace.go:171","msg":"trace[1960896495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:565; }","duration":"1.446839966s","start":"2026-07-14T17:40:42.580156Z","end":"2026-07-14T17:40:44.026996Z","steps":["trace[1960896495] 'agreement among raft nodes before linearized reading' (duration: 1.446721263s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:44.027045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:42.580133Z","time spent":"1.446888497s","remote":"127.0.0.1:48334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-14T17:40:44.026855Z","caller":"traceutil/trace.go:171","msg":"trace[2136203151] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"1.505793508s","start":"2026-07-14T17:40:42.521055Z","end":"2026-07-14T17:40:44.026848Z","steps":["trace[2136203151] 'process raft request' (duration: 1.505634284s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:44.027388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:42.521019Z","time spent":"1.50633319s","remote":"127.0.0.1:48142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T17:40:44.027714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:42.467472Z","time spent":"1.559502694s","remote":"127.0.0.1:48142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:40:44.056292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.475938223s","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-07-14T17:40:44.056383Z","caller":"traceutil/trace.go:171","msg":"trace[2132854621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:567; }","duration":"1.476101336s","start":"2026-07-14T17:40:42.58025Z","end":"2026-07-14T17:40:44.056351Z","steps":["trace[2132854621] 'agreement among raft nodes before linearized reading' (duration: 1.47582338s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:44.056424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:42.580244Z","time spent":"1.476171239s","remote":"127.0.0.1:48334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T17:40:44.056811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.253311409s","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-07-14T17:40:44.056845Z","caller":"traceutil/trace.go:171","msg":"trace[1190680103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:567; }","duration":"1.253380501s","start":"2026-07-14T17:40:42.803457Z","end":"2026-07-14T17:40:44.056837Z","steps":["trace[1190680103] 'agreement among raft nodes before linearized reading' (duration: 1.253322779s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:44.056874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:42.803434Z","time spent":"1.253434052s","remote":"127.0.0.1:48334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-14T17:40:44.057022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087060665s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:40:44.057168Z","caller":"traceutil/trace.go:171","msg":"trace[1963027136] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"1.087264739s","start":"2026-07-14T17:40:42.969866Z","end":"2026-07-14T17:40:44.057131Z","steps":["trace[1963027136] 'agreement among raft nodes before linearized reading' (duration: 1.086890771s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:44.05726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:42.969848Z","time spent":"1.087402622s","remote":"127.0.0.1:48070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T17:40:44.057596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.296744ms","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-07-14T17:40:44.057704Z","caller":"traceutil/trace.go:171","msg":"trace[822986442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:567; }","duration":"431.441068ms","start":"2026-07-14T17:40:43.626232Z","end":"2026-07-14T17:40:44.057673Z","steps":["trace[822986442] 'agreement among raft nodes before linearized reading' (duration: 431.314975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:44.057788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:43.626204Z","time spent":"431.55471ms","remote":"127.0.0.1:48334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T17:40:44.485617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.544502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:40:44.486004Z","caller":"traceutil/trace.go:171","msg":"trace[755118244] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"275.745539ms","start":"2026-07-14T17:40:44.210244Z","end":"2026-07-14T17:40:44.485989Z","steps":["trace[755118244] 'process raft request' (duration: 275.474543ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:44.486113Z","caller":"traceutil/trace.go:171","msg":"trace[1904583196] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"280.515812ms","start":"2026-07-14T17:40:44.205552Z","end":"2026-07-14T17:40:44.486068Z","steps":["trace[1904583196] 'process raft request' (duration: 174.438368ms)","trace[1904583196] 'compare' (duration: 105.394118ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:44.488754Z","caller":"traceutil/trace.go:171","msg":"trace[1253874506] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"233.123163ms","start":"2026-07-14T17:40:44.255616Z","end":"2026-07-14T17:40:44.48874Z","steps":["trace[1253874506] 'process raft request' (duration: 232.900378ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:44.488708Z","caller":"traceutil/trace.go:171","msg":"trace[1595408049] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"191.172263ms","start":"2026-07-14T17:40:44.297519Z","end":"2026-07-14T17:40:44.488691Z","steps":["trace[1595408049] 'process raft request' (duration: 191.085641ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:44.67746Z","caller":"traceutil/trace.go:171","msg":"trace[784875173] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"173.837754ms","start":"2026-07-14T17:40:44.503587Z","end":"2026-07-14T17:40:44.677424Z","steps":["trace[784875173] 'process raft request' (duration: 136.239847ms)","trace[784875173] 'compare' (duration: 37.326981ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:44.677578Z","caller":"traceutil/trace.go:171","msg":"trace[1253368289] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"133.454039ms","start":"2026-07-14T17:40:44.544102Z","end":"2026-07-14T17:40:44.677556Z","steps":["trace[1253368289] 'process raft request' (duration: 133.189183ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:44.677534Z","caller":"traceutil/trace.go:171","msg":"trace[777722389] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"126.23624ms","start":"2026-07-14T17:40:44.551288Z","end":"2026-07-14T17:40:44.677524Z","steps":["trace[777722389] 'process raft request' (duration: 126.126097ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:48.162171Z","caller":"traceutil/trace.go:171","msg":"trace[1131214790] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"147.103672ms","start":"2026-07-14T17:40:48.014988Z","end":"2026-07-14T17:40:48.162092Z","steps":["trace[1131214790] 'process raft request' (duration: 139.515576ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:53.258942Z","caller":"traceutil/trace.go:171","msg":"trace[865757231] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"326.60899ms","start":"2026-07-14T17:40:52.932314Z","end":"2026-07-14T17:40:53.258923Z","steps":["trace[865757231] 'process raft request' (duration: 326.467317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:53.259013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.517611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:40:53.259041Z","caller":"traceutil/trace.go:171","msg":"trace[2113633882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"289.610503ms","start":"2026-07-14T17:40:52.969422Z","end":"2026-07-14T17:40:53.259033Z","steps":["trace[2113633882] 'agreement among raft nodes before linearized reading' (duration: 289.544841ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:53.259071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:52.932281Z","time spent":"326.724773ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:40:53.258929Z","caller":"traceutil/trace.go:171","msg":"trace[1470917774] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:692; }","duration":"289.425678ms","start":"2026-07-14T17:40:52.969482Z","end":"2026-07-14T17:40:53.258908Z","steps":["trace[1470917774] 'read index received' (duration: 289.398857ms)","trace[1470917774] 'applied index is now lower than readState.Index' (duration: 26.061µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:54.803216Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14259979037165878171,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T17:40:55.06414Z","caller":"traceutil/trace.go:171","msg":"trace[1378099872] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"838.416149ms","start":"2026-07-14T17:40:54.225694Z","end":"2026-07-14T17:40:55.06411Z","steps":["trace[1378099872] 'process raft request' (duration: 838.234915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:55.064477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:54.225655Z","time spent":"838.671585ms","remote":"127.0.0.1:48334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:40:55.242629Z","caller":"traceutil/trace.go:171","msg":"trace[460974428] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:693; }","duration":"939.502618ms","start":"2026-07-14T17:40:54.303102Z","end":"2026-07-14T17:40:55.242604Z","steps":["trace[460974428] 'read index received' (duration: 760.734621ms)","trace[460974428] 'applied index is now lower than readState.Index' (duration: 178.766637ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:55.242711Z","caller":"traceutil/trace.go:171","msg":"trace[2001222351] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"591.062242ms","start":"2026-07-14T17:40:54.651613Z","end":"2026-07-14T17:40:55.242675Z","steps":["trace[2001222351] 'process raft request' (duration: 590.827796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:55.242805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.688093ms","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-07-14T17:40:55.242846Z","caller":"traceutil/trace.go:171","msg":"trace[1668931634] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:670; }","duration":"939.791455ms","start":"2026-07-14T17:40:54.303044Z","end":"2026-07-14T17:40:55.242835Z","steps":["trace[1668931634] 'agreement among raft nodes before linearized reading' (duration: 939.691923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:55.242864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:54.651588Z","time spent":"591.190925ms","remote":"127.0.0.1:48334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:40:55.242885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:54.303022Z","time spent":"939.854536ms","remote":"127.0.0.1:48334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T17:40:55.243093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.376468ms","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-07-14T17:40:55.243122Z","caller":"traceutil/trace.go:171","msg":"trace[475813892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:670; }","duration":"922.442429ms","start":"2026-07-14T17:40:54.320672Z","end":"2026-07-14T17:40:55.243114Z","steps":["trace[475813892] 'agreement among raft nodes before linearized reading' (duration: 922.330036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:55.243145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:54.320652Z","time spent":"922.48789ms","remote":"127.0.0.1:48334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T17:40:55.243281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.29773ms","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-07-14T17:40:55.243319Z","caller":"traceutil/trace.go:171","msg":"trace[1237479889] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:670; }","duration":"920.354561ms","start":"2026-07-14T17:40:54.322955Z","end":"2026-07-14T17:40:55.243309Z","steps":["trace[1237479889] 'agreement among raft nodes before linearized reading' (duration: 920.147626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:55.243345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:54.322946Z","time spent":"920.392933ms","remote":"127.0.0.1:48334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-14T17:40:57.352633Z","caller":"traceutil/trace.go:171","msg":"trace[1735733845] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"213.047092ms","start":"2026-07-14T17:40:57.139557Z","end":"2026-07-14T17:40:57.352604Z","steps":["trace[1735733845] 'process raft request' (duration: 212.889518ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:57.362024Z","caller":"traceutil/trace.go:171","msg":"trace[828918694] linearizableReadLoop","detail":"{readStateIndex:723; appliedIndex:721; }","duration":"101.957987ms","start":"2026-07-14T17:40:57.260038Z","end":"2026-07-14T17:40:57.361996Z","steps":["trace[828918694] 'read index received' (duration: 92.462594ms)","trace[828918694] 'applied index is now lower than readState.Index' (duration: 9.493923ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:57.36247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.403289ms","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-07-14T17:40:57.362544Z","caller":"traceutil/trace.go:171","msg":"trace[755029082] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:699; }","duration":"102.534721ms","start":"2026-07-14T17:40:57.259969Z","end":"2026-07-14T17:40:57.362504Z","steps":["trace[755029082] 'agreement among raft nodes before linearized reading' (duration: 102.310815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:57.367407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.48038ms","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-07-14T17:40:57.367482Z","caller":"traceutil/trace.go:171","msg":"trace[853907982] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:700; }","duration":"106.574892ms","start":"2026-07-14T17:40:57.260896Z","end":"2026-07-14T17:40:57.367471Z","steps":["trace[853907982] 'agreement among raft nodes before linearized reading' (duration: 106.412178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:57.367469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.327409ms","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-07-14T17:40:57.367583Z","caller":"traceutil/trace.go:171","msg":"trace[1926770684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:700; }","duration":"107.471312ms","start":"2026-07-14T17:40:57.260098Z","end":"2026-07-14T17:40:57.36757Z","steps":["trace[1926770684] 'agreement among raft nodes before linearized reading' (duration: 107.203826ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:11.38452Z","caller":"traceutil/trace.go:171","msg":"trace[58728446] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"214.82788ms","start":"2026-07-14T17:41:11.169669Z","end":"2026-07-14T17:41:11.384497Z","steps":["trace[58728446] 'process raft request' (duration: 214.566844ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:11.786034Z","caller":"traceutil/trace.go:171","msg":"trace[385303089] linearizableReadLoop","detail":"{readStateIndex:860; appliedIndex:859; }","duration":"315.422671ms","start":"2026-07-14T17:41:11.470593Z","end":"2026-07-14T17:41:11.786016Z","steps":["trace[385303089] 'read index received' (duration: 315.212087ms)","trace[385303089] 'applied index is now lower than readState.Index' (duration: 209.694µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:11.786136Z","caller":"traceutil/trace.go:171","msg":"trace[837438920] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"318.479787ms","start":"2026-07-14T17:41:11.467634Z","end":"2026-07-14T17:41:11.786114Z","steps":["trace[837438920] 'process raft request' (duration: 318.227262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:11.786285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.685927ms","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-07-14T17:41:11.786342Z","caller":"traceutil/trace.go:171","msg":"trace[799378731] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:830; }","duration":"315.756318ms","start":"2026-07-14T17:41:11.470577Z","end":"2026-07-14T17:41:11.786334Z","steps":["trace[799378731] 'agreement among raft nodes before linearized reading' (duration: 315.544644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:11.786324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:11.467599Z","time spent":"318.63151ms","remote":"127.0.0.1:48334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:41:11.786368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:11.470522Z","time spent":"315.84021ms","remote":"127.0.0.1:48334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-14T17:41:11.786799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.347495ms","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-07-14T17:41:11.786919Z","caller":"traceutil/trace.go:171","msg":"trace[1716783950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:830; }","duration":"278.497749ms","start":"2026-07-14T17:41:11.50838Z","end":"2026-07-14T17:41:11.786878Z","steps":["trace[1716783950] 'agreement among raft nodes before linearized reading' (duration: 277.911185ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:26.201886Z","caller":"traceutil/trace.go:171","msg":"trace[1640145389] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"294.147669ms","start":"2026-07-14T17:41:25.907709Z","end":"2026-07-14T17:41:26.201857Z","steps":["trace[1640145389] 'process raft request' (duration: 293.989526ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:26.22906Z","caller":"traceutil/trace.go:171","msg":"trace[538947519] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1036; }","duration":"117.131723ms","start":"2026-07-14T17:41:26.111905Z","end":"2026-07-14T17:41:26.229037Z","steps":["trace[538947519] 'read index received' (duration: 89.795202ms)","trace[538947519] 'applied index is now lower than readState.Index' (duration: 27.335661ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:26.229198Z","caller":"traceutil/trace.go:171","msg":"trace[664021487] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"253.020271ms","start":"2026-07-14T17:41:25.976146Z","end":"2026-07-14T17:41:26.229166Z","steps":["trace[664021487] 'process raft request' (duration: 252.725075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:26.229293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.380497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-679c578f5-45r95\" ","response":"range_response_count:1 size:3611"} {"level":"info","ts":"2026-07-14T17:41:26.229331Z","caller":"traceutil/trace.go:171","msg":"trace[2019965543] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-679c578f5-45r95; range_end:; response_count:1; response_revision:1004; }","duration":"117.474389ms","start":"2026-07-14T17:41:26.111846Z","end":"2026-07-14T17:41:26.22932Z","steps":["trace[2019965543] 'agreement among raft nodes before linearized reading' (duration: 117.318546ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:28.414855Z","caller":"traceutil/trace.go:171","msg":"trace[160313084] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"203.696462ms","start":"2026-07-14T17:41:28.21112Z","end":"2026-07-14T17:41:28.414817Z","steps":["trace[160313084] 'process raft request' (duration: 203.466598ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:50.343474Z","caller":"traceutil/trace.go:171","msg":"trace[1048756584] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1127; }","duration":"133.648159ms","start":"2026-07-14T17:41:50.209801Z","end":"2026-07-14T17:41:50.343449Z","steps":["trace[1048756584] 'read index received' (duration: 133.372084ms)","trace[1048756584] 'applied index is now lower than readState.Index' (duration: 275.065µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:50.343514Z","caller":"traceutil/trace.go:171","msg":"trace[357011766] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"300.554875ms","start":"2026-07-14T17:41:50.042937Z","end":"2026-07-14T17:41:50.343492Z","steps":["trace[357011766] 'process raft request' (duration: 300.31232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:50.343651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:50.04291Z","time spent":"300.654617ms","remote":"127.0.0.1:48334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:41:50.343924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.080648ms","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-07-14T17:41:50.34439Z","caller":"traceutil/trace.go:171","msg":"trace[1198904211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1088; }","duration":"134.616987ms","start":"2026-07-14T17:41:50.209752Z","end":"2026-07-14T17:41:50.344369Z","steps":["trace[1198904211] 'agreement among raft nodes before linearized reading' (duration: 133.829242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:50.775282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.504573ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:41:50.775403Z","caller":"traceutil/trace.go:171","msg":"trace[1227253682] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1088; }","duration":"278.747487ms","start":"2026-07-14T17:41:50.496637Z","end":"2026-07-14T17:41:50.775384Z","steps":["trace[1227253682] 'range keys from in-memory index tree' (duration: 278.481011ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:50.775551Z","caller":"traceutil/trace.go:171","msg":"trace[2092436381] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1128; }","duration":"244.993051ms","start":"2026-07-14T17:41:50.530531Z","end":"2026-07-14T17:41:50.775524Z","steps":["trace[2092436381] 'read index received' (duration: 234.328938ms)","trace[2092436381] 'applied index is now lower than readState.Index' (duration: 10.662733ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:50.775663Z","caller":"traceutil/trace.go:171","msg":"trace[1269311232] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"426.504326ms","start":"2026-07-14T17:41:50.349146Z","end":"2026-07-14T17:41:50.77565Z","steps":["trace[1269311232] 'process raft request' (duration: 415.807772ms)","trace[1269311232] 'compare' (duration: 10.116813ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:41:50.775757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:50.349119Z","time spent":"426.574868ms","remote":"127.0.0.1:48334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:41:50.775796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.249686ms","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-07-14T17:41:50.775854Z","caller":"traceutil/trace.go:171","msg":"trace[1959206699] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1089; }","duration":"245.369818ms","start":"2026-07-14T17:41:50.530472Z","end":"2026-07-14T17:41:50.775842Z","steps":["trace[1959206699] 'agreement among raft nodes before linearized reading' (duration: 245.173844ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:17.113501Z","caller":"traceutil/trace.go:171","msg":"trace[1199481941] linearizableReadLoop","detail":"{readStateIndex:1222; appliedIndex:1219; }","duration":"141.588364ms","start":"2026-07-14T17:42:16.971891Z","end":"2026-07-14T17:42:17.11348Z","steps":["trace[1199481941] 'read index received' (duration: 7.811749ms)","trace[1199481941] 'applied index is now lower than readState.Index' (duration: 133.775935ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:42:17.113582Z","caller":"traceutil/trace.go:171","msg":"trace[1156039960] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"156.548884ms","start":"2026-07-14T17:42:16.957002Z","end":"2026-07-14T17:42:17.113551Z","steps":["trace[1156039960] 'process raft request' (duration: 156.253359ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:17.113635Z","caller":"traceutil/trace.go:171","msg":"trace[1293023714] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"156.179987ms","start":"2026-07-14T17:42:16.957439Z","end":"2026-07-14T17:42:17.113619Z","steps":["trace[1293023714] 'process raft request' (duration: 156.001284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:17.113787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.864609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:42:17.113919Z","caller":"traceutil/trace.go:171","msg":"trace[1619577841] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1177; }","duration":"142.016822ms","start":"2026-07-14T17:42:16.971871Z","end":"2026-07-14T17:42:17.113887Z","steps":["trace[1619577841] 'agreement among raft nodes before linearized reading' (duration: 141.779027ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:43.379823Z","caller":"traceutil/trace.go:171","msg":"trace[1392819574] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"116.207955ms","start":"2026-07-14T17:42:43.263583Z","end":"2026-07-14T17:42:43.379791Z","steps":["trace[1392819574] 'process raft request' (duration: 116.036982ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:43:08.596361Z","caller":"traceutil/trace.go:171","msg":"trace[528495975] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"123.362072ms","start":"2026-07-14T17:43:08.472969Z","end":"2026-07-14T17:43:08.596331Z","steps":["trace[528495975] 'process raft request' (duration: 119.453949ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:43:10.895427Z","caller":"traceutil/trace.go:171","msg":"trace[643447957] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"109.668573ms","start":"2026-07-14T17:43:10.785711Z","end":"2026-07-14T17:43:10.895379Z","steps":["trace[643447957] 'process raft request' (duration: 109.42631ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:43:19.405043Z","caller":"traceutil/trace.go:171","msg":"trace[577799427] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"111.003588ms","start":"2026-07-14T17:43:19.293988Z","end":"2026-07-14T17:43:19.404991Z","steps":["trace[577799427] 'process raft request' (duration: 110.696874ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:43:20.045239Z","caller":"traceutil/trace.go:171","msg":"trace[1879166923] linearizableReadLoop","detail":"{readStateIndex:1478; appliedIndex:1477; }","duration":"224.0817ms","start":"2026-07-14T17:43:19.821134Z","end":"2026-07-14T17:43:20.045216Z","steps":["trace[1879166923] 'read index received' (duration: 223.846806ms)","trace[1879166923] 'applied index is now lower than readState.Index' (duration: 233.904µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:43:20.045285Z","caller":"traceutil/trace.go:171","msg":"trace[1761272264] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"366.187231ms","start":"2026-07-14T17:43:19.679067Z","end":"2026-07-14T17:43:20.045254Z","steps":["trace[1761272264] 'process raft request' (duration: 365.980878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:43:20.045472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.321843ms","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-07-14T17:43:20.04551Z","caller":"traceutil/trace.go:171","msg":"trace[16506929] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1419; }","duration":"224.440025ms","start":"2026-07-14T17:43:19.821061Z","end":"2026-07-14T17:43:20.045501Z","steps":["trace[16506929] 'agreement among raft nodes before linearized reading' (duration: 224.264942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:43:20.045462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:43:19.679046Z","time spent":"366.299524ms","remote":"127.0.0.1:48334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:43:20.483345Z","caller":"traceutil/trace.go:171","msg":"trace[2067320615] linearizableReadLoop","detail":"{readStateIndex:1479; appliedIndex:1478; }","duration":"352.25447ms","start":"2026-07-14T17:43:20.131062Z","end":"2026-07-14T17:43:20.483316Z","steps":["trace[2067320615] 'read index received' (duration: 352.126338ms)","trace[2067320615] 'applied index is now lower than readState.Index' (duration: 126.782µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:43:20.48345Z","caller":"traceutil/trace.go:171","msg":"trace[1057092578] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"433.323333ms","start":"2026-07-14T17:43:20.050091Z","end":"2026-07-14T17:43:20.483414Z","steps":["trace[1057092578] 'process raft request' (duration: 432.951407ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:43:20.483667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.448074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:43:20.483714Z","caller":"traceutil/trace.go:171","msg":"trace[1623231996] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1420; }","duration":"352.729828ms","start":"2026-07-14T17:43:20.130973Z","end":"2026-07-14T17:43:20.483703Z","steps":["trace[1623231996] 'agreement among raft nodes before linearized reading' (duration: 352.467924ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:43:20.48381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:43:20.130948Z","time spent":"352.821059ms","remote":"127.0.0.1:48222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-07-14T17:43:20.483886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:43:20.050062Z","time spent":"433.553137ms","remote":"127.0.0.1:48334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:43:20.505012Z","caller":"traceutil/trace.go:171","msg":"trace[1622744589] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"258.548898ms","start":"2026-07-14T17:43:20.246432Z","end":"2026-07-14T17:43:20.504981Z","steps":["trace[1622744589] 'process raft request' (duration: 258.383246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:43:20.505068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.656757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:43:20.505121Z","caller":"traceutil/trace.go:171","msg":"trace[1406267914] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1421; }","duration":"125.771199ms","start":"2026-07-14T17:43:20.379335Z","end":"2026-07-14T17:43:20.505106Z","steps":["trace[1406267914] 'agreement among raft nodes before linearized reading' (duration: 125.651357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:43:20.505464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.834059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-14T17:43:20.505493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.226464ms","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-07-14T17:43:20.505521Z","caller":"traceutil/trace.go:171","msg":"trace[1691063710] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1421; }","duration":"290.945111ms","start":"2026-07-14T17:43:20.214562Z","end":"2026-07-14T17:43:20.505507Z","steps":["trace[1691063710] 'agreement among raft nodes before linearized reading' (duration: 290.84527ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:43:20.505547Z","caller":"traceutil/trace.go:171","msg":"trace[1998142837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1421; }","duration":"215.323495ms","start":"2026-07-14T17:43:20.290209Z","end":"2026-07-14T17:43:20.505532Z","steps":["trace[1998142837] 'agreement among raft nodes before linearized reading' (duration: 215.161082ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:43:24.611586Z","caller":"traceutil/trace.go:171","msg":"trace[1603883395] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"108.581037ms","start":"2026-07-14T17:43:24.502986Z","end":"2026-07-14T17:43:24.611567Z","steps":["trace[1603883395] 'process raft request' (duration: 108.455665ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:43:38.284526Z","caller":"traceutil/trace.go:171","msg":"trace[1560856509] transaction","detail":"{read_only:false; response_revision:1482; number_of_response:1; }","duration":"160.076634ms","start":"2026-07-14T17:43:38.124436Z","end":"2026-07-14T17:43:38.284512Z","steps":["trace[1560856509] 'process raft request' (duration: 159.78106ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:43:38.284492Z","caller":"traceutil/trace.go:171","msg":"trace[272080171] linearizableReadLoop","detail":"{readStateIndex:1546; appliedIndex:1545; }","duration":"140.412021ms","start":"2026-07-14T17:43:38.144044Z","end":"2026-07-14T17:43:38.284456Z","steps":["trace[272080171] 'read index received' (duration: 140.180058ms)","trace[272080171] 'applied index is now lower than readState.Index' (duration: 230.663µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:43:38.284665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.594414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pv-protection-controller\" ","response":"range_response_count:1 size:771"} {"level":"info","ts":"2026-07-14T17:43:38.284839Z","caller":"traceutil/trace.go:171","msg":"trace[1635955251] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pv-protection-controller; range_end:; response_count:1; response_revision:1482; }","duration":"140.831307ms","start":"2026-07-14T17:43:38.143997Z","end":"2026-07-14T17:43:38.284829Z","steps":["trace[1635955251] 'agreement among raft nodes before linearized reading' (duration: 140.560423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:43:38.285035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.267914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-proxier\" ","response":"range_response_count:1 size:714"} {"level":"info","ts":"2026-07-14T17:43:38.285139Z","caller":"traceutil/trace.go:171","msg":"trace[132008509] range","detail":"{range_begin:/registry/clusterroles/system:node-proxier; range_end:; response_count:1; response_revision:1482; }","duration":"115.450766ms","start":"2026-07-14T17:43:38.169664Z","end":"2026-07-14T17:43:38.285115Z","steps":["trace[132008509] 'agreement among raft nodes before linearized reading' (duration: 115.214223ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:43:38.412809Z","caller":"traceutil/trace.go:171","msg":"trace[71309845] linearizableReadLoop","detail":"{readStateIndex:1547; appliedIndex:1546; }","duration":"122.254433ms","start":"2026-07-14T17:43:38.290529Z","end":"2026-07-14T17:43:38.412783Z","steps":["trace[71309845] 'read index received' (duration: 103.90561ms)","trace[71309845] 'applied index is now lower than readState.Index' (duration: 18.347903ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:43:38.412999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.460095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pv-protection-controller\" ","response":"range_response_count:1 size:771"} {"level":"info","ts":"2026-07-14T17:43:38.412985Z","caller":"traceutil/trace.go:171","msg":"trace[1051278857] transaction","detail":"{read_only:false; response_revision:1483; number_of_response:1; }","duration":"123.478641ms","start":"2026-07-14T17:43:38.289459Z","end":"2026-07-14T17:43:38.412937Z","steps":["trace[1051278857] 'process raft request' (duration: 105.048847ms)","trace[1051278857] 'compare' (duration: 18.14932ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:43:38.413033Z","caller":"traceutil/trace.go:171","msg":"trace[987806789] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pv-protection-controller; range_end:; response_count:1; response_revision:1483; }","duration":"122.560047ms","start":"2026-07-14T17:43:38.290461Z","end":"2026-07-14T17:43:38.413021Z","steps":["trace[987806789] 'agreement among raft nodes before linearized reading' (duration: 122.472886ms)"],"step_count":1}