{"level":"warn","ts":"2026-07-21T13:14:00.676077Z","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-21T13:14:00.676239Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.86: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.86:2380","--initial-cluster=instance=https://199.19.213.86:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.86:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.86: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-21T13:14:00.676462Z","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-21T13:14:00.676501Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.86:2380"]} {"level":"info","ts":"2026-07-21T13:14:00.676601Z","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-21T13:14:00.677414Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"]} {"level":"info","ts":"2026-07-21T13:14:00.677509Z","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.86:2380"],"listen-peer-urls":["https://199.19.213.86:2380"],"advertise-client-urls":["https://199.19.213.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.86: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-21T13:14:00.681818Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.039027ms"} {"level":"info","ts":"2026-07-21T13:14:00.690145Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"849a6ffb5e81c109","cluster-id":"d4d86b1dd8829199"} {"level":"info","ts":"2026-07-21T13:14:00.690261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=()"} {"level":"info","ts":"2026-07-21T13:14:00.690313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became follower at term 0"} {"level":"info","ts":"2026-07-21T13:14:00.690339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 849a6ffb5e81c109 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-21T13:14:00.690348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became follower at term 1"} {"level":"info","ts":"2026-07-21T13:14:00.690388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=(9555072684832964873)"} {"level":"warn","ts":"2026-07-21T13:14:00.698802Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-21T13:14:00.700789Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-21T13:14:00.701969Z","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-21T13:14:00.704248Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"849a6ffb5e81c109","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-21T13:14:00.704403Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"849a6ffb5e81c109","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-21T13:14:00.704453Z","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-21T13:14:00.705028Z","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-21T13:14:00.705075Z","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-21T13:14:00.705721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=(9555072684832964873)"} {"level":"info","ts":"2026-07-21T13:14:00.707914Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d4d86b1dd8829199","local-member-id":"849a6ffb5e81c109","added-peer-id":"849a6ffb5e81c109","added-peer-peer-urls":["https://199.19.213.86:2380"]} {"level":"info","ts":"2026-07-21T13:14:00.70942Z","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-21T13:14:00.70959Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.86:2380"} {"level":"info","ts":"2026-07-21T13:14:00.70963Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.86:2380"} {"level":"info","ts":"2026-07-21T13:14:00.709657Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"849a6ffb5e81c109","initial-advertise-peer-urls":["https://199.19.213.86:2380"],"listen-peer-urls":["https://199.19.213.86:2380"],"advertise-client-urls":["https://199.19.213.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-21T13:14:00.709685Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-21T13:14:01.491061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 is starting a new election at term 1"} {"level":"info","ts":"2026-07-21T13:14:01.491172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-21T13:14:01.491328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 received MsgPreVoteResp from 849a6ffb5e81c109 at term 1"} {"level":"info","ts":"2026-07-21T13:14:01.491351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became candidate at term 2"} {"level":"info","ts":"2026-07-21T13:14:01.491358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 received MsgVoteResp from 849a6ffb5e81c109 at term 2"} {"level":"info","ts":"2026-07-21T13:14:01.491423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became leader at term 2"} {"level":"info","ts":"2026-07-21T13:14:01.491467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 849a6ffb5e81c109 elected leader 849a6ffb5e81c109 at term 2"} {"level":"info","ts":"2026-07-21T13:14:01.492482Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"849a6ffb5e81c109","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.86:2379]}","request-path":"/0/members/849a6ffb5e81c109/attributes","cluster-id":"d4d86b1dd8829199","publish-timeout":"7s"} {"level":"info","ts":"2026-07-21T13:14:01.492519Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T13:14:01.492488Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T13:14:01.492486Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T13:14:01.492841Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-21T13:14:01.492862Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-21T13:14:01.493475Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d4d86b1dd8829199","local-member-id":"849a6ffb5e81c109","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T13:14:01.493583Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T13:14:01.493612Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T13:14:01.494982Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.86:2379"} {"level":"info","ts":"2026-07-21T13:14:01.49528Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-21T13:14:15.229179Z","caller":"traceutil/trace.go:171","msg":"trace[1297740683] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"168.168235ms","start":"2026-07-21T13:14:15.060989Z","end":"2026-07-21T13:14:15.229157Z","steps":["trace[1297740683] 'process raft request' (duration: 168.053903ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.418192Z","caller":"traceutil/trace.go:171","msg":"trace[1508143376] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"174.074768ms","start":"2026-07-21T13:14:42.244098Z","end":"2026-07-21T13:14:42.418172Z","steps":["trace[1508143376] 'process raft request' (duration: 173.976446ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.418257Z","caller":"traceutil/trace.go:171","msg":"trace[337746989] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"173.943996ms","start":"2026-07-21T13:14:42.244223Z","end":"2026-07-21T13:14:42.418167Z","steps":["trace[337746989] 'process raft request' (duration: 173.905605ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.418196Z","caller":"traceutil/trace.go:171","msg":"trace[1947849954] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"175.034241ms","start":"2026-07-21T13:14:42.243131Z","end":"2026-07-21T13:14:42.418165Z","steps":["trace[1947849954] 'process raft request' (duration: 171.382881ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.456717Z","caller":"traceutil/trace.go:171","msg":"trace[2121011206] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"208.107323ms","start":"2026-07-21T13:14:42.248551Z","end":"2026-07-21T13:14:42.456659Z","steps":["trace[2121011206] 'process raft request' (duration: 207.362093ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.457235Z","caller":"traceutil/trace.go:171","msg":"trace[453078351] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"200.893753ms","start":"2026-07-21T13:14:42.256329Z","end":"2026-07-21T13:14:42.457222Z","steps":["trace[453078351] 'process raft request' (duration: 200.427767ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.457251Z","caller":"traceutil/trace.go:171","msg":"trace[1869617843] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"208.443237ms","start":"2026-07-21T13:14:42.248792Z","end":"2026-07-21T13:14:42.457235Z","steps":["trace[1869617843] 'process raft request' (duration: 207.606526ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.457415Z","caller":"traceutil/trace.go:171","msg":"trace[499876479] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"199.299983ms","start":"2026-07-21T13:14:42.258106Z","end":"2026-07-21T13:14:42.457406Z","steps":["trace[499876479] 'process raft request' (duration: 198.894297ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.45742Z","caller":"traceutil/trace.go:171","msg":"trace[1398228718] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"200.542839ms","start":"2026-07-21T13:14:42.256865Z","end":"2026-07-21T13:14:42.457408Z","steps":["trace[1398228718] 'process raft request' (duration: 199.943571ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.457521Z","caller":"traceutil/trace.go:171","msg":"trace[1430800905] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"200.986725ms","start":"2026-07-21T13:14:42.256527Z","end":"2026-07-21T13:14:42.457514Z","steps":["trace[1430800905] 'process raft request' (duration: 200.259246ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.457644Z","caller":"traceutil/trace.go:171","msg":"trace[1324465054] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"196.077989ms","start":"2026-07-21T13:14:42.261354Z","end":"2026-07-21T13:14:42.457432Z","steps":["trace[1324465054] 'process raft request' (duration: 195.712754ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.458017Z","caller":"traceutil/trace.go:171","msg":"trace[1834029809] transaction","detail":"{read_only:false; number_of_response:1; response_revision:505; }","duration":"194.415337ms","start":"2026-07-21T13:14:42.263581Z","end":"2026-07-21T13:14:42.457997Z","steps":["trace[1834029809] 'process raft request' (duration: 193.560124ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.45841Z","caller":"traceutil/trace.go:171","msg":"trace[881969165] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:516; }","duration":"206.549632ms","start":"2026-07-21T13:14:42.251841Z","end":"2026-07-21T13:14:42.458391Z","steps":["trace[881969165] 'read index received' (duration: 162.702533ms)","trace[881969165] 'applied index is now lower than readState.Index' (duration: 43.845979ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:14:42.458534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.674353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-21T13:14:42.458563Z","caller":"traceutil/trace.go:171","msg":"trace[20649077] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"174.576295ms","start":"2026-07-21T13:14:42.283964Z","end":"2026-07-21T13:14:42.45854Z","steps":["trace[20649077] 'process raft request' (duration: 174.23227ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.458601Z","caller":"traceutil/trace.go:171","msg":"trace[795754356] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"120.200982ms","start":"2026-07-21T13:14:42.338396Z","end":"2026-07-21T13:14:42.458597Z","steps":["trace[795754356] 'process raft request' (duration: 119.916509ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.458787Z","caller":"traceutil/trace.go:171","msg":"trace[1009025057] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"190.523843ms","start":"2026-07-21T13:14:42.268252Z","end":"2026-07-21T13:14:42.458776Z","steps":["trace[1009025057] 'process raft request' (duration: 188.953791ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.458579Z","caller":"traceutil/trace.go:171","msg":"trace[936610427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:508; }","duration":"206.755134ms","start":"2026-07-21T13:14:42.251816Z","end":"2026-07-21T13:14:42.458571Z","steps":["trace[936610427] 'agreement among raft nodes before linearized reading' (duration: 206.629162ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.669299Z","caller":"traceutil/trace.go:171","msg":"trace[446315151] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"241.515459ms","start":"2026-07-21T13:14:42.427761Z","end":"2026-07-21T13:14:42.669277Z","steps":["trace[446315151] 'process raft request' (duration: 232.290723ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.669378Z","caller":"traceutil/trace.go:171","msg":"trace[18861809] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"227.898012ms","start":"2026-07-21T13:14:42.44146Z","end":"2026-07-21T13:14:42.669358Z","steps":["trace[18861809] 'process raft request' (duration: 227.825291ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.670682Z","caller":"traceutil/trace.go:171","msg":"trace[742087202] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"229.291282ms","start":"2026-07-21T13:14:42.440306Z","end":"2026-07-21T13:14:42.669598Z","steps":["trace[742087202] 'process raft request' (duration: 228.847967ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:43.056378Z","caller":"traceutil/trace.go:171","msg":"trace[690172897] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"590.509314ms","start":"2026-07-21T13:14:42.465844Z","end":"2026-07-21T13:14:43.056353Z","steps":["trace[690172897] 'process raft request' (duration: 561.573231ms)","trace[690172897] 'compare' (duration: 28.72606ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:14:43.056754Z","caller":"traceutil/trace.go:171","msg":"trace[84639465] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"587.37814ms","start":"2026-07-21T13:14:42.469354Z","end":"2026-07-21T13:14:43.056732Z","steps":["trace[84639465] 'process raft request' (duration: 587.126057ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:43.057046Z","caller":"traceutil/trace.go:171","msg":"trace[2122129747] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"589.269477ms","start":"2026-07-21T13:14:42.467267Z","end":"2026-07-21T13:14:43.056537Z","steps":["trace[2122129747] 'process raft request' (duration: 589.047554ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:43.057144Z","caller":"traceutil/trace.go:171","msg":"trace[1522578064] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"585.092081ms","start":"2026-07-21T13:14:42.472042Z","end":"2026-07-21T13:14:43.057134Z","steps":["trace[1522578064] 'process raft request' (duration: 584.507522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:43.057211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.472026Z","time spent":"585.145931ms","remote":"127.0.0.1:53518","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-21T13:14:43.057211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.465822Z","time spent":"590.883259ms","remote":"127.0.0.1:53518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:14:43.057504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.469325Z","time spent":"587.498433ms","remote":"127.0.0.1:53990","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-21T13:14:43.057703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.467255Z","time spent":"589.830274ms","remote":"127.0.0.1:53738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:14:43.058034Z","caller":"traceutil/trace.go:171","msg":"trace[1137076870] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"581.777785ms","start":"2026-07-21T13:14:42.476234Z","end":"2026-07-21T13:14:43.058011Z","steps":["trace[1137076870] 'process raft request' (duration: 580.582038ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:43.058097Z","caller":"traceutil/trace.go:171","msg":"trace[1053760835] transaction","detail":"{read_only:false; number_of_response:1; response_revision:516; }","duration":"581.335499ms","start":"2026-07-21T13:14:42.476751Z","end":"2026-07-21T13:14:43.058086Z","steps":["trace[1053760835] 'process raft request' (duration: 580.172173ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:43.05812Z","caller":"traceutil/trace.go:171","msg":"trace[1524948641] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"566.447976ms","start":"2026-07-21T13:14:42.49166Z","end":"2026-07-21T13:14:43.058108Z","steps":["trace[1524948641] 'process raft request' (duration: 565.867018ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:43.058128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.476203Z","time spent":"581.885007ms","remote":"127.0.0.1:53518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7326,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:14:43.058155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.476669Z","time spent":"581.451831ms","remote":"127.0.0.1:53518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7376,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:14:43.058054Z","caller":"traceutil/trace.go:171","msg":"trace[1502610197] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"580.904734ms","start":"2026-07-21T13:14:42.477138Z","end":"2026-07-21T13:14:43.058043Z","steps":["trace[1502610197] 'process raft request' (duration: 580.271125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:43.058197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.491626Z","time spent":"566.526836ms","remote":"127.0.0.1:53518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:14:43.058239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.477124Z","time spent":"581.084516ms","remote":"127.0.0.1:53518","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-21T13:14:43.058274Z","caller":"traceutil/trace.go:171","msg":"trace[1055096462] transaction","detail":"{read_only:false; number_of_response:0; response_revision:518; }","duration":"385.426143ms","start":"2026-07-21T13:14:42.672837Z","end":"2026-07-21T13:14:43.058264Z","steps":["trace[1055096462] 'process raft request' (duration: 384.759344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:43.05832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.672813Z","time spent":"385.482084ms","remote":"127.0.0.1:53990","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T13:14:43.058335Z","caller":"traceutil/trace.go:171","msg":"trace[1588878127] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"362.037964ms","start":"2026-07-21T13:14:42.696285Z","end":"2026-07-21T13:14:43.058323Z","steps":["trace[1588878127] 'process raft request' (duration: 361.344984ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:43.058336Z","caller":"traceutil/trace.go:171","msg":"trace[2093399053] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"320.278633ms","start":"2026-07-21T13:14:42.738045Z","end":"2026-07-21T13:14:43.058324Z","steps":["trace[2093399053] 'process raft request' (duration: 320.217202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:43.058397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.73802Z","time spent":"320.348863ms","remote":"127.0.0.1:53518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T13:14:43.05841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.696258Z","time spent":"362.117324ms","remote":"127.0.0.1:53518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:14:49.364668Z","caller":"traceutil/trace.go:171","msg":"trace[799573201] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"228.51919ms","start":"2026-07-21T13:14:49.136125Z","end":"2026-07-21T13:14:49.364644Z","steps":["trace[799573201] 'process raft request' (duration: 227.76154ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:49.696314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.164461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wkw5p\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-21T13:14:49.696387Z","caller":"traceutil/trace.go:171","msg":"trace[892692552] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wkw5p; range_end:; response_count:1; response_revision:602; }","duration":"203.288174ms","start":"2026-07-21T13:14:49.493084Z","end":"2026-07-21T13:14:49.696372Z","steps":["trace[892692552] 'range keys from in-memory index tree' (duration: 203.016928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:49.69638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.313607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:14:49.696519Z","caller":"traceutil/trace.go:171","msg":"trace[1933882] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:602; }","duration":"231.49062ms","start":"2026-07-21T13:14:49.465Z","end":"2026-07-21T13:14:49.69649Z","steps":["trace[1933882] 'range keys from in-memory index tree' (duration: 231.221046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:49.69662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.60535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T13:14:49.69669Z","caller":"traceutil/trace.go:171","msg":"trace[659226486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:602; }","duration":"156.706792ms","start":"2026-07-21T13:14:49.539959Z","end":"2026-07-21T13:14:49.696665Z","steps":["trace[659226486] 'range keys from in-memory index tree' (duration: 156.432897ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:49.69716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.224316ms","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-21T13:14:49.697296Z","caller":"traceutil/trace.go:171","msg":"trace[243281214] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:602; }","duration":"216.402047ms","start":"2026-07-21T13:14:49.48087Z","end":"2026-07-21T13:14:49.697272Z","steps":["trace[243281214] 'range keys from in-memory index tree' (duration: 215.864781ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:49.83239Z","caller":"traceutil/trace.go:171","msg":"trace[1891218000] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"130.997648ms","start":"2026-07-21T13:14:49.701369Z","end":"2026-07-21T13:14:49.832366Z","steps":["trace[1891218000] 'process raft request' (duration: 130.762895ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:49.832601Z","caller":"traceutil/trace.go:171","msg":"trace[960294015] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"119.659767ms","start":"2026-07-21T13:14:49.712929Z","end":"2026-07-21T13:14:49.832589Z","steps":["trace[960294015] 'process raft request' (duration: 119.593886ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:49.832624Z","caller":"traceutil/trace.go:171","msg":"trace[280627917] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"130.774306ms","start":"2026-07-21T13:14:49.70184Z","end":"2026-07-21T13:14:49.832615Z","steps":["trace[280627917] 'process raft request' (duration: 130.488782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:50.980784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.851108ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-21T13:14:50.981945Z","caller":"traceutil/trace.go:171","msg":"trace[1271692803] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"853.93906ms","start":"2026-07-21T13:14:50.127968Z","end":"2026-07-21T13:14:50.981907Z","steps":["trace[1271692803] 'process raft request' (duration: 853.313322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:50.982104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.482085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:14:50.98218Z","caller":"traceutil/trace.go:171","msg":"trace[688400021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"239.593548ms","start":"2026-07-21T13:14:50.742567Z","end":"2026-07-21T13:14:50.98216Z","steps":["trace[688400021] 'agreement among raft nodes before linearized reading' (duration: 239.465216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:50.982286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:50.127943Z","time spent":"854.158683ms","remote":"127.0.0.1:53566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:14:50.982667Z","caller":"traceutil/trace.go:171","msg":"trace[1209395457] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:631; }","duration":"239.248512ms","start":"2026-07-21T13:14:50.742604Z","end":"2026-07-21T13:14:50.981853Z","steps":["trace[1209395457] 'read index received' (duration: 126.32661ms)","trace[1209395457] 'applied index is now lower than readState.Index' (duration: 112.920982ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:14:51.930525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.134576ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:14:51.930635Z","caller":"traceutil/trace.go:171","msg":"trace[1718682716] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"185.230437ms","start":"2026-07-21T13:14:51.745388Z","end":"2026-07-21T13:14:51.930619Z","steps":["trace[1718682716] 'read index received' (duration: 60.845038ms)","trace[1718682716] 'applied index is now lower than readState.Index' (duration: 124.384349ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:14:51.930673Z","caller":"traceutil/trace.go:171","msg":"trace[903811592] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"223.616936ms","start":"2026-07-21T13:14:51.707015Z","end":"2026-07-21T13:14:51.930632Z","steps":["trace[903811592] 'process raft request' (duration: 99.306218ms)","trace[903811592] 'compare' (duration: 124.012604ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:14:51.930781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.400689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:14:51.930809Z","caller":"traceutil/trace.go:171","msg":"trace[1006154748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"185.471439ms","start":"2026-07-21T13:14:51.745329Z","end":"2026-07-21T13:14:51.9308Z","steps":["trace[1006154748] 'agreement among raft nodes before linearized reading' (duration: 185.382538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:51.930921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.109239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T13:14:51.930999Z","caller":"traceutil/trace.go:171","msg":"trace[996119419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"128.243141ms","start":"2026-07-21T13:14:51.802738Z","end":"2026-07-21T13:14:51.930981Z","steps":["trace[996119419] 'agreement among raft nodes before linearized reading' (duration: 128.003747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:52.924078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.187608ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:14:52.924212Z","caller":"traceutil/trace.go:171","msg":"trace[286192540] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"244.027847ms","start":"2026-07-21T13:14:52.680152Z","end":"2026-07-21T13:14:52.92418Z","steps":["trace[286192540] 'process raft request' (duration: 15.653947ms)","trace[286192540] 'compare' (duration: 228.054676ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:14:52.986186Z","caller":"traceutil/trace.go:171","msg":"trace[1967629172] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"242.068271ms","start":"2026-07-21T13:14:52.744093Z","end":"2026-07-21T13:14:52.986161Z","steps":["trace[1967629172] 'read index received' (duration: 242.004151ms)","trace[1967629172] 'applied index is now lower than readState.Index' (duration: 63.13µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:14:52.986249Z","caller":"traceutil/trace.go:171","msg":"trace[1156572793] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"296.768224ms","start":"2026-07-21T13:14:52.689447Z","end":"2026-07-21T13:14:52.986216Z","steps":["trace[1156572793] 'process raft request' (duration: 296.604302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:52.986369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.230733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:14:52.986407Z","caller":"traceutil/trace.go:171","msg":"trace[167208583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"242.350194ms","start":"2026-07-21T13:14:52.744048Z","end":"2026-07-21T13:14:52.986398Z","steps":["trace[167208583] 'agreement among raft nodes before linearized reading' (duration: 242.238343ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:53.02244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.976614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:14:53.022499Z","caller":"traceutil/trace.go:171","msg":"trace[538148257] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:640; }","duration":"191.086756ms","start":"2026-07-21T13:14:52.831398Z","end":"2026-07-21T13:14:53.022485Z","steps":["trace[538148257] 'agreement among raft nodes before linearized reading' (duration: 190.995875ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:56.236868Z","caller":"traceutil/trace.go:171","msg":"trace[1480073933] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"256.066217ms","start":"2026-07-21T13:14:55.980773Z","end":"2026-07-21T13:14:56.236839Z","steps":["trace[1480073933] 'process raft request' (duration: 255.910455ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:56.350647Z","caller":"traceutil/trace.go:171","msg":"trace[642989186] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"368.599708ms","start":"2026-07-21T13:14:55.982015Z","end":"2026-07-21T13:14:56.350615Z","steps":["trace[642989186] 'process raft request' (duration: 368.382725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:56.350828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:55.982Z","time spent":"368.736409ms","remote":"127.0.0.1:53738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:14:56.351102Z","caller":"traceutil/trace.go:171","msg":"trace[1537814383] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:710; }","duration":"119.169726ms","start":"2026-07-21T13:14:56.2319Z","end":"2026-07-21T13:14:56.35107Z","steps":["trace[1537814383] 'read index received' (duration: 4.891163ms)","trace[1537814383] 'applied index is now lower than readState.Index' (duration: 114.277463ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:14:56.351287Z","caller":"traceutil/trace.go:171","msg":"trace[306144024] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"289.089347ms","start":"2026-07-21T13:14:56.062182Z","end":"2026-07-21T13:14:56.351271Z","steps":["trace[306144024] 'process raft request' (duration: 288.718902ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:56.351351Z","caller":"traceutil/trace.go:171","msg":"trace[2136945014] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"369.239215ms","start":"2026-07-21T13:14:55.982064Z","end":"2026-07-21T13:14:56.351303Z","steps":["trace[2136945014] 'process raft request' (duration: 368.507746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:56.35162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.677883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"warn","ts":"2026-07-21T13:14:56.351619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:55.982046Z","time spent":"369.404898ms","remote":"127.0.0.1:53738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:14:56.351725Z","caller":"traceutil/trace.go:171","msg":"trace[13502003] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:687; }","duration":"119.805146ms","start":"2026-07-21T13:14:56.231857Z","end":"2026-07-21T13:14:56.351662Z","steps":["trace[13502003] 'agreement among raft nodes before linearized reading' (duration: 119.34637ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:56.630899Z","caller":"traceutil/trace.go:171","msg":"trace[1569606788] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"185.190069ms","start":"2026-07-21T13:14:56.445668Z","end":"2026-07-21T13:14:56.630858Z","steps":["trace[1569606788] 'process raft request' (duration: 184.870005ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:56.662428Z","caller":"traceutil/trace.go:171","msg":"trace[684081484] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:715; }","duration":"109.963236ms","start":"2026-07-21T13:14:56.552444Z","end":"2026-07-21T13:14:56.662408Z","steps":["trace[684081484] 'read index received' (duration: 80.051816ms)","trace[684081484] 'applied index is now lower than readState.Index' (duration: 29.91057ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:14:56.66267Z","caller":"traceutil/trace.go:171","msg":"trace[1119510399] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"186.247123ms","start":"2026-07-21T13:14:56.476416Z","end":"2026-07-21T13:14:56.662663Z","steps":["trace[1119510399] 'process raft request' (duration: 185.852778ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:56.662797Z","caller":"traceutil/trace.go:171","msg":"trace[2064977892] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"107.499984ms","start":"2026-07-21T13:14:56.555291Z","end":"2026-07-21T13:14:56.662791Z","steps":["trace[2064977892] 'process raft request' (duration: 107.081379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:56.663005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.554774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wkw5p\" ","response":"range_response_count:1 size:18333"} {"level":"info","ts":"2026-07-21T13:14:56.663028Z","caller":"traceutil/trace.go:171","msg":"trace[739120552] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wkw5p; range_end:; response_count:1; response_revision:690; }","duration":"110.643635ms","start":"2026-07-21T13:14:56.552377Z","end":"2026-07-21T13:14:56.663021Z","steps":["trace[739120552] 'agreement among raft nodes before linearized reading' (duration: 110.512973ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:56.814491Z","caller":"traceutil/trace.go:171","msg":"trace[787707847] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:717; }","duration":"117.703688ms","start":"2026-07-21T13:14:56.696768Z","end":"2026-07-21T13:14:56.814471Z","steps":["trace[787707847] 'read index received' (duration: 83.250029ms)","trace[787707847] 'applied index is now lower than readState.Index' (duration: 34.452789ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:14:56.814746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.963363ms","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-21T13:14:56.814804Z","caller":"traceutil/trace.go:171","msg":"trace[324744591] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:691; }","duration":"118.061044ms","start":"2026-07-21T13:14:56.696735Z","end":"2026-07-21T13:14:56.814796Z","steps":["trace[324744591] 'agreement among raft nodes before linearized reading' (duration: 117.876121ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:56.814979Z","caller":"traceutil/trace.go:171","msg":"trace[589070331] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"137.172313ms","start":"2026-07-21T13:14:56.677797Z","end":"2026-07-21T13:14:56.814969Z","steps":["trace[589070331] 'process raft request' (duration: 102.153345ms)","trace[589070331] 'compare' (duration: 34.221927ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:14:56.832807Z","caller":"traceutil/trace.go:171","msg":"trace[2018761328] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"116.799507ms","start":"2026-07-21T13:14:56.71597Z","end":"2026-07-21T13:14:56.832769Z","steps":["trace[2018761328] 'process raft request' (duration: 116.138959ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:03.703859Z","caller":"traceutil/trace.go:171","msg":"trace[1312849719] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"106.217251ms","start":"2026-07-21T13:15:03.597606Z","end":"2026-07-21T13:15:03.703823Z","steps":["trace[1312849719] 'process raft request' (duration: 106.009728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:21.296927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.699322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T13:15:21.29708Z","caller":"traceutil/trace.go:171","msg":"trace[1077846427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:939; }","duration":"756.148418ms","start":"2026-07-21T13:15:20.540903Z","end":"2026-07-21T13:15:21.297051Z","steps":["trace[1077846427] 'range keys from bolt db' (duration: 208.010539ms)","trace[1077846427] 'filter and sort the key-value pairs' (duration: 547.538122ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:15:21.297137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:20.540878Z","time spent":"756.246169ms","remote":"127.0.0.1:53738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-21T13:15:21.307065Z","caller":"traceutil/trace.go:171","msg":"trace[2032380825] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"362.540065ms","start":"2026-07-21T13:15:20.94449Z","end":"2026-07-21T13:15:21.30703Z","steps":["trace[2032380825] 'process raft request' (duration: 362.343823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:21.307233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:20.944446Z","time spent":"362.701826ms","remote":"127.0.0.1:53658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3705,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:15:21.312052Z","caller":"traceutil/trace.go:171","msg":"trace[350742724] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"359.527566ms","start":"2026-07-21T13:15:20.952512Z","end":"2026-07-21T13:15:21.312039Z","steps":["trace[350742724] 'process raft request' (duration: 358.969579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:21.312207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:20.952472Z","time spent":"359.660318ms","remote":"127.0.0.1:53738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:15:21.313297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.00583ms","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-21T13:15:21.313337Z","caller":"traceutil/trace.go:171","msg":"trace[845715963] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:943; }","duration":"311.119561ms","start":"2026-07-21T13:15:21.002211Z","end":"2026-07-21T13:15:21.31333Z","steps":["trace[845715963] 'agreement among raft nodes before linearized reading' (duration: 310.98545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:21.31336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:21.002186Z","time spent":"311.168971ms","remote":"127.0.0.1:53738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-21T13:15:21.313562Z","caller":"traceutil/trace.go:171","msg":"trace[1430261203] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:976; }","duration":"309.647853ms","start":"2026-07-21T13:15:21.002285Z","end":"2026-07-21T13:15:21.311932Z","steps":["trace[1430261203] 'read index received' (duration: 304.744333ms)","trace[1430261203] 'applied index is now lower than readState.Index' (duration: 4.90276ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:15:23.305752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.668995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-21T13:15:23.305857Z","caller":"traceutil/trace.go:171","msg":"trace[957623921] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:947; }","duration":"295.820306ms","start":"2026-07-21T13:15:23.010013Z","end":"2026-07-21T13:15:23.305833Z","steps":["trace[957623921] 'range keys from in-memory index tree' (duration: 295.428902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:23.305882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.766377ms","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-21T13:15:23.305948Z","caller":"traceutil/trace.go:171","msg":"trace[1697988764] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:947; }","duration":"295.846298ms","start":"2026-07-21T13:15:23.010086Z","end":"2026-07-21T13:15:23.305933Z","steps":["trace[1697988764] 'range keys from in-memory index tree' (duration: 295.668625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:23.898568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.957334ms","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-21T13:15:23.898776Z","caller":"traceutil/trace.go:171","msg":"trace[1445435094] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:953; }","duration":"446.218647ms","start":"2026-07-21T13:15:23.452529Z","end":"2026-07-21T13:15:23.898747Z","steps":["trace[1445435094] 'range keys from in-memory index tree' (duration: 445.932344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:41.938887Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909824316881795710,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T13:15:42.203413Z","caller":"traceutil/trace.go:171","msg":"trace[1420081470] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"765.133966ms","start":"2026-07-21T13:15:41.438242Z","end":"2026-07-21T13:15:42.203376Z","steps":["trace[1420081470] 'read index received' (duration: 764.65831ms)","trace[1420081470] 'applied index is now lower than readState.Index' (duration: 474.276µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:15:42.203567Z","caller":"traceutil/trace.go:171","msg":"trace[1663650728] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"797.280967ms","start":"2026-07-21T13:15:41.406214Z","end":"2026-07-21T13:15:42.203495Z","steps":["trace[1663650728] 'process raft request' (duration: 796.900473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:42.203938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.675272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-21T13:15:42.203993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:41.406194Z","time spent":"797.716922ms","remote":"127.0.0.1:53738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:15:42.203987Z","caller":"traceutil/trace.go:171","msg":"trace[729080189] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1013; }","duration":"765.789714ms","start":"2026-07-21T13:15:41.438188Z","end":"2026-07-21T13:15:42.203978Z","steps":["trace[729080189] 'agreement among raft nodes before linearized reading' (duration: 765.50746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:42.204512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.353801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-21T13:15:42.204531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:41.438153Z","time spent":"766.36562ms","remote":"127.0.0.1:53738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-21T13:15:42.20455Z","caller":"traceutil/trace.go:171","msg":"trace[207606118] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1013; }","duration":"761.403911ms","start":"2026-07-21T13:15:41.443135Z","end":"2026-07-21T13:15:42.204539Z","steps":["trace[207606118] 'agreement among raft nodes before linearized reading' (duration: 760.815444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:42.204572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:41.443128Z","time spent":"761.438221ms","remote":"127.0.0.1:53738","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-21T13:15:42.204643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.716317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:15:42.204664Z","caller":"traceutil/trace.go:171","msg":"trace[260611323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1013; }","duration":"458.782917ms","start":"2026-07-21T13:15:41.745875Z","end":"2026-07-21T13:15:42.204658Z","steps":["trace[260611323] 'agreement among raft nodes before linearized reading' (duration: 458.743087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:42.204681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:41.745852Z","time spent":"458.825488ms","remote":"127.0.0.1:53460","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-21T13:15:42.204717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.238607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-21T13:15:42.204744Z","caller":"traceutil/trace.go:171","msg":"trace[521326131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1013; }","duration":"727.285878ms","start":"2026-07-21T13:15:41.477448Z","end":"2026-07-21T13:15:42.204734Z","steps":["trace[521326131] 'agreement among raft nodes before linearized reading' (duration: 727.200046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:42.204763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:41.477421Z","time spent":"727.337229ms","remote":"127.0.0.1:53738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T13:15:42.204833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.571417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T13:15:42.204856Z","caller":"traceutil/trace.go:171","msg":"trace[1525648379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1013; }","duration":"752.609248ms","start":"2026-07-21T13:15:41.45224Z","end":"2026-07-21T13:15:42.204849Z","steps":["trace[1525648379] 'agreement among raft nodes before linearized reading' (duration: 752.547787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:42.204867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.167579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-21T13:15:42.204873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:41.452231Z","time spent":"752.637768ms","remote":"127.0.0.1:53738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-21T13:15:42.204894Z","caller":"traceutil/trace.go:171","msg":"trace[296067576] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1013; }","duration":"706.218019ms","start":"2026-07-21T13:15:41.498669Z","end":"2026-07-21T13:15:42.204887Z","steps":["trace[296067576] 'agreement among raft nodes before linearized reading' (duration: 706.150908ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:42.204916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:41.498657Z","time spent":"706.253459ms","remote":"127.0.0.1:53738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-21T13:15:48.539296Z","caller":"traceutil/trace.go:171","msg":"trace[555708286] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1072; }","duration":"299.998062ms","start":"2026-07-21T13:15:48.239272Z","end":"2026-07-21T13:15:48.53927Z","steps":["trace[555708286] 'read index received' (duration: 299.549707ms)","trace[555708286] 'applied index is now lower than readState.Index' (duration: 418.184µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:15:48.539728Z","caller":"traceutil/trace.go:171","msg":"trace[974173401] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"313.396649ms","start":"2026-07-21T13:15:48.226217Z","end":"2026-07-21T13:15:48.539613Z","steps":["trace[974173401] 'process raft request' (duration: 312.727566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:48.539867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.54039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-21T13:15:48.539935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:48.226195Z","time spent":"313.628687ms","remote":"127.0.0.1:53738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:15:48.539964Z","caller":"traceutil/trace.go:171","msg":"trace[1462876145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1033; }","duration":"300.714206ms","start":"2026-07-21T13:15:48.239239Z","end":"2026-07-21T13:15:48.539953Z","steps":["trace[1462876145] 'agreement among raft nodes before linearized reading' (duration: 300.456467ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:48.539952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.055268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-21T13:15:48.540017Z","caller":"traceutil/trace.go:171","msg":"trace[1780810494] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1033; }","duration":"291.145091ms","start":"2026-07-21T13:15:48.248857Z","end":"2026-07-21T13:15:48.540002Z","steps":["trace[1780810494] 'agreement among raft nodes before linearized reading' (duration: 290.919343ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:48.540004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:48.23923Z","time spent":"300.761688ms","remote":"127.0.0.1:53738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T13:15:48.540117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.810985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:15:48.540147Z","caller":"traceutil/trace.go:171","msg":"trace[568074051] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1033; }","duration":"232.866547ms","start":"2026-07-21T13:15:48.307261Z","end":"2026-07-21T13:15:48.540128Z","steps":["trace[568074051] 'agreement among raft nodes before linearized reading' (duration: 232.835176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:48.540224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.932797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-21T13:15:48.54025Z","caller":"traceutil/trace.go:171","msg":"trace[2095569230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1033; }","duration":"286.983639ms","start":"2026-07-21T13:15:48.25326Z","end":"2026-07-21T13:15:48.540243Z","steps":["trace[2095569230] 'agreement among raft nodes before linearized reading' (duration: 286.901836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:48.540291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.805009ms","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-21T13:15:48.540325Z","caller":"traceutil/trace.go:171","msg":"trace[1583611230] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1033; }","duration":"290.846041ms","start":"2026-07-21T13:15:48.249469Z","end":"2026-07-21T13:15:48.540315Z","steps":["trace[1583611230] 'agreement among raft nodes before linearized reading' (duration: 290.744658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:50.38944Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909824316881795771,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:15:50.890222Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909824316881795771,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:15:51.391371Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909824316881795771,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:15:52.195585Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909824316881795771,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:15:52.209474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.320777866s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-21T13:15:52.209579Z","caller":"traceutil/trace.go:171","msg":"trace[1292973720] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.320919971s","start":"2026-07-21T13:15:49.888637Z","end":"2026-07-21T13:15:52.209557Z","steps":["trace[1292973720] 'agreement among raft nodes before linearized reading' (duration: 2.320797577s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:52.209615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:49.888625Z","time spent":"2.320983134s","remote":"127.0.0.1:53460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T13:15:52.695817Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909824316881795771,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:15:53.368589Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909824316881795771,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:15:53.943217Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909824316881795771,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:15:54.207622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.993543755s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-21T13:15:54.246953Z","caller":"traceutil/trace.go:171","msg":"trace[1699150732] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.032835126s","start":"2026-07-21T13:15:52.214024Z","end":"2026-07-21T13:15:54.246859Z","steps":["trace[1699150732] 'agreement among raft nodes before linearized reading' (duration: 1.993563526s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:54.253446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:52.214007Z","time spent":"2.039393562s","remote":"127.0.0.1:53454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/21 13:15:54 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-21T13:15:54.332006Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.447236453s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T13:15:54.351726Z","caller":"traceutil/trace.go:171","msg":"trace[168847273] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"4.242153017s","start":"2026-07-21T13:15:50.109542Z","end":"2026-07-21T13:15:54.351695Z","steps":["trace[168847273] 'process raft request' (duration: 4.241891387s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:54.351875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:50.109526Z","time spent":"4.242281492s","remote":"127.0.0.1:53548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":887,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T13:15:54.352307Z","caller":"traceutil/trace.go:171","msg":"trace[1364443662] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1079; }","duration":"4.463631293s","start":"2026-07-21T13:15:49.888666Z","end":"2026-07-21T13:15:54.352297Z","steps":["trace[1364443662] 'read index received' (duration: 4.443619171s)","trace[1364443662] 'applied index is now lower than readState.Index' (duration: 20.011602ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:15:54.352806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.794095558s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-21T13:15:54.352902Z","caller":"traceutil/trace.go:171","msg":"trace[1780102983] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1041; }","duration":"3.794226533s","start":"2026-07-21T13:15:50.55865Z","end":"2026-07-21T13:15:54.352876Z","steps":["trace[1780102983] 'agreement among raft nodes before linearized reading' (duration: 3.793926063s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:54.352956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:50.558638Z","time spent":"3.794295614s","remote":"127.0.0.1:53738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-21T13:15:54.353168Z","caller":"traceutil/trace.go:171","msg":"trace[361701748] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"3.804551881s","start":"2026-07-21T13:15:50.548606Z","end":"2026-07-21T13:15:54.353158Z","steps":["trace[361701748] 'process raft request' (duration: 3.803023061s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:54.353313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:50.548576Z","time spent":"3.804614803s","remote":"127.0.0.1:53738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:15:54.353623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.587215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:15:54.353682Z","caller":"traceutil/trace.go:171","msg":"trace[1598927600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1041; }","duration":"138.674408ms","start":"2026-07-21T13:15:54.214997Z","end":"2026-07-21T13:15:54.353671Z","steps":["trace[1598927600] 'agreement among raft nodes before linearized reading' (duration: 138.596905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:54.353961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.908241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:15:54.35399Z","caller":"traceutil/trace.go:171","msg":"trace[1217633185] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1041; }","duration":"960.004425ms","start":"2026-07-21T13:15:53.393977Z","end":"2026-07-21T13:15:54.353982Z","steps":["trace[1217633185] 'agreement among raft nodes before linearized reading' (duration: 959.918782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:54.354065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:53.393955Z","time spent":"960.091647ms","remote":"127.0.0.1:53460","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-21T13:15:54.354334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.647996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:15:54.354403Z","caller":"traceutil/trace.go:171","msg":"trace[1380070917] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1041; }","duration":"982.73789ms","start":"2026-07-21T13:15:53.371644Z","end":"2026-07-21T13:15:54.354382Z","steps":["trace[1380070917] 'agreement among raft nodes before linearized reading' (duration: 982.640856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:54.35446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:53.37163Z","time spent":"982.818452ms","remote":"127.0.0.1:53760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-07-21T13:15:54.354824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.140718535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:15:54.35487Z","caller":"traceutil/trace.go:171","msg":"trace[2007318095] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1041; }","duration":"2.140812198s","start":"2026-07-21T13:15:52.214048Z","end":"2026-07-21T13:15:54.35486Z","steps":["trace[2007318095] 'agreement among raft nodes before linearized reading' (duration: 2.140695475s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:54.355017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:52.214014Z","time spent":"2.140990064s","remote":"127.0.0.1:53694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-07-21T13:15:54.355273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.79596891s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-21T13:15:54.35532Z","caller":"traceutil/trace.go:171","msg":"trace[491064755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1041; }","duration":"3.796056823s","start":"2026-07-21T13:15:50.559251Z","end":"2026-07-21T13:15:54.355308Z","steps":["trace[491064755] 'agreement among raft nodes before linearized reading' (duration: 3.795864677s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:54.355353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:50.559237Z","time spent":"3.796107694s","remote":"127.0.0.1:53738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T13:15:54.355585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.498600853s","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-21T13:15:54.355615Z","caller":"traceutil/trace.go:171","msg":"trace[544945359] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1041; }","duration":"3.498664985s","start":"2026-07-21T13:15:50.856941Z","end":"2026-07-21T13:15:54.355606Z","steps":["trace[544945359] 'agreement among raft nodes before linearized reading' (duration: 3.498581932s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:54.355639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:50.856923Z","time spent":"3.498710397s","remote":"127.0.0.1:53738","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-21T13:15:54.355722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.792873595s","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-21T13:15:54.355747Z","caller":"traceutil/trace.go:171","msg":"trace[517369805] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1041; }","duration":"3.792927377s","start":"2026-07-21T13:15:50.562813Z","end":"2026-07-21T13:15:54.35574Z","steps":["trace[517369805] 'agreement among raft nodes before linearized reading' (duration: 3.792861905s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:54.355762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:50.562794Z","time spent":"3.792964348s","remote":"127.0.0.1:53738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-21T13:15:54.355812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.774979312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T13:15:54.355825Z","caller":"traceutil/trace.go:171","msg":"trace[508099715] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1041; }","duration":"3.775014094s","start":"2026-07-21T13:15:50.580806Z","end":"2026-07-21T13:15:54.35582Z","steps":["trace[508099715] 'agreement among raft nodes before linearized reading' (duration: 3.774986983s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:54.35584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:50.580796Z","time spent":"3.775041324s","remote":"127.0.0.1:53880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-21T13:15:54.355856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.796463066s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-21T13:15:54.355897Z","caller":"traceutil/trace.go:171","msg":"trace[694286907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1041; }","duration":"3.796548679s","start":"2026-07-21T13:15:50.559338Z","end":"2026-07-21T13:15:54.355887Z","steps":["trace[694286907] 'agreement among raft nodes before linearized reading' (duration: 3.796363973s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:54.355965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:50.559323Z","time spent":"3.796616321s","remote":"127.0.0.1:53738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}