{"level":"warn","ts":"2026-07-06T12:04:34.435089Z","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-06T12:04:34.435159Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.151: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.151:2380","--initial-cluster=instance=https://199.19.213.151:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.151:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.151: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-06T12:04:34.435215Z","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-06T12:04:34.435225Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.151:2380"]} {"level":"info","ts":"2026-07-06T12:04:34.435246Z","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-06T12:04:34.435848Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.151:2379"]} {"level":"info","ts":"2026-07-06T12:04:34.43592Z","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.151:2380"],"listen-peer-urls":["https://199.19.213.151:2380"],"advertise-client-urls":["https://199.19.213.151:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.151:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.151: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-06T12:04:34.450672Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"14.562404ms"} {"level":"info","ts":"2026-07-06T12:04:34.478565Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9abf795dae4ebc2c","cluster-id":"8a186cf11cef5bce"} {"level":"info","ts":"2026-07-06T12:04:34.478654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c switched to configuration voters=()"} {"level":"info","ts":"2026-07-06T12:04:34.478684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c became follower at term 0"} {"level":"info","ts":"2026-07-06T12:04:34.478695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9abf795dae4ebc2c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-06T12:04:34.478704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c became follower at term 1"} {"level":"info","ts":"2026-07-06T12:04:34.478755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c switched to configuration voters=(11150764645655952428)"} {"level":"warn","ts":"2026-07-06T12:04:34.531064Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-06T12:04:34.540883Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-06T12:04:34.593273Z","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-06T12:04:34.622357Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9abf795dae4ebc2c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-06T12:04:34.622741Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9abf795dae4ebc2c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-06T12:04:34.622944Z","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-06T12:04:34.623495Z","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-06T12:04:34.623547Z","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-06T12:04:34.627121Z","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-06T12:04:34.627269Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.151:2380"} {"level":"info","ts":"2026-07-06T12:04:34.627432Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.151:2380"} {"level":"info","ts":"2026-07-06T12:04:34.627571Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9abf795dae4ebc2c","initial-advertise-peer-urls":["https://199.19.213.151:2380"],"listen-peer-urls":["https://199.19.213.151:2380"],"advertise-client-urls":["https://199.19.213.151:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.151:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-06T12:04:34.627631Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-06T12:04:34.631945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c switched to configuration voters=(11150764645655952428)"} {"level":"info","ts":"2026-07-06T12:04:34.632104Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8a186cf11cef5bce","local-member-id":"9abf795dae4ebc2c","added-peer-id":"9abf795dae4ebc2c","added-peer-peer-urls":["https://199.19.213.151:2380"]} {"level":"info","ts":"2026-07-06T12:04:35.183002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c is starting a new election at term 1"} {"level":"info","ts":"2026-07-06T12:04:35.183108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c became pre-candidate at term 1"} {"level":"info","ts":"2026-07-06T12:04:35.183152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c received MsgPreVoteResp from 9abf795dae4ebc2c at term 1"} {"level":"info","ts":"2026-07-06T12:04:35.183199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c became candidate at term 2"} {"level":"info","ts":"2026-07-06T12:04:35.183233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c received MsgVoteResp from 9abf795dae4ebc2c at term 2"} {"level":"info","ts":"2026-07-06T12:04:35.183264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c became leader at term 2"} {"level":"info","ts":"2026-07-06T12:04:35.183307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9abf795dae4ebc2c elected leader 9abf795dae4ebc2c at term 2"} {"level":"info","ts":"2026-07-06T12:04:35.195092Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T12:04:35.250106Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9abf795dae4ebc2c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.151:2379]}","request-path":"/0/members/9abf795dae4ebc2c/attributes","cluster-id":"8a186cf11cef5bce","publish-timeout":"7s"} {"level":"info","ts":"2026-07-06T12:04:35.250226Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-06T12:04:35.250108Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-06T12:04:35.253584Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-06T12:04:35.253915Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-06T12:04:35.256207Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-06T12:04:35.256069Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.151:2379"} {"level":"info","ts":"2026-07-06T12:04:35.28751Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8a186cf11cef5bce","local-member-id":"9abf795dae4ebc2c","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T12:04:35.287637Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T12:04:35.287692Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-07-06T12:04:35.459598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.768811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-06T12:04:35.459684Z","caller":"traceutil/trace.go:171","msg":"trace[1793079696] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"104.907997ms","start":"2026-07-06T12:04:35.354762Z","end":"2026-07-06T12:04:35.45967Z","steps":["trace[1793079696] 'range keys from in-memory index tree' (duration: 104.677657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:04:35.459824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.846164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-06T12:04:35.459858Z","caller":"traceutil/trace.go:171","msg":"trace[988662353] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"104.883016ms","start":"2026-07-06T12:04:35.354967Z","end":"2026-07-06T12:04:35.45985Z","steps":["trace[988662353] 'count revisions from in-memory index tree' (duration: 104.782731ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:04:36.916641Z","caller":"traceutil/trace.go:171","msg":"trace[1556482993] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"102.652388ms","start":"2026-07-06T12:04:36.813972Z","end":"2026-07-06T12:04:36.916624Z","steps":["trace[1556482993] 'process raft request' (duration: 29.199374ms)","trace[1556482993] 'compare' (duration: 73.360949ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T12:04:36.967168Z","caller":"traceutil/trace.go:171","msg":"trace[457846742] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"152.420654ms","start":"2026-07-06T12:04:36.814721Z","end":"2026-07-06T12:04:36.967142Z","steps":["trace[457846742] 'process raft request' (duration: 152.233676ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:04:36.967268Z","caller":"traceutil/trace.go:171","msg":"trace[18812616] linearizableReadLoop","detail":"{readStateIndex:29; appliedIndex:27; }","duration":"152.183743ms","start":"2026-07-06T12:04:36.815067Z","end":"2026-07-06T12:04:36.967251Z","steps":["trace[18812616] 'read index received' (duration: 28.111173ms)","trace[18812616] 'applied index is now lower than readState.Index' (duration: 124.07171ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T12:04:36.967286Z","caller":"traceutil/trace.go:171","msg":"trace[969925772] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"152.112579ms","start":"2026-07-06T12:04:36.815163Z","end":"2026-07-06T12:04:36.967275Z","steps":["trace[969925772] 'process raft request' (duration: 151.895849ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:04:36.967336Z","caller":"traceutil/trace.go:171","msg":"trace[741204772] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"151.844188ms","start":"2026-07-06T12:04:36.815484Z","end":"2026-07-06T12:04:36.967328Z","steps":["trace[741204772] 'process raft request' (duration: 151.707131ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:04:36.96733Z","caller":"traceutil/trace.go:171","msg":"trace[1230279283] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"151.9078ms","start":"2026-07-06T12:04:36.815401Z","end":"2026-07-06T12:04:36.967309Z","steps":["trace[1230279283] 'process raft request' (duration: 151.760423ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:04:36.967424Z","caller":"traceutil/trace.go:171","msg":"trace[977824873] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"152.156332ms","start":"2026-07-06T12:04:36.815259Z","end":"2026-07-06T12:04:36.967415Z","steps":["trace[977824873] 'process raft request' (duration: 151.867038ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:04:36.967454Z","caller":"traceutil/trace.go:171","msg":"trace[659364160] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"151.886339ms","start":"2026-07-06T12:04:36.815535Z","end":"2026-07-06T12:04:36.967421Z","steps":["trace[659364160] 'process raft request' (duration: 151.6916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:04:36.967505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.185123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-06T12:04:36.967543Z","caller":"traceutil/trace.go:171","msg":"trace[1323607017] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:30; }","duration":"152.253916ms","start":"2026-07-06T12:04:36.815274Z","end":"2026-07-06T12:04:36.967528Z","steps":["trace[1323607017] 'agreement among raft nodes before linearized reading' (duration: 152.13396ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:04:36.967563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.466976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-06T12:04:36.967638Z","caller":"traceutil/trace.go:171","msg":"trace[99574228] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:30; }","duration":"152.597682ms","start":"2026-07-06T12:04:36.815022Z","end":"2026-07-06T12:04:36.967619Z","steps":["trace[99574228] 'agreement among raft nodes before linearized reading' (duration: 152.347031ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:04:37.07926Z","caller":"traceutil/trace.go:171","msg":"trace[1939803421] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"109.909947ms","start":"2026-07-06T12:04:36.969326Z","end":"2026-07-06T12:04:37.079236Z","steps":["trace[1939803421] 'process raft request' (duration: 60.202741ms)","trace[1939803421] 'compare' (duration: 49.451905ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T12:04:37.079381Z","caller":"traceutil/trace.go:171","msg":"trace[835676849] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"108.352287ms","start":"2026-07-06T12:04:36.970996Z","end":"2026-07-06T12:04:37.079348Z","steps":["trace[835676849] 'process raft request' (duration: 108.246882ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:04:37.079627Z","caller":"traceutil/trace.go:171","msg":"trace[1985091237] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"108.931603ms","start":"2026-07-06T12:04:36.970659Z","end":"2026-07-06T12:04:37.07959Z","steps":["trace[1985091237] 'process raft request' (duration: 108.554775ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:04:37.079647Z","caller":"traceutil/trace.go:171","msg":"trace[2127613999] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"108.604478ms","start":"2026-07-06T12:04:36.971014Z","end":"2026-07-06T12:04:37.079619Z","steps":["trace[2127613999] 'process raft request' (duration: 108.259012ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:04:37.079894Z","caller":"traceutil/trace.go:171","msg":"trace[1736330993] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"109.595273ms","start":"2026-07-06T12:04:36.970184Z","end":"2026-07-06T12:04:37.07978Z","steps":["trace[1736330993] 'process raft request' (duration: 108.984885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:04:37.316364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.042314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-06T12:04:37.316877Z","caller":"traceutil/trace.go:171","msg":"trace[1203259060] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"222.236051ms","start":"2026-07-06T12:04:37.094614Z","end":"2026-07-06T12:04:37.31685Z","steps":["trace[1203259060] 'process raft request' (duration: 221.955288ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:04:37.316974Z","caller":"traceutil/trace.go:171","msg":"trace[498867705] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"205.795929ms","start":"2026-07-06T12:04:37.111156Z","end":"2026-07-06T12:04:37.316952Z","steps":["trace[498867705] 'process raft request' (duration: 205.544567ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:04:37.317093Z","caller":"traceutil/trace.go:171","msg":"trace[567138539] linearizableReadLoop","detail":"{readStateIndex:42; appliedIndex:40; }","duration":"215.472958ms","start":"2026-07-06T12:04:37.101609Z","end":"2026-07-06T12:04:37.317082Z","steps":["trace[567138539] 'read index received' (duration: 87.265689ms)","trace[567138539] 'applied index is now lower than readState.Index' (duration: 128.206108ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T12:04:37.317297Z","caller":"traceutil/trace.go:171","msg":"trace[202952380] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"224.443063ms","start":"2026-07-06T12:04:37.092827Z","end":"2026-07-06T12:04:37.31727Z","steps":["trace[202952380] 'process raft request' (duration: 96.046686ms)","trace[202952380] 'compare' (duration: 126.932719ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T12:04:37.317352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.452712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-06T12:04:37.317383Z","caller":"traceutil/trace.go:171","msg":"trace[153093577] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:40; }","duration":"136.510544ms","start":"2026-07-06T12:04:37.18086Z","end":"2026-07-06T12:04:37.317371Z","steps":["trace[153093577] 'agreement among raft nodes before linearized reading' (duration: 136.474742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:04:37.317307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.679277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-06T12:04:37.317624Z","caller":"traceutil/trace.go:171","msg":"trace[1154942026] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:40; }","duration":"216.004682ms","start":"2026-07-06T12:04:37.101605Z","end":"2026-07-06T12:04:37.31761Z","steps":["trace[1154942026] 'agreement among raft nodes before linearized reading' (duration: 215.629795ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:04:37.619606Z","caller":"traceutil/trace.go:171","msg":"trace[1580322456] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"221.427043ms","start":"2026-07-06T12:04:37.39815Z","end":"2026-07-06T12:04:37.619577Z","steps":["trace[1580322456] 'process raft request' (duration: 177.023652ms)","trace[1580322456] 'compare' (duration: 44.246654ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T12:04:37.621214Z","caller":"traceutil/trace.go:171","msg":"trace[509058690] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"209.605173ms","start":"2026-07-06T12:04:37.411594Z","end":"2026-07-06T12:04:37.621199Z","steps":["trace[509058690] 'process raft request' (duration: 209.029787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:04:38.062585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.80873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-06T12:04:38.062684Z","caller":"traceutil/trace.go:171","msg":"trace[994921156] linearizableReadLoop","detail":"{readStateIndex:63; appliedIndex:62; }","duration":"158.666234ms","start":"2026-07-06T12:04:37.904002Z","end":"2026-07-06T12:04:38.062668Z","steps":["trace[994921156] 'read index received' (duration: 10.667315ms)","trace[994921156] 'applied index is now lower than readState.Index' (duration: 147.995629ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T12:04:38.062703Z","caller":"traceutil/trace.go:171","msg":"trace[432055904] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"161.183481ms","start":"2026-07-06T12:04:37.901486Z","end":"2026-07-06T12:04:38.06267Z","steps":["trace[432055904] 'process raft request' (duration: 13.224923ms)","trace[432055904] 'compare' (duration: 147.677985ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T12:04:38.062791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.78018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-06T12:04:38.062827Z","caller":"traceutil/trace.go:171","msg":"trace[866856785] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:59; }","duration":"158.850603ms","start":"2026-07-06T12:04:37.903966Z","end":"2026-07-06T12:04:38.062817Z","steps":["trace[866856785] 'agreement among raft nodes before linearized reading' (duration: 158.766899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:04:38.064133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.91336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/endpoint-controller\" ","response":"range_response_count:1 size:1256"} {"level":"info","ts":"2026-07-06T12:04:38.064192Z","caller":"traceutil/trace.go:171","msg":"trace[925194820] range","detail":"{range_begin:/registry/flowschemas/endpoint-controller; range_end:; response_count:1; response_revision:61; }","duration":"157.999604ms","start":"2026-07-06T12:04:37.906174Z","end":"2026-07-06T12:04:38.064174Z","steps":["trace[925194820] 'agreement among raft nodes before linearized reading' (duration: 157.875568ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:04:38.064486Z","caller":"traceutil/trace.go:171","msg":"trace[553132483] transaction","detail":"{read_only:false; response_revision:61; number_of_response:1; }","duration":"159.020091ms","start":"2026-07-06T12:04:37.90544Z","end":"2026-07-06T12:04:38.064424Z","steps":["trace[553132483] 'process raft request' (duration: 158.583911ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:04:38.064516Z","caller":"traceutil/trace.go:171","msg":"trace[154748431] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"159.858989ms","start":"2026-07-06T12:04:37.904632Z","end":"2026-07-06T12:04:38.064491Z","steps":["trace[154748431] 'process raft request' (duration: 159.334815ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:04:40.520328Z","caller":"traceutil/trace.go:171","msg":"trace[1610647247] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"109.996037ms","start":"2026-07-06T12:04:40.410304Z","end":"2026-07-06T12:04:40.520301Z","steps":["trace[1610647247] 'process raft request' (duration: 109.859561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:04:41.550877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.350085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-06T12:04:41.551Z","caller":"traceutil/trace.go:171","msg":"trace[929029801] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"368.490491ms","start":"2026-07-06T12:04:41.182489Z","end":"2026-07-06T12:04:41.550979Z","steps":["trace[929029801] 'process raft request' (duration: 256.967269ms)","trace[929029801] 'compare' (duration: 110.620886ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T12:04:41.551054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:04:41.182469Z","time spent":"368.563304ms","remote":"127.0.0.1:35204","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-06T12:04:41.583025Z","caller":"traceutil/trace.go:171","msg":"trace[1991431286] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"395.569843ms","start":"2026-07-06T12:04:41.187425Z","end":"2026-07-06T12:04:41.582995Z","steps":["trace[1991431286] 'process raft request' (duration: 395.334134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:04:41.583444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:04:41.187398Z","time spent":"395.715219ms","remote":"127.0.0.1:35054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4649,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T12:04:42.262958Z","caller":"traceutil/trace.go:171","msg":"trace[787457799] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"128.762149ms","start":"2026-07-06T12:04:42.134166Z","end":"2026-07-06T12:04:42.262928Z","steps":["trace[787457799] 'process raft request' (duration: 96.940859ms)","trace[787457799] 'compare' (duration: 31.615842ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T12:04:42.263406Z","caller":"traceutil/trace.go:171","msg":"trace[1761447758] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"129.146556ms","start":"2026-07-06T12:04:42.134245Z","end":"2026-07-06T12:04:42.263392Z","steps":["trace[1761447758] 'process raft request' (duration: 128.964287ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:04:42.263475Z","caller":"traceutil/trace.go:171","msg":"trace[1942021181] transaction","detail":"{read_only:false; number_of_response:0; response_revision:241; }","duration":"129.161346ms","start":"2026-07-06T12:04:42.134286Z","end":"2026-07-06T12:04:42.263447Z","steps":["trace[1942021181] 'process raft request' (duration: 128.995367ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:04:42.263585Z","caller":"traceutil/trace.go:171","msg":"trace[1603179037] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"129.319315ms","start":"2026-07-06T12:04:42.134199Z","end":"2026-07-06T12:04:42.263519Z","steps":["trace[1603179037] 'process raft request' (duration: 128.67379ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:04:42.263716Z","caller":"traceutil/trace.go:171","msg":"trace[587823409] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"129.364545ms","start":"2026-07-06T12:04:42.134324Z","end":"2026-07-06T12:04:42.263688Z","steps":["trace[587823409] 'process raft request' (duration: 128.979098ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:04:42.263943Z","caller":"traceutil/trace.go:171","msg":"trace[1298564001] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"129.537222ms","start":"2026-07-06T12:04:42.134388Z","end":"2026-07-06T12:04:42.263925Z","steps":["trace[1298564001] 'process raft request' (duration: 128.957877ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:04:42.264005Z","caller":"traceutil/trace.go:171","msg":"trace[928655508] transaction","detail":"{read_only:false; number_of_response:0; response_revision:243; }","duration":"127.483791ms","start":"2026-07-06T12:04:42.136508Z","end":"2026-07-06T12:04:42.263991Z","steps":["trace[928655508] 'process raft request' (duration: 127.383672ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:04:42.263951Z","caller":"traceutil/trace.go:171","msg":"trace[896287991] transaction","detail":"{read_only:false; number_of_response:0; response_revision:243; }","duration":"129.439272ms","start":"2026-07-06T12:04:42.134494Z","end":"2026-07-06T12:04:42.263933Z","steps":["trace[896287991] 'process raft request' (duration: 129.363783ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:04:42.264099Z","caller":"traceutil/trace.go:171","msg":"trace[1180502916] linearizableReadLoop","detail":"{readStateIndex:248; appliedIndex:244; }","duration":"129.78259ms","start":"2026-07-06T12:04:42.134287Z","end":"2026-07-06T12:04:42.264069Z","steps":["trace[1180502916] 'read index received' (duration: 33.097849ms)","trace[1180502916] 'applied index is now lower than readState.Index' (duration: 96.683331ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T12:04:42.264211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.901469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3806"} {"level":"info","ts":"2026-07-06T12:04:42.264246Z","caller":"traceutil/trace.go:171","msg":"trace[119552958] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:244; }","duration":"129.969959ms","start":"2026-07-06T12:04:42.134268Z","end":"2026-07-06T12:04:42.264238Z","steps":["trace[119552958] 'agreement among raft nodes before linearized reading' (duration: 129.87225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:04:42.485201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.659449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-06T12:04:42.485267Z","caller":"traceutil/trace.go:171","msg":"trace[2118852374] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"128.934318ms","start":"2026-07-06T12:04:42.35632Z","end":"2026-07-06T12:04:42.485255Z","steps":["trace[2118852374] 'process raft request' (duration: 26.17313ms)","trace[2118852374] 'compare' (duration: 102.572379ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T12:04:42.615005Z","caller":"traceutil/trace.go:171","msg":"trace[2074049292] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"111.675109ms","start":"2026-07-06T12:04:42.50331Z","end":"2026-07-06T12:04:42.614986Z","steps":["trace[2074049292] 'process raft request' (duration: 101.30804ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:04:45.477433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.177262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-06T12:04:45.477529Z","caller":"traceutil/trace.go:171","msg":"trace[1167795886] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:292; }","duration":"108.331421ms","start":"2026-07-06T12:04:45.369175Z","end":"2026-07-06T12:04:45.477506Z","steps":["trace[1167795886] 'range keys from in-memory index tree' (duration: 108.036653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:04:45.985137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.585893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-06T12:04:45.985229Z","caller":"traceutil/trace.go:171","msg":"trace[1329113192] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:296; }","duration":"115.721902ms","start":"2026-07-06T12:04:45.869489Z","end":"2026-07-06T12:04:45.985211Z","steps":["trace[1329113192] 'range keys from in-memory index tree' (duration: 115.424274ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:04:46.103825Z","caller":"traceutil/trace.go:171","msg":"trace[238130772] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"115.397868ms","start":"2026-07-06T12:04:45.988401Z","end":"2026-07-06T12:04:46.103799Z","steps":["trace[238130772] 'process raft request' (duration: 115.221859ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:04:49.491353Z","caller":"traceutil/trace.go:171","msg":"trace[1352101172] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"787.171085ms","start":"2026-07-06T12:04:48.704157Z","end":"2026-07-06T12:04:49.491328Z","steps":["trace[1352101172] 'process raft request' (duration: 787.020795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:04:49.491508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:04:48.704122Z","time spent":"787.309914ms","remote":"127.0.0.1:35118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T12:04:49.80163Z","caller":"traceutil/trace.go:171","msg":"trace[1910050465] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"149.679972ms","start":"2026-07-06T12:04:49.65193Z","end":"2026-07-06T12:04:49.80161Z","steps":["trace[1910050465] 'process raft request' (duration: 148.315117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:04:55.736571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.701817ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-06T12:04:55.736679Z","caller":"traceutil/trace.go:171","msg":"trace[2126622098] linearizableReadLoop","detail":"{readStateIndex:398; appliedIndex:397; }","duration":"445.761858ms","start":"2026-07-06T12:04:55.290903Z","end":"2026-07-06T12:04:55.736665Z","steps":["trace[2126622098] 'read index received' (duration: 339.103121ms)","trace[2126622098] 'applied index is now lower than readState.Index' (duration: 106.657537ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T12:04:55.736768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.866008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T12:04:55.736791Z","caller":"traceutil/trace.go:171","msg":"trace[1428457707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:381; }","duration":"445.935228ms","start":"2026-07-06T12:04:55.290849Z","end":"2026-07-06T12:04:55.736785Z","steps":["trace[1428457707] 'agreement among raft nodes before linearized reading' (duration: 445.882198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:04:55.736815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:04:55.290829Z","time spent":"445.980648ms","remote":"127.0.0.1:34858","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-06T12:04:55.736854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.832618ms","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-06T12:04:55.73694Z","caller":"traceutil/trace.go:171","msg":"trace[239376205] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:381; }","duration":"221.955608ms","start":"2026-07-06T12:04:55.514959Z","end":"2026-07-06T12:04:55.736914Z","steps":["trace[239376205] 'agreement among raft nodes before linearized reading' (duration: 221.810678ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:05:04.402183Z","caller":"traceutil/trace.go:171","msg":"trace[1743738736] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:450; }","duration":"447.014373ms","start":"2026-07-06T12:05:03.955152Z","end":"2026-07-06T12:05:04.402167Z","steps":["trace[1743738736] 'read index received' (duration: 446.859982ms)","trace[1743738736] 'applied index is now lower than readState.Index' (duration: 153.831µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T12:05:04.402263Z","caller":"traceutil/trace.go:171","msg":"trace[1289495655] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"556.342841ms","start":"2026-07-06T12:05:03.845915Z","end":"2026-07-06T12:05:04.402258Z","steps":["trace[1289495655] 'process raft request' (duration: 556.14205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:04.402346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:03.845896Z","time spent":"556.383972ms","remote":"127.0.0.1:35118","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-06T12:05:04.402514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.330105ms","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-06T12:05:04.40254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.846826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T12:05:04.402702Z","caller":"traceutil/trace.go:171","msg":"trace[426951516] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:431; }","duration":"112.068068ms","start":"2026-07-06T12:05:04.290595Z","end":"2026-07-06T12:05:04.402663Z","steps":["trace[426951516] 'agreement among raft nodes before linearized reading' (duration: 111.803246ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:05:04.40262Z","caller":"traceutil/trace.go:171","msg":"trace[162425905] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:431; }","duration":"447.505925ms","start":"2026-07-06T12:05:03.955093Z","end":"2026-07-06T12:05:04.402599Z","steps":["trace[162425905] 'agreement among raft nodes before linearized reading' (duration: 447.307554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:04.40276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:03.955069Z","time spent":"447.682376ms","remote":"127.0.0.1:35118","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-06T12:05:06.706784Z","caller":"traceutil/trace.go:171","msg":"trace[673837775] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"176.878858ms","start":"2026-07-06T12:05:06.529854Z","end":"2026-07-06T12:05:06.706733Z","steps":["trace[673837775] 'process raft request' (duration: 125.317935ms)","trace[673837775] 'compare' (duration: 51.367012ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T12:05:16.324452Z","caller":"traceutil/trace.go:171","msg":"trace[2100283067] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"780.639945ms","start":"2026-07-06T12:05:15.543787Z","end":"2026-07-06T12:05:16.324427Z","steps":["trace[2100283067] 'process raft request' (duration: 780.451703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:16.324603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:15.543767Z","time spent":"780.771227ms","remote":"127.0.0.1:34892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T12:05:16.332706Z","caller":"traceutil/trace.go:171","msg":"trace[2101553881] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"782.787214ms","start":"2026-07-06T12:05:15.549891Z","end":"2026-07-06T12:05:16.332679Z","steps":["trace[2101553881] 'process raft request' (duration: 782.536991ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:05:16.332745Z","caller":"traceutil/trace.go:171","msg":"trace[1646352461] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"782.790603ms","start":"2026-07-06T12:05:15.549938Z","end":"2026-07-06T12:05:16.332728Z","steps":["trace[1646352461] 'process raft request' (duration: 782.662822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:16.332807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:15.549877Z","time spent":"782.889365ms","remote":"127.0.0.1:34892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T12:05:16.332805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:15.549919Z","time spent":"782.851424ms","remote":"127.0.0.1:34892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T12:05:16.332946Z","caller":"traceutil/trace.go:171","msg":"trace[16224376] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"774.358057ms","start":"2026-07-06T12:05:15.558579Z","end":"2026-07-06T12:05:16.332937Z","steps":["trace[16224376] 'process raft request' (duration: 774.100563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:16.333002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:15.558562Z","time spent":"774.413747ms","remote":"127.0.0.1:34892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T12:05:16.333038Z","caller":"traceutil/trace.go:171","msg":"trace[1755244572] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"776.228799ms","start":"2026-07-06T12:05:15.5568Z","end":"2026-07-06T12:05:16.333029Z","steps":["trace[1755244572] 'process raft request' (duration: 775.841663ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:05:16.333083Z","caller":"traceutil/trace.go:171","msg":"trace[1215502828] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"773.292714ms","start":"2026-07-06T12:05:15.559782Z","end":"2026-07-06T12:05:16.333075Z","steps":["trace[1215502828] 'process raft request' (duration: 772.97193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:16.333087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:15.556783Z","time spent":"776.283419ms","remote":"127.0.0.1:34892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T12:05:16.333128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:15.559771Z","time spent":"773.337945ms","remote":"127.0.0.1:34892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T12:05:16.333194Z","caller":"traceutil/trace.go:171","msg":"trace[817834179] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"773.474286ms","start":"2026-07-06T12:05:15.559712Z","end":"2026-07-06T12:05:16.333186Z","steps":["trace[817834179] 'process raft request' (duration: 773.009609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:16.333234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:15.559702Z","time spent":"773.514247ms","remote":"127.0.0.1:34892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T12:05:16.333268Z","caller":"traceutil/trace.go:171","msg":"trace[2034587677] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"770.44268ms","start":"2026-07-06T12:05:15.562816Z","end":"2026-07-06T12:05:16.333258Z","steps":["trace[2034587677] 'process raft request' (duration: 769.971254ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:05:16.333318Z","caller":"traceutil/trace.go:171","msg":"trace[345808146] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"766.443905ms","start":"2026-07-06T12:05:15.566865Z","end":"2026-07-06T12:05:16.333309Z","steps":["trace[345808146] 'process raft request' (duration: 766.068431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:16.333321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:15.562809Z","time spent":"770.489081ms","remote":"127.0.0.1:34892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T12:05:16.333362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:15.566857Z","time spent":"766.487095ms","remote":"127.0.0.1:34892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T12:05:16.333508Z","caller":"traceutil/trace.go:171","msg":"trace[731733928] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"759.545134ms","start":"2026-07-06T12:05:15.573953Z","end":"2026-07-06T12:05:16.333498Z","steps":["trace[731733928] 'process raft request' (duration: 759.038458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:16.333552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:15.573942Z","time spent":"759.592805ms","remote":"127.0.0.1:34892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T12:05:16.333741Z","caller":"traceutil/trace.go:171","msg":"trace[1110457115] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"737.431628ms","start":"2026-07-06T12:05:15.596296Z","end":"2026-07-06T12:05:16.333727Z","steps":["trace[1110457115] 'process raft request' (duration: 737.007463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:16.333786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:15.596279Z","time spent":"737.489218ms","remote":"127.0.0.1:34892","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-06T12:05:16.333912Z","caller":"traceutil/trace.go:171","msg":"trace[1008885415] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"613.751911ms","start":"2026-07-06T12:05:15.720153Z","end":"2026-07-06T12:05:16.333905Z","steps":["trace[1008885415] 'process raft request' (duration: 613.556609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:16.333961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:15.720119Z","time spent":"613.821052ms","remote":"127.0.0.1:34892","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-06T12:05:22.548448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.244236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T12:05:22.548543Z","caller":"traceutil/trace.go:171","msg":"trace[107569309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"258.387268ms","start":"2026-07-06T12:05:22.290136Z","end":"2026-07-06T12:05:22.548523Z","steps":["trace[107569309] 'range keys from in-memory index tree' (duration: 258.160974ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:05:23.044433Z","caller":"traceutil/trace.go:171","msg":"trace[629718153] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"494.820348ms","start":"2026-07-06T12:05:22.549591Z","end":"2026-07-06T12:05:23.044411Z","steps":["trace[629718153] 'read index received' (duration: 494.032587ms)","trace[629718153] 'applied index is now lower than readState.Index' (duration: 786.551µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T12:05:23.04457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.964041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T12:05:23.044605Z","caller":"traceutil/trace.go:171","msg":"trace[1860301103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"495.046442ms","start":"2026-07-06T12:05:22.549544Z","end":"2026-07-06T12:05:23.044591Z","steps":["trace[1860301103] 'agreement among raft nodes before linearized reading' (duration: 494.977441ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:05:23.044587Z","caller":"traceutil/trace.go:171","msg":"trace[888822187] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"519.696035ms","start":"2026-07-06T12:05:22.524845Z","end":"2026-07-06T12:05:23.044541Z","steps":["trace[888822187] 'process raft request' (duration: 518.944424ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:23.044648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:22.549528Z","time spent":"495.112063ms","remote":"127.0.0.1:34864","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-06T12:05:23.044775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:22.524813Z","time spent":"519.862408ms","remote":"127.0.0.1:34940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T12:05:23.549735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.580525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T12:05:23.54981Z","caller":"traceutil/trace.go:171","msg":"trace[197049637] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"505.295739ms","start":"2026-07-06T12:05:23.044501Z","end":"2026-07-06T12:05:23.549797Z","steps":["trace[197049637] 'read index received' (duration: 115.584192ms)","trace[197049637] 'applied index is now lower than readState.Index' (duration: 389.710857ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T12:05:23.549868Z","caller":"traceutil/trace.go:171","msg":"trace[405811121] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"901.803949ms","start":"2026-07-06T12:05:22.648008Z","end":"2026-07-06T12:05:23.549812Z","steps":["trace[405811121] 'process raft request' (duration: 512.071762ms)","trace[405811121] 'compare' (duration: 389.481943ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T12:05:23.54993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.953295ms","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-06T12:05:23.549961Z","caller":"traceutil/trace.go:171","msg":"trace[1132391903] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:593; }","duration":"949.014475ms","start":"2026-07-06T12:05:22.600936Z","end":"2026-07-06T12:05:23.54995Z","steps":["trace[1132391903] 'agreement among raft nodes before linearized reading' (duration: 948.914074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:23.550004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:22.60092Z","time spent":"949.075596ms","remote":"127.0.0.1:35118","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-06T12:05:23.550034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:22.647989Z","time spent":"901.956861ms","remote":"127.0.0.1:35118","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-06T12:05:23.550034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.997445ms","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-06T12:05:23.550095Z","caller":"traceutil/trace.go:171","msg":"trace[1856618449] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:593; }","duration":"949.066896ms","start":"2026-07-06T12:05:22.60101Z","end":"2026-07-06T12:05:23.550077Z","steps":["trace[1856618449] 'agreement among raft nodes before linearized reading' (duration: 948.933634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:23.550139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:22.601007Z","time spent":"949.124927ms","remote":"127.0.0.1:35118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-06T12:05:23.550188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.805853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-06T12:05:23.550273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.118301ms","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-06T12:05:23.55032Z","caller":"traceutil/trace.go:171","msg":"trace[1171038766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"760.205462ms","start":"2026-07-06T12:05:22.7901Z","end":"2026-07-06T12:05:23.550305Z","steps":["trace[1171038766] 'agreement among raft nodes before linearized reading' (duration: 760.05222ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:05:23.550339Z","caller":"traceutil/trace.go:171","msg":"trace[622514632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:593; }","duration":"869.035806ms","start":"2026-07-06T12:05:22.681233Z","end":"2026-07-06T12:05:23.550269Z","steps":["trace[622514632] 'agreement among raft nodes before linearized reading' (duration: 868.697111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:23.550458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:22.681217Z","time spent":"869.223889ms","remote":"127.0.0.1:35118","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-06T12:05:23.550354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:22.790066Z","time spent":"760.281383ms","remote":"127.0.0.1:35118","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":"info","ts":"2026-07-06T12:05:23.606455Z","caller":"traceutil/trace.go:171","msg":"trace[1340678469] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"504.341285ms","start":"2026-07-06T12:05:23.102056Z","end":"2026-07-06T12:05:23.606397Z","steps":["trace[1340678469] 'process raft request' (duration: 504.151822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:23.606663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:23.102031Z","time spent":"504.534857ms","remote":"127.0.0.1:35170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T12:05:24.790495Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13559387638034478944,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T12:05:25.291391Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13559387638034478944,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T12:05:25.791946Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13559387638034478944,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T12:05:26.290106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000339758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-06T12:05:26.290175Z","caller":"traceutil/trace.go:171","msg":"trace[182345449] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000456269s","start":"2026-07-06T12:05:24.289705Z","end":"2026-07-06T12:05:26.290161Z","steps":["trace[182345449] 'agreement among raft nodes before linearized reading' (duration: 2.000361748s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:26.290209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:24.289688Z","time spent":"2.00051509s","remote":"127.0.0.1:34858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/06 12:05:26 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-06T12:05:26.292289Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13559387638034478944,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T12:05:26.792826Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13559387638034478944,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T12:05:27.043206Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.989114564s","expected-duration":"1s"} {"level":"info","ts":"2026-07-06T12:05:27.045902Z","caller":"traceutil/trace.go:171","msg":"trace[228683274] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"2.993069829s","start":"2026-07-06T12:05:24.052809Z","end":"2026-07-06T12:05:27.045879Z","steps":["trace[228683274] 'process raft request' (duration: 2.991461641s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:27.04611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:24.052782Z","time spent":"2.993191111s","remote":"127.0.0.1:34940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036201,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T12:05:27.424863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.599375ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-06T12:05:27.425025Z","caller":"traceutil/trace.go:171","msg":"trace[986678408] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:626; }","duration":"3.135248656s","start":"2026-07-06T12:05:24.289736Z","end":"2026-07-06T12:05:27.424985Z","steps":["trace[986678408] 'read index received' (duration: 2.753711069s)","trace[986678408] 'applied index is now lower than readState.Index' (duration: 381.536227ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T12:05:27.46154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.837951082s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-06T12:05:27.461592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171239658s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T12:05:27.461597Z","caller":"traceutil/trace.go:171","msg":"trace[981119382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:600; }","duration":"1.838039943s","start":"2026-07-06T12:05:25.623542Z","end":"2026-07-06T12:05:27.461582Z","steps":["trace[981119382] 'agreement among raft nodes before linearized reading' (duration: 1.837913241s)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:05:27.461614Z","caller":"traceutil/trace.go:171","msg":"trace[790296298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"1.171283568s","start":"2026-07-06T12:05:26.290321Z","end":"2026-07-06T12:05:27.461605Z","steps":["trace[790296298] 'agreement among raft nodes before linearized reading' (duration: 1.171254237s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:27.461631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:26.290306Z","time spent":"1.171320528s","remote":"127.0.0.1:34864","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-06T12:05:27.461628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:25.623531Z","time spent":"1.838091003s","remote":"127.0.0.1:35118","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-06T12:05:27.461684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.838122644s","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-06T12:05:27.461539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.838138265s","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-06T12:05:27.461764Z","caller":"traceutil/trace.go:171","msg":"trace[194964336] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:600; }","duration":"1.838228165s","start":"2026-07-06T12:05:25.623516Z","end":"2026-07-06T12:05:27.461744Z","steps":["trace[194964336] 'agreement among raft nodes before linearized reading' (duration: 1.837967421s)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:05:27.46162Z","caller":"traceutil/trace.go:171","msg":"trace[1766176769] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"364.851517ms","start":"2026-07-06T12:05:27.096716Z","end":"2026-07-06T12:05:27.461567Z","steps":["trace[1766176769] 'process raft request' (duration: 364.584443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:27.461812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:25.623509Z","time spent":"1.838289127s","remote":"127.0.0.1:35118","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-06T12:05:27.461924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:27.096689Z","time spent":"365.159663ms","remote":"127.0.0.1:35182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T12:05:27.461803Z","caller":"traceutil/trace.go:171","msg":"trace[1367821115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:600; }","duration":"1.838442059s","start":"2026-07-06T12:05:25.623344Z","end":"2026-07-06T12:05:27.461786Z","steps":["trace[1367821115] 'agreement among raft nodes before linearized reading' (duration: 1.838077373s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:27.462089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:25.623327Z","time spent":"1.838753444s","remote":"127.0.0.1:35118","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-06T12:05:27.462454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.838577332s","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-06T12:05:27.463391Z","caller":"traceutil/trace.go:171","msg":"trace[196183481] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:600; }","duration":"1.839596959s","start":"2026-07-06T12:05:25.62378Z","end":"2026-07-06T12:05:27.463377Z","steps":["trace[196183481] 'agreement among raft nodes before linearized reading' (duration: 1.83787599s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:27.463436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:25.623773Z","time spent":"1.83963852s","remote":"127.0.0.1:35118","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-06T12:05:29.299404Z","caller":"traceutil/trace.go:171","msg":"trace[1535402975] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"312.034305ms","start":"2026-07-06T12:05:28.987266Z","end":"2026-07-06T12:05:29.2993Z","steps":["trace[1535402975] 'process raft request' (duration: 311.16779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:29.299937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:28.987229Z","time spent":"312.448193ms","remote":"127.0.0.1:34940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T12:05:29.300069Z","caller":"traceutil/trace.go:171","msg":"trace[923405874] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"287.936688ms","start":"2026-07-06T12:05:29.012083Z","end":"2026-07-06T12:05:29.300019Z","steps":["trace[923405874] 'read index received' (duration: 285.989375ms)","trace[923405874] 'applied index is now lower than readState.Index' (duration: 1.945603ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T12:05:29.300332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.258455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-06T12:05:29.300436Z","caller":"traceutil/trace.go:171","msg":"trace[1058275109] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:621; }","duration":"288.378937ms","start":"2026-07-06T12:05:29.012015Z","end":"2026-07-06T12:05:29.300394Z","steps":["trace[1058275109] 'agreement among raft nodes before linearized reading' (duration: 288.256104ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:05:39.680835Z","caller":"traceutil/trace.go:171","msg":"trace[273580338] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"120.650647ms","start":"2026-07-06T12:05:39.560164Z","end":"2026-07-06T12:05:39.680815Z","steps":["trace[273580338] 'process raft request' (duration: 120.511155ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:05:39.843084Z","caller":"traceutil/trace.go:171","msg":"trace[1621596309] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"282.036693ms","start":"2026-07-06T12:05:39.561031Z","end":"2026-07-06T12:05:39.843068Z","steps":["trace[1621596309] 'process raft request' (duration: 281.91791ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:05:39.843214Z","caller":"traceutil/trace.go:171","msg":"trace[445416273] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"282.135785ms","start":"2026-07-06T12:05:39.561073Z","end":"2026-07-06T12:05:39.843209Z","steps":["trace[445416273] 'process raft request' (duration: 281.964361ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:05:39.843303Z","caller":"traceutil/trace.go:171","msg":"trace[1941399915] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:780; }","duration":"192.05826ms","start":"2026-07-06T12:05:39.651239Z","end":"2026-07-06T12:05:39.843297Z","steps":["trace[1941399915] 'read index received' (duration: 29.400389ms)","trace[1941399915] 'applied index is now lower than readState.Index' (duration: 162.657451ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T12:05:39.84339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.154432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.151\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-06T12:05:39.843408Z","caller":"traceutil/trace.go:171","msg":"trace[1138914351] range","detail":"{range_begin:/registry/masterleases/199.19.213.151; range_end:; response_count:1; response_revision:752; }","duration":"192.227324ms","start":"2026-07-06T12:05:39.651176Z","end":"2026-07-06T12:05:39.843403Z","steps":["trace[1138914351] 'agreement among raft nodes before linearized reading' (duration: 192.141952ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:05:39.84352Z","caller":"traceutil/trace.go:171","msg":"trace[2055463600] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"282.326139ms","start":"2026-07-06T12:05:39.561185Z","end":"2026-07-06T12:05:39.843511Z","steps":["trace[2055463600] 'process raft request' (duration: 282.003202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:43.568119Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13559387638034479275,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T12:05:43.619806Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.027574194s","expected-duration":"1s"} {"level":"info","ts":"2026-07-06T12:05:43.62017Z","caller":"traceutil/trace.go:171","msg":"trace[454429801] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"1.028089456s","start":"2026-07-06T12:05:42.592057Z","end":"2026-07-06T12:05:43.620147Z","steps":["trace[454429801] 'process raft request' (duration: 1.027959343s)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:05:43.620169Z","caller":"traceutil/trace.go:171","msg":"trace[1671630537] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:801; }","duration":"552.498703ms","start":"2026-07-06T12:05:43.067647Z","end":"2026-07-06T12:05:43.620145Z","steps":["trace[1671630537] 'read index received' (duration: 552.489922ms)","trace[1671630537] 'applied index is now lower than readState.Index' (duration: 7.051µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T12:05:43.620312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:42.592034Z","time spent":"1.028207998s","remote":"127.0.0.1:35100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T12:05:43.62062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.80618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-06T12:05:43.620923Z","caller":"traceutil/trace.go:171","msg":"trace[1504714692] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:768; }","duration":"553.277011ms","start":"2026-07-06T12:05:43.067609Z","end":"2026-07-06T12:05:43.620886Z","steps":["trace[1504714692] 'agreement among raft nodes before linearized reading' (duration: 552.688457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:43.620979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:43.067592Z","time spent":"553.374194ms","remote":"127.0.0.1:34940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":9,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-07-06T12:05:43.627418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.167999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T12:05:43.627611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.940032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-06T12:05:43.62772Z","caller":"traceutil/trace.go:171","msg":"trace[1943841618] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:769; }","duration":"220.100545ms","start":"2026-07-06T12:05:43.407604Z","end":"2026-07-06T12:05:43.627705Z","steps":["trace[1943841618] 'agreement among raft nodes before linearized reading' (duration: 219.933452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:43.627913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.034058ms","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-06T12:05:43.627995Z","caller":"traceutil/trace.go:171","msg":"trace[1915511719] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:769; }","duration":"213.196741ms","start":"2026-07-06T12:05:43.414779Z","end":"2026-07-06T12:05:43.627975Z","steps":["trace[1915511719] 'agreement among raft nodes before linearized reading' (duration: 212.872444ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:05:43.627473Z","caller":"traceutil/trace.go:171","msg":"trace[234523890] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:769; }","duration":"343.276581ms","start":"2026-07-06T12:05:43.284183Z","end":"2026-07-06T12:05:43.627459Z","steps":["trace[234523890] 'agreement among raft nodes before linearized reading' (duration: 343.168709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:43.628184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:43.284161Z","time spent":"343.989206ms","remote":"127.0.0.1:35020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-07-06T12:05:43.628262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.45286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T12:05:43.62831Z","caller":"traceutil/trace.go:171","msg":"trace[2052174061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:769; }","duration":"337.524171ms","start":"2026-07-06T12:05:43.290774Z","end":"2026-07-06T12:05:43.628299Z","steps":["trace[2052174061] 'agreement among raft nodes before linearized reading' (duration: 337.44823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:43.628341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:43.290762Z","time spent":"337.573133ms","remote":"127.0.0.1:34858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-06T12:05:56.983045Z","caller":"traceutil/trace.go:171","msg":"trace[194378904] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"110.920287ms","start":"2026-07-06T12:05:56.872106Z","end":"2026-07-06T12:05:56.983027Z","steps":["trace[194378904] 'process raft request' (duration: 110.819104ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:05:58.016411Z","caller":"traceutil/trace.go:171","msg":"trace[2075795147] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:967; }","duration":"231.520744ms","start":"2026-07-06T12:05:57.784869Z","end":"2026-07-06T12:05:58.01639Z","steps":["trace[2075795147] 'read index received' (duration: 231.332039ms)","trace[2075795147] 'applied index is now lower than readState.Index' (duration: 187.595µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T12:05:58.016464Z","caller":"traceutil/trace.go:171","msg":"trace[333917356] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"270.351177ms","start":"2026-07-06T12:05:57.74609Z","end":"2026-07-06T12:05:58.016441Z","steps":["trace[333917356] 'process raft request' (duration: 270.163143ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:58.020567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.688864ms","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-06T12:05:58.020823Z","caller":"traceutil/trace.go:171","msg":"trace[1701948193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:931; }","duration":"235.817017ms","start":"2026-07-06T12:05:57.784818Z","end":"2026-07-06T12:05:58.020635Z","steps":["trace[1701948193] 'agreement among raft nodes before linearized reading' (duration: 231.669868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:58.710447Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13559387638034479594,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-06T12:05:58.809513Z","caller":"traceutil/trace.go:171","msg":"trace[519063310] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:977; }","duration":"599.802738ms","start":"2026-07-06T12:05:58.209687Z","end":"2026-07-06T12:05:58.80949Z","steps":["trace[519063310] 'read index received' (duration: 589.040614ms)","trace[519063310] 'applied index is now lower than readState.Index' (duration: 10.760744ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T12:05:58.809643Z","caller":"traceutil/trace.go:171","msg":"trace[516817980] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"614.128274ms","start":"2026-07-06T12:05:58.195501Z","end":"2026-07-06T12:05:58.809629Z","steps":["trace[516817980] 'process raft request' (duration: 603.271499ms)","trace[516817980] 'compare' (duration: 10.082535ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T12:05:58.809719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.292385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T12:05:58.809737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:58.195476Z","time spent":"614.193106ms","remote":"127.0.0.1:35288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5626,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T12:05:58.809779Z","caller":"traceutil/trace.go:171","msg":"trace[1911310428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:941; }","duration":"522.357257ms","start":"2026-07-06T12:05:58.287406Z","end":"2026-07-06T12:05:58.809763Z","steps":["trace[1911310428] 'agreement among raft nodes before linearized reading' (duration: 522.268984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:58.809914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:58.28738Z","time spent":"522.427479ms","remote":"127.0.0.1:34858","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-06T12:05:58.810149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.459515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-5dvdl\" ","response":"range_response_count:1 size:4881"} {"level":"info","ts":"2026-07-06T12:05:58.810177Z","caller":"traceutil/trace.go:171","msg":"trace[1615809496] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-5dvdl; range_end:; response_count:1; response_revision:941; }","duration":"600.512407ms","start":"2026-07-06T12:05:58.209655Z","end":"2026-07-06T12:05:58.810167Z","steps":["trace[1615809496] 'agreement among raft nodes before linearized reading' (duration: 600.417355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:58.810203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:58.20964Z","time spent":"600.557818ms","remote":"127.0.0.1:35054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4905,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-5dvdl\" "} {"level":"info","ts":"2026-07-06T12:05:59.020898Z","caller":"traceutil/trace.go:171","msg":"trace[536102678] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:978; }","duration":"186.428194ms","start":"2026-07-06T12:05:58.834444Z","end":"2026-07-06T12:05:59.020872Z","steps":["trace[536102678] 'read index received' (duration: 186.361992ms)","trace[536102678] 'applied index is now lower than readState.Index' (duration: 65.052µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T12:05:59.021Z","caller":"traceutil/trace.go:171","msg":"trace[1151029431] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"201.87675ms","start":"2026-07-06T12:05:58.819112Z","end":"2026-07-06T12:05:59.020989Z","steps":["trace[1151029431] 'process raft request' (duration: 201.614213ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:59.021408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.933067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T12:05:59.021787Z","caller":"traceutil/trace.go:171","msg":"trace[1760096365] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:942; }","duration":"187.354968ms","start":"2026-07-06T12:05:58.83441Z","end":"2026-07-06T12:05:59.021765Z","steps":["trace[1760096365] 'agreement among raft nodes before linearized reading' (duration: 186.719182ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:05:59.048624Z","caller":"traceutil/trace.go:171","msg":"trace[1931308435] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"209.062676ms","start":"2026-07-06T12:05:58.839544Z","end":"2026-07-06T12:05:59.048607Z","steps":["trace[1931308435] 'process raft request' (duration: 208.916092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:05:59.049126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.425514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T12:05:59.049177Z","caller":"traceutil/trace.go:171","msg":"trace[1201369804] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:944; }","duration":"185.535707ms","start":"2026-07-06T12:05:58.863627Z","end":"2026-07-06T12:05:59.049163Z","steps":["trace[1201369804] 'agreement among raft nodes before linearized reading' (duration: 185.446834ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:05:59.133087Z","caller":"traceutil/trace.go:171","msg":"trace[1436271160] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"105.991787ms","start":"2026-07-06T12:05:59.027054Z","end":"2026-07-06T12:05:59.133046Z","steps":["trace[1436271160] 'process raft request' (duration: 77.617413ms)","trace[1436271160] 'compare' (duration: 28.096617ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T12:05:59.133164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.658402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-5dvdl\" ","response":"range_response_count:1 size:5069"} {"level":"info","ts":"2026-07-06T12:05:59.133602Z","caller":"traceutil/trace.go:171","msg":"trace[1090725463] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-5dvdl; range_end:; response_count:1; response_revision:945; }","duration":"108.179966ms","start":"2026-07-06T12:05:59.025395Z","end":"2026-07-06T12:05:59.133575Z","steps":["trace[1090725463] 'agreement among raft nodes before linearized reading' (duration: 107.533919ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:05:59.460796Z","caller":"traceutil/trace.go:171","msg":"trace[1900342483] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"320.875618ms","start":"2026-07-06T12:05:59.139907Z","end":"2026-07-06T12:05:59.460783Z","steps":["trace[1900342483] 'process raft request' (duration: 282.347894ms)","trace[1900342483] 'compare' (duration: 38.351349ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T12:05:59.460945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:05:59.139885Z","time spent":"320.9786ms","remote":"127.0.0.1:35054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5054,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T12:05:59.460744Z","caller":"traceutil/trace.go:171","msg":"trace[1811145124] linearizableReadLoop","detail":"{readStateIndex:983; appliedIndex:982; }","duration":"170.047799ms","start":"2026-07-06T12:05:59.290672Z","end":"2026-07-06T12:05:59.46072Z","steps":["trace[1811145124] 'read index received' (duration: 131.560026ms)","trace[1811145124] 'applied index is now lower than readState.Index' (duration: 38.486843ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T12:05:59.461076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.378819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T12:05:59.461151Z","caller":"traceutil/trace.go:171","msg":"trace[1841506747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:946; }","duration":"170.510532ms","start":"2026-07-06T12:05:59.290622Z","end":"2026-07-06T12:05:59.461132Z","steps":["trace[1841506747] 'agreement among raft nodes before linearized reading' (duration: 170.329857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:06:00.328575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.536436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T12:06:00.328839Z","caller":"traceutil/trace.go:171","msg":"trace[267243771] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"285.154915ms","start":"2026-07-06T12:06:00.043663Z","end":"2026-07-06T12:06:00.328818Z","steps":["trace[267243771] 'process raft request' (duration: 285.015771ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:06:00.329242Z","caller":"traceutil/trace.go:171","msg":"trace[11452329] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"285.185676ms","start":"2026-07-06T12:06:00.043564Z","end":"2026-07-06T12:06:00.32875Z","steps":["trace[11452329] 'process raft request' (duration: 181.395804ms)","trace[11452329] 'compare' (duration: 103.403882ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T12:06:11.339269Z","caller":"traceutil/trace.go:171","msg":"trace[23272335] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"164.580798ms","start":"2026-07-06T12:06:11.174672Z","end":"2026-07-06T12:06:11.339253Z","steps":["trace[23272335] 'process raft request' (duration: 164.468475ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:06:14.52902Z","caller":"traceutil/trace.go:171","msg":"trace[1124916715] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1039; }","duration":"309.015066ms","start":"2026-07-06T12:06:14.219986Z","end":"2026-07-06T12:06:14.529001Z","steps":["trace[1124916715] 'read index received' (duration: 308.956594ms)","trace[1124916715] 'applied index is now lower than readState.Index' (duration: 57.612µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T12:06:14.52905Z","caller":"traceutil/trace.go:171","msg":"trace[1247724600] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"380.391997ms","start":"2026-07-06T12:06:14.148638Z","end":"2026-07-06T12:06:14.52903Z","steps":["trace[1247724600] 'process raft request' (duration: 380.243983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:06:14.529171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:06:14.148614Z","time spent":"380.49301ms","remote":"127.0.0.1:35118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T12:06:14.529291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.239893ms","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-06T12:06:14.529483Z","caller":"traceutil/trace.go:171","msg":"trace[765862935] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1000; }","duration":"309.454719ms","start":"2026-07-06T12:06:14.219928Z","end":"2026-07-06T12:06:14.529383Z","steps":["trace[765862935] 'agreement among raft nodes before linearized reading' (duration: 309.170151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:06:14.529575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:06:14.219906Z","time spent":"309.611643ms","remote":"127.0.0.1:35118","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-06T12:06:14.751326Z","caller":"traceutil/trace.go:171","msg":"trace[1690809385] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"222.204565ms","start":"2026-07-06T12:06:14.529077Z","end":"2026-07-06T12:06:14.751281Z","steps":["trace[1690809385] 'read index received' (duration: 205.572011ms)","trace[1690809385] 'applied index is now lower than readState.Index' (duration: 16.631474ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T12:06:14.751432Z","caller":"traceutil/trace.go:171","msg":"trace[1282358493] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"433.784654ms","start":"2026-07-06T12:06:14.31763Z","end":"2026-07-06T12:06:14.751415Z","steps":["trace[1282358493] 'process raft request' (duration: 417.011346ms)","trace[1282358493] 'compare' (duration: 16.523941ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T12:06:14.75143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.695087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T12:06:14.751483Z","caller":"traceutil/trace.go:171","msg":"trace[2080241758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1001; }","duration":"343.78219ms","start":"2026-07-06T12:06:14.407686Z","end":"2026-07-06T12:06:14.751469Z","steps":["trace[2080241758] 'agreement among raft nodes before linearized reading' (duration: 343.682517ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:06:14.751491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.93093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-06T12:06:14.751526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:06:14.407672Z","time spent":"343.845561ms","remote":"127.0.0.1:34858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-06T12:06:14.751569Z","caller":"traceutil/trace.go:171","msg":"trace[1832648882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1001; }","duration":"500.059434ms","start":"2026-07-06T12:06:14.251495Z","end":"2026-07-06T12:06:14.751555Z","steps":["trace[1832648882] 'agreement among raft nodes before linearized reading' (duration: 499.801317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:06:14.751572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.23056ms","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-06T12:06:14.75162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:06:14.25148Z","time spent":"500.131256ms","remote":"127.0.0.1:35118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-06T12:06:14.751642Z","caller":"traceutil/trace.go:171","msg":"trace[1189250755] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1001; }","duration":"315.337333ms","start":"2026-07-06T12:06:14.436285Z","end":"2026-07-06T12:06:14.751623Z","steps":["trace[1189250755] 'agreement among raft nodes before linearized reading' (duration: 315.108146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:06:14.751589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.882004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T12:06:14.751696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:06:14.436263Z","time spent":"315.423515ms","remote":"127.0.0.1:35118","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":"info","ts":"2026-07-06T12:06:14.751763Z","caller":"traceutil/trace.go:171","msg":"trace[251382010] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1001; }","duration":"229.071969ms","start":"2026-07-06T12:06:14.522654Z","end":"2026-07-06T12:06:14.751726Z","steps":["trace[251382010] 'agreement among raft nodes before linearized reading' (duration: 228.687158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:06:14.751531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:06:14.317612Z","time spent":"433.857357ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T12:06:14.751802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.510677ms","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-06T12:06:14.751845Z","caller":"traceutil/trace.go:171","msg":"trace[1876530236] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1001; }","duration":"463.61184ms","start":"2026-07-06T12:06:14.288221Z","end":"2026-07-06T12:06:14.751832Z","steps":["trace[1876530236] 'agreement among raft nodes before linearized reading' (duration: 463.519248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:06:14.751881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:06:14.288188Z","time spent":"463.683033ms","remote":"127.0.0.1:35258","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-06T12:06:14.75194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.170592ms","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-06T12:06:14.751995Z","caller":"traceutil/trace.go:171","msg":"trace[1918126343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1001; }","duration":"227.249035ms","start":"2026-07-06T12:06:14.524732Z","end":"2026-07-06T12:06:14.751981Z","steps":["trace[1918126343] 'agreement among raft nodes before linearized reading' (duration: 227.09674ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:06:14.781234Z","caller":"traceutil/trace.go:171","msg":"trace[1841868363] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"248.060044ms","start":"2026-07-06T12:06:14.533144Z","end":"2026-07-06T12:06:14.781204Z","steps":["trace[1841868363] 'process raft request' (duration: 247.909819ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:06:15.154771Z","caller":"traceutil/trace.go:171","msg":"trace[12360438] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"398.299647ms","start":"2026-07-06T12:06:14.756442Z","end":"2026-07-06T12:06:15.154742Z","steps":["trace[12360438] 'process raft request' (duration: 398.210694ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:06:15.154794Z","caller":"traceutil/trace.go:171","msg":"trace[642769427] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"399.08113ms","start":"2026-07-06T12:06:14.755682Z","end":"2026-07-06T12:06:15.154763Z","steps":["trace[642769427] 'process raft request' (duration: 398.892795ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:06:15.154859Z","caller":"traceutil/trace.go:171","msg":"trace[1832483933] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"399.278866ms","start":"2026-07-06T12:06:14.755569Z","end":"2026-07-06T12:06:15.154848Z","steps":["trace[1832483933] 'process raft request' (duration: 397.491852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:06:15.154922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:06:14.755657Z","time spent":"399.194153ms","remote":"127.0.0.1:35118","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-06T12:06:15.154928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:06:14.755552Z","time spent":"399.330357ms","remote":"127.0.0.1:35118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T12:06:15.154928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:06:14.756434Z","time spent":"398.40392ms","remote":"127.0.0.1:35118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T12:06:15.155957Z","caller":"traceutil/trace.go:171","msg":"trace[469817586] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"302.895391ms","start":"2026-07-06T12:06:14.853051Z","end":"2026-07-06T12:06:15.155946Z","steps":["trace[469817586] 'process raft request' (duration: 302.83857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:06:15.156017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:06:14.853032Z","time spent":"302.960854ms","remote":"127.0.0.1:35118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T12:06:22.738094Z","caller":"traceutil/trace.go:171","msg":"trace[818770803] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"105.837624ms","start":"2026-07-06T12:06:22.632219Z","end":"2026-07-06T12:06:22.738057Z","steps":["trace[818770803] 'process raft request' (duration: 105.570396ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:06:23.687505Z","caller":"traceutil/trace.go:171","msg":"trace[1087279587] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"148.048863ms","start":"2026-07-06T12:06:23.53944Z","end":"2026-07-06T12:06:23.687489Z","steps":["trace[1087279587] 'process raft request' (duration: 147.945489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:06:25.600093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.513847ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T12:06:25.600176Z","caller":"traceutil/trace.go:171","msg":"trace[397614501] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"297.373698ms","start":"2026-07-06T12:06:25.302788Z","end":"2026-07-06T12:06:25.600162Z","steps":["trace[397614501] 'process raft request' (duration: 140.747748ms)","trace[397614501] 'compare' (duration: 156.060231ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T12:06:29.610519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.290988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T12:06:29.610609Z","caller":"traceutil/trace.go:171","msg":"trace[70392089] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1052; }","duration":"321.443903ms","start":"2026-07-06T12:06:29.289146Z","end":"2026-07-06T12:06:29.61059Z","steps":["trace[70392089] 'range keys from in-memory index tree' (duration: 321.164995ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:06:29.610907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:06:29.289127Z","time spent":"321.763984ms","remote":"127.0.0.1:34858","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-06T12:06:29.610942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.281392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-07-06T12:06:29.610968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.476955ms","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-06T12:06:29.611029Z","caller":"traceutil/trace.go:171","msg":"trace[1899954814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1052; }","duration":"267.597699ms","start":"2026-07-06T12:06:29.343416Z","end":"2026-07-06T12:06:29.611014Z","steps":["trace[1899954814] 'range keys from in-memory index tree' (duration: 267.212877ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:06:29.61104Z","caller":"traceutil/trace.go:171","msg":"trace[861491837] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"245.371643ms","start":"2026-07-06T12:06:29.365649Z","end":"2026-07-06T12:06:29.611021Z","steps":["trace[861491837] 'process raft request' (duration: 52.926695ms)","trace[861491837] 'compare' (duration: 192.111497ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T12:06:29.611184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.343297ms","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-06T12:06:29.611262Z","caller":"traceutil/trace.go:171","msg":"trace[367306137] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1052; }","duration":"342.456461ms","start":"2026-07-06T12:06:29.268786Z","end":"2026-07-06T12:06:29.611243Z","steps":["trace[367306137] 'range keys from in-memory index tree' (duration: 341.775999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:06:29.611312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:06:29.268768Z","time spent":"342.535054ms","remote":"127.0.0.1:35118","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-06T12:06:29.77585Z","caller":"traceutil/trace.go:171","msg":"trace[1359120429] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"159.586181ms","start":"2026-07-06T12:06:29.616246Z","end":"2026-07-06T12:06:29.775832Z","steps":["trace[1359120429] 'process raft request' (duration: 152.802943ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:06:29.775852Z","caller":"traceutil/trace.go:171","msg":"trace[1616218792] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1097; }","duration":"156.504392ms","start":"2026-07-06T12:06:29.619328Z","end":"2026-07-06T12:06:29.775832Z","steps":["trace[1616218792] 'read index received' (duration: 83.478909ms)","trace[1616218792] 'applied index is now lower than readState.Index' (duration: 73.023873ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T12:06:29.776057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.403742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.151\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-06T12:06:29.776043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.689098ms","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-06T12:06:29.776134Z","caller":"traceutil/trace.go:171","msg":"trace[233719833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1055; }","duration":"156.824531ms","start":"2026-07-06T12:06:29.619302Z","end":"2026-07-06T12:06:29.776127Z","steps":["trace[233719833] 'agreement among raft nodes before linearized reading' (duration: 156.617105ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:06:29.776131Z","caller":"traceutil/trace.go:171","msg":"trace[2126005912] range","detail":"{range_begin:/registry/masterleases/199.19.213.151; range_end:; response_count:1; response_revision:1056; }","duration":"122.517506ms","start":"2026-07-06T12:06:29.653601Z","end":"2026-07-06T12:06:29.776118Z","steps":["trace[2126005912] 'agreement among raft nodes before linearized reading' (duration: 122.375261ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:06:31.061392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.984593ms","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-06T12:06:31.061476Z","caller":"traceutil/trace.go:171","msg":"trace[1784695741] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1059; }","duration":"138.115407ms","start":"2026-07-06T12:06:30.923342Z","end":"2026-07-06T12:06:31.061458Z","steps":["trace[1784695741] 'range keys from in-memory index tree' (duration: 137.824797ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:06:33.24852Z","caller":"traceutil/trace.go:171","msg":"trace[1099342185] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"150.892047ms","start":"2026-07-06T12:06:33.097588Z","end":"2026-07-06T12:06:33.248481Z","steps":["trace[1099342185] 'process raft request' (duration: 150.747592ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:07:12.106815Z","caller":"traceutil/trace.go:171","msg":"trace[1833264109] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"105.502203ms","start":"2026-07-06T12:07:12.00129Z","end":"2026-07-06T12:07:12.106792Z","steps":["trace[1833264109] 'process raft request' (duration: 105.364938ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:07:19.333509Z","caller":"traceutil/trace.go:171","msg":"trace[1760274802] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"146.359639ms","start":"2026-07-06T12:07:19.187117Z","end":"2026-07-06T12:07:19.333477Z","steps":["trace[1760274802] 'process raft request' (duration: 146.085598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:07:20.400627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.71895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T12:07:20.40071Z","caller":"traceutil/trace.go:171","msg":"trace[687789078] linearizableReadLoop","detail":"{readStateIndex:1275; appliedIndex:1273; }","duration":"187.533715ms","start":"2026-07-06T12:07:20.213164Z","end":"2026-07-06T12:07:20.400697Z","steps":["trace[687789078] 'read index received' (duration: 4.822409ms)","trace[687789078] 'applied index is now lower than readState.Index' (duration: 182.710396ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T12:07:20.400768Z","caller":"traceutil/trace.go:171","msg":"trace[1719096219] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"254.230018ms","start":"2026-07-06T12:07:20.146524Z","end":"2026-07-06T12:07:20.400754Z","steps":["trace[1719096219] 'process raft request' (duration: 152.2886ms)","trace[1719096219] 'compare' (duration: 101.566845ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T12:07:20.400842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.682582ms","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-06T12:07:20.400867Z","caller":"traceutil/trace.go:171","msg":"trace[239313056] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1220; }","duration":"187.736804ms","start":"2026-07-06T12:07:20.213124Z","end":"2026-07-06T12:07:20.400861Z","steps":["trace[239313056] 'agreement among raft nodes before linearized reading' (duration: 187.6331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:07:20.40094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.55787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-06T12:07:20.400896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.839722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T12:07:20.400952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.480909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T12:07:20.401Z","caller":"traceutil/trace.go:171","msg":"trace[1401680252] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:0; response_revision:1220; }","duration":"148.969307ms","start":"2026-07-06T12:07:20.252016Z","end":"2026-07-06T12:07:20.400986Z","steps":["trace[1401680252] 'agreement among raft nodes before linearized reading' (duration: 148.845362ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:07:20.401032Z","caller":"traceutil/trace.go:171","msg":"trace[1356787247] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1220; }","duration":"110.607803ms","start":"2026-07-06T12:07:20.290407Z","end":"2026-07-06T12:07:20.401015Z","steps":["trace[1356787247] 'agreement among raft nodes before linearized reading' (duration: 110.483969ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:07:20.40098Z","caller":"traceutil/trace.go:171","msg":"trace[1689554044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1220; }","duration":"137.623033ms","start":"2026-07-06T12:07:20.26335Z","end":"2026-07-06T12:07:20.400973Z","steps":["trace[1689554044] 'agreement among raft nodes before linearized reading' (duration: 137.55196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:07:20.672061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.778014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T12:07:20.672255Z","caller":"traceutil/trace.go:171","msg":"trace[1517824108] linearizableReadLoop","detail":"{readStateIndex:1277; appliedIndex:1275; }","duration":"265.54119ms","start":"2026-07-06T12:07:20.406696Z","end":"2026-07-06T12:07:20.672237Z","steps":["trace[1517824108] 'read index received' (duration: 34.944045ms)","trace[1517824108] 'applied index is now lower than readState.Index' (duration: 230.595895ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T12:07:20.672352Z","caller":"traceutil/trace.go:171","msg":"trace[2078773116] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"259.920721ms","start":"2026-07-06T12:07:20.412421Z","end":"2026-07-06T12:07:20.672342Z","steps":["trace[2078773116] 'process raft request' (duration: 259.754175ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:07:20.67253Z","caller":"traceutil/trace.go:171","msg":"trace[48716324] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"266.068591ms","start":"2026-07-06T12:07:20.406423Z","end":"2026-07-06T12:07:20.672491Z","steps":["trace[48716324] 'process raft request' (duration: 152.761038ms)","trace[48716324] 'compare' (duration: 112.481972ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T12:07:20.672649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.922766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/default/\" range_end:\"/registry/limitranges/default0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T12:07:20.672743Z","caller":"traceutil/trace.go:171","msg":"trace[1279552721] range","detail":"{range_begin:/registry/limitranges/default/; range_end:/registry/limitranges/default0; response_count:0; response_revision:1223; }","duration":"266.055251ms","start":"2026-07-06T12:07:20.406662Z","end":"2026-07-06T12:07:20.672717Z","steps":["trace[1279552721] 'agreement among raft nodes before linearized reading' (duration: 265.903035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:07:20.672664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.612314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T12:07:20.672847Z","caller":"traceutil/trace.go:171","msg":"trace[1499527224] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1223; }","duration":"144.854832ms","start":"2026-07-06T12:07:20.527973Z","end":"2026-07-06T12:07:20.672828Z","steps":["trace[1499527224] 'agreement among raft nodes before linearized reading' (duration: 144.597132ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:07:21.828246Z","caller":"traceutil/trace.go:171","msg":"trace[1435226672] linearizableReadLoop","detail":"{readStateIndex:1282; appliedIndex:1281; }","duration":"224.619097ms","start":"2026-07-06T12:07:21.6036Z","end":"2026-07-06T12:07:21.828219Z","steps":["trace[1435226672] 'read index received' (duration: 224.41498ms)","trace[1435226672] 'applied index is now lower than readState.Index' (duration: 202.477µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T12:07:21.828303Z","caller":"traceutil/trace.go:171","msg":"trace[517731621] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"487.156804ms","start":"2026-07-06T12:07:21.341098Z","end":"2026-07-06T12:07:21.828255Z","steps":["trace[517731621] 'process raft request' (duration: 486.946216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:07:21.82851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.895287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T12:07:21.82859Z","caller":"traceutil/trace.go:171","msg":"trace[1878880879] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:0; response_revision:1226; }","duration":"225.037273ms","start":"2026-07-06T12:07:21.603535Z","end":"2026-07-06T12:07:21.828573Z","steps":["trace[1878880879] 'agreement among raft nodes before linearized reading' (duration: 224.835865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:07:21.828531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:07:21.341074Z","time spent":"487.32143ms","remote":"127.0.0.1:35118","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-06T12:07:22.024121Z","caller":"traceutil/trace.go:171","msg":"trace[2142079806] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"191.132175ms","start":"2026-07-06T12:07:21.832958Z","end":"2026-07-06T12:07:22.02409Z","steps":["trace[2142079806] 'process raft request' (duration: 186.303144ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:07:22.055007Z","caller":"traceutil/trace.go:171","msg":"trace[1352899837] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"218.45726ms","start":"2026-07-06T12:07:21.836525Z","end":"2026-07-06T12:07:22.054982Z","steps":["trace[1352899837] 'process raft request' (duration: 218.320635ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:07:22.328791Z","caller":"traceutil/trace.go:171","msg":"trace[456957495] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1286; }","duration":"215.167617ms","start":"2026-07-06T12:07:22.113609Z","end":"2026-07-06T12:07:22.328776Z","steps":["trace[456957495] 'read index received' (duration: 215.050463ms)","trace[456957495] 'applied index is now lower than readState.Index' (duration: 116.224µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T12:07:22.328934Z","caller":"traceutil/trace.go:171","msg":"trace[445113455] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"216.244707ms","start":"2026-07-06T12:07:22.112638Z","end":"2026-07-06T12:07:22.328883Z","steps":["trace[445113455] 'process raft request' (duration: 216.027409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:07:22.329154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.488689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5548"} {"level":"info","ts":"2026-07-06T12:07:22.329208Z","caller":"traceutil/trace.go:171","msg":"trace[1534034891] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1231; }","duration":"215.603843ms","start":"2026-07-06T12:07:22.113595Z","end":"2026-07-06T12:07:22.329198Z","steps":["trace[1534034891] 'agreement among raft nodes before linearized reading' (duration: 215.283421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:07:22.32957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.753773ms","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-06T12:07:22.329752Z","caller":"traceutil/trace.go:171","msg":"trace[2826638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1231; }","duration":"102.972561ms","start":"2026-07-06T12:07:22.226754Z","end":"2026-07-06T12:07:22.329726Z","steps":["trace[2826638] 'agreement among raft nodes before linearized reading' (duration: 102.620388ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:07:23.958834Z","caller":"traceutil/trace.go:171","msg":"trace[1380461837] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"121.088739ms","start":"2026-07-06T12:07:23.837709Z","end":"2026-07-06T12:07:23.958797Z","steps":["trace[1380461837] 'process raft request' (duration: 120.928493ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:07:24.236604Z","caller":"traceutil/trace.go:171","msg":"trace[1431109232] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"133.617647ms","start":"2026-07-06T12:07:24.102951Z","end":"2026-07-06T12:07:24.236569Z","steps":["trace[1431109232] 'process raft request' (duration: 133.401639ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:07:28.149792Z","caller":"traceutil/trace.go:171","msg":"trace[683834640] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1316; }","duration":"175.721731ms","start":"2026-07-06T12:07:27.97405Z","end":"2026-07-06T12:07:28.149772Z","steps":["trace[683834640] 'read index received' (duration: 175.555854ms)","trace[683834640] 'applied index is now lower than readState.Index' (duration: 165.076µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T12:07:28.149998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.931649ms","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-06T12:07:28.150041Z","caller":"traceutil/trace.go:171","msg":"trace[258704738] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1260; }","duration":"176.023122ms","start":"2026-07-06T12:07:27.974006Z","end":"2026-07-06T12:07:28.150029Z","steps":["trace[258704738] 'agreement among raft nodes before linearized reading' (duration: 175.879497ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:07:28.149974Z","caller":"traceutil/trace.go:171","msg":"trace[1885341667] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"176.551792ms","start":"2026-07-06T12:07:27.973307Z","end":"2026-07-06T12:07:28.149858Z","steps":["trace[1885341667] 'process raft request' (duration: 176.343344ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:07:28.727177Z","caller":"traceutil/trace.go:171","msg":"trace[1457068285] linearizableReadLoop","detail":"{readStateIndex:1322; appliedIndex:1321; }","duration":"161.461293ms","start":"2026-07-06T12:07:28.565679Z","end":"2026-07-06T12:07:28.72714Z","steps":["trace[1457068285] 'read index received' (duration: 109.89277ms)","trace[1457068285] 'applied index is now lower than readState.Index' (duration: 51.567413ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T12:07:28.727359Z","caller":"traceutil/trace.go:171","msg":"trace[1627146750] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"171.249753ms","start":"2026-07-06T12:07:28.55607Z","end":"2026-07-06T12:07:28.72732Z","steps":["trace[1627146750] 'process raft request' (duration: 119.476292ms)","trace[1627146750] 'compare' (duration: 51.438978ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T12:07:28.72765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.891749ms","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-06T12:07:28.727742Z","caller":"traceutil/trace.go:171","msg":"trace[375794919] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1265; }","duration":"162.074606ms","start":"2026-07-06T12:07:28.565655Z","end":"2026-07-06T12:07:28.727729Z","steps":["trace[375794919] 'agreement among raft nodes before linearized reading' (duration: 161.63627ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:07:28.881368Z","caller":"traceutil/trace.go:171","msg":"trace[2076720558] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"106.772842ms","start":"2026-07-06T12:07:28.774545Z","end":"2026-07-06T12:07:28.881318Z","steps":["trace[2076720558] 'process raft request' (duration: 53.025138ms)","trace[2076720558] 'compare' (duration: 53.284917ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T12:07:29.087828Z","caller":"traceutil/trace.go:171","msg":"trace[750456885] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"108.067375ms","start":"2026-07-06T12:07:28.979728Z","end":"2026-07-06T12:07:29.087796Z","steps":["trace[750456885] 'process raft request' (duration: 107.832206ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:07:32.504556Z","caller":"traceutil/trace.go:171","msg":"trace[1166865918] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1345; }","duration":"214.237759ms","start":"2026-07-06T12:07:32.290292Z","end":"2026-07-06T12:07:32.50453Z","steps":["trace[1166865918] 'read index received' (duration: 214.00451ms)","trace[1166865918] 'applied index is now lower than readState.Index' (duration: 232.369µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T12:07:32.504809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.621202ms","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-06T12:07:32.504838Z","caller":"traceutil/trace.go:171","msg":"trace[1180105099] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1288; }","duration":"168.722905ms","start":"2026-07-06T12:07:32.336107Z","end":"2026-07-06T12:07:32.50483Z","steps":["trace[1180105099] 'agreement among raft nodes before linearized reading' (duration: 168.568969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:07:32.504799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.495577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T12:07:32.504936Z","caller":"traceutil/trace.go:171","msg":"trace[1023493770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1288; }","duration":"214.648964ms","start":"2026-07-06T12:07:32.290267Z","end":"2026-07-06T12:07:32.504916Z","steps":["trace[1023493770] 'agreement among raft nodes before linearized reading' (duration: 214.405265ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:07:32.505287Z","caller":"traceutil/trace.go:171","msg":"trace[692454010] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"250.504629ms","start":"2026-07-06T12:07:32.254752Z","end":"2026-07-06T12:07:32.505257Z","steps":["trace[692454010] 'process raft request' (duration: 249.577484ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:07:39.072268Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13559387638034480578,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-06T12:07:39.21796Z","caller":"traceutil/trace.go:171","msg":"trace[529404593] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"647.18985ms","start":"2026-07-06T12:07:38.570734Z","end":"2026-07-06T12:07:39.217924Z","steps":["trace[529404593] 'process raft request' (duration: 646.965741ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:07:39.218145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:07:38.57071Z","time spent":"647.337226ms","remote":"127.0.0.1:35118","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-06T12:07:39.226287Z","caller":"traceutil/trace.go:171","msg":"trace[2050890599] linearizableReadLoop","detail":"{readStateIndex:1374; appliedIndex:1371; }","duration":"654.895865ms","start":"2026-07-06T12:07:38.571366Z","end":"2026-07-06T12:07:39.226261Z","steps":["trace[2050890599] 'read index received' (duration: 646.326635ms)","trace[2050890599] 'applied index is now lower than readState.Index' (duration: 8.56821ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T12:07:39.226361Z","caller":"traceutil/trace.go:171","msg":"trace[2143762385] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"653.656128ms","start":"2026-07-06T12:07:38.572668Z","end":"2026-07-06T12:07:39.226324Z","steps":["trace[2143762385] 'process raft request' (duration: 653.442829ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:07:39.226398Z","caller":"traceutil/trace.go:171","msg":"trace[20874867] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"647.998681ms","start":"2026-07-06T12:07:38.578381Z","end":"2026-07-06T12:07:39.22638Z","steps":["trace[20874867] 'process raft request' (duration: 647.848034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:07:39.226505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:07:38.578372Z","time spent":"648.077844ms","remote":"127.0.0.1:35118","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-06T12:07:39.226588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.161616ms","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-06T12:07:39.226624Z","caller":"traceutil/trace.go:171","msg":"trace[1040401439] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1315; }","duration":"655.261019ms","start":"2026-07-06T12:07:38.571354Z","end":"2026-07-06T12:07:39.226615Z","steps":["trace[1040401439] 'agreement among raft nodes before linearized reading' (duration: 655.065032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:07:39.226672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:07:38.572659Z","time spent":"653.865286ms","remote":"127.0.0.1:35118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T12:07:39.226739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:07:38.571349Z","time spent":"655.348972ms","remote":"127.0.0.1:35118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-06T12:07:39.227013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.396918ms","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-06T12:07:39.227042Z","caller":"traceutil/trace.go:171","msg":"trace[1130072029] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1315; }","duration":"416.466251ms","start":"2026-07-06T12:07:38.810569Z","end":"2026-07-06T12:07:39.227035Z","steps":["trace[1130072029] 'agreement among raft nodes before linearized reading' (duration: 416.389788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:07:39.227088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:07:38.810549Z","time spent":"416.533574ms","remote":"127.0.0.1:35118","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-06T12:07:39.22741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.823719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-06T12:07:39.227455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.659739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-06T12:07:39.227507Z","caller":"traceutil/trace.go:171","msg":"trace[255958698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1315; }","duration":"295.955634ms","start":"2026-07-06T12:07:38.931522Z","end":"2026-07-06T12:07:39.227477Z","steps":["trace[255958698] 'agreement among raft nodes before linearized reading' (duration: 295.775497ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:07:39.22797Z","caller":"traceutil/trace.go:171","msg":"trace[1439825295] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1315; }","duration":"347.752692ms","start":"2026-07-06T12:07:38.879735Z","end":"2026-07-06T12:07:39.227488Z","steps":["trace[1439825295] 'agreement among raft nodes before linearized reading' (duration: 347.626488ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:07:39.228117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T12:07:38.879716Z","time spent":"348.361726ms","remote":"127.0.0.1:35054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-07-06T12:07:55.550172Z","caller":"traceutil/trace.go:171","msg":"trace[1322135642] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"179.38011ms","start":"2026-07-06T12:07:55.37076Z","end":"2026-07-06T12:07:55.55014Z","steps":["trace[1322135642] 'process raft request' (duration: 179.167242ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:07:55.561144Z","caller":"traceutil/trace.go:171","msg":"trace[1986494996] linearizableReadLoop","detail":"{readStateIndex:1437; appliedIndex:1435; }","duration":"152.089832ms","start":"2026-07-06T12:07:55.409031Z","end":"2026-07-06T12:07:55.561121Z","steps":["trace[1986494996] 'read index received' (duration: 140.968847ms)","trace[1986494996] 'applied index is now lower than readState.Index' (duration: 11.119765ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T12:07:55.561223Z","caller":"traceutil/trace.go:171","msg":"trace[602064871] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"190.081978ms","start":"2026-07-06T12:07:55.371109Z","end":"2026-07-06T12:07:55.561191Z","steps":["trace[602064871] 'process raft request' (duration: 189.88402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:07:55.561329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.274889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:public-info-viewer\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-07-06T12:07:55.561371Z","caller":"traceutil/trace.go:171","msg":"trace[1043469712] range","detail":"{range_begin:/registry/clusterrolebindings/system:public-info-viewer; range_end:; response_count:1; response_revision:1373; }","duration":"152.380184ms","start":"2026-07-06T12:07:55.408978Z","end":"2026-07-06T12:07:55.561358Z","steps":["trace[1043469712] 'agreement among raft nodes before linearized reading' (duration: 152.271529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T12:07:55.561415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.712919ms","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-06T12:07:55.561481Z","caller":"traceutil/trace.go:171","msg":"trace[1913875643] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1373; }","duration":"125.820244ms","start":"2026-07-06T12:07:55.435643Z","end":"2026-07-06T12:07:55.561463Z","steps":["trace[1913875643] 'agreement among raft nodes before linearized reading' (duration: 125.652027ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:08:07.763647Z","caller":"traceutil/trace.go:171","msg":"trace[1096805184] linearizableReadLoop","detail":"{readStateIndex:1479; appliedIndex:1478; }","duration":"107.785153ms","start":"2026-07-06T12:08:07.655834Z","end":"2026-07-06T12:08:07.763619Z","steps":["trace[1096805184] 'read index received' (duration: 107.550133ms)","trace[1096805184] 'applied index is now lower than readState.Index' (duration: 233.87µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T12:08:07.763827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.97183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-07-06T12:08:07.763877Z","caller":"traceutil/trace.go:171","msg":"trace[180348483] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:1413; }","duration":"108.070263ms","start":"2026-07-06T12:08:07.655791Z","end":"2026-07-06T12:08:07.763861Z","steps":["trace[180348483] 'agreement among raft nodes before linearized reading' (duration: 107.972499ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T12:08:07.763833Z","caller":"traceutil/trace.go:171","msg":"trace[1524782993] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"118.796188ms","start":"2026-07-06T12:08:07.644941Z","end":"2026-07-06T12:08:07.763738Z","steps":["trace[1524782993] 'process raft request' (duration: 118.510457ms)"],"step_count":1}