{"level":"warn","ts":"2026-07-09T15:25:45.561622Z","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-09T15:25:45.561709Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.91: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.91:2380","--initial-cluster=instance=https://199.19.213.91:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.91:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.91: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-09T15:25:45.561775Z","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-09T15:25:45.561788Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.91:2380"]} {"level":"info","ts":"2026-07-09T15:25:45.561807Z","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-09T15:25:45.562395Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.91:2379"]} {"level":"info","ts":"2026-07-09T15:25:45.562468Z","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.91:2380"],"listen-peer-urls":["https://199.19.213.91:2380"],"advertise-client-urls":["https://199.19.213.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.91: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-09T15:25:45.566079Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.397991ms"} {"level":"info","ts":"2026-07-09T15:25:45.575357Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2c24222821500990","cluster-id":"f38faa6ba493daad"} {"level":"info","ts":"2026-07-09T15:25:45.575433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 switched to configuration voters=()"} {"level":"info","ts":"2026-07-09T15:25:45.575471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became follower at term 0"} {"level":"info","ts":"2026-07-09T15:25:45.575484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2c24222821500990 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-09T15:25:45.575513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became follower at term 1"} {"level":"info","ts":"2026-07-09T15:25:45.575547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 switched to configuration voters=(3180704792583342480)"} {"level":"warn","ts":"2026-07-09T15:25:45.584363Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-09T15:25:45.585897Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-09T15:25:45.58788Z","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-09T15:25:45.590625Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2c24222821500990","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-09T15:25:45.59285Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2c24222821500990","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-09T15:25:45.593097Z","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-09T15:25:45.593207Z","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-09T15:25:45.593228Z","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-09T15:25:45.597004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 switched to configuration voters=(3180704792583342480)"} {"level":"info","ts":"2026-07-09T15:25:45.597114Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f38faa6ba493daad","local-member-id":"2c24222821500990","added-peer-id":"2c24222821500990","added-peer-peer-urls":["https://199.19.213.91:2380"]} {"level":"info","ts":"2026-07-09T15:25:45.59835Z","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-09T15:25:45.598509Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.91:2380"} {"level":"info","ts":"2026-07-09T15:25:45.598526Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.91:2380"} {"level":"info","ts":"2026-07-09T15:25:45.599381Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2c24222821500990","initial-advertise-peer-urls":["https://199.19.213.91:2380"],"listen-peer-urls":["https://199.19.213.91:2380"],"advertise-client-urls":["https://199.19.213.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-09T15:25:45.599431Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-09T15:25:46.175771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 is starting a new election at term 1"} {"level":"info","ts":"2026-07-09T15:25:46.175844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-09T15:25:46.175915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 received MsgPreVoteResp from 2c24222821500990 at term 1"} {"level":"info","ts":"2026-07-09T15:25:46.175942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became candidate at term 2"} {"level":"info","ts":"2026-07-09T15:25:46.175961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 received MsgVoteResp from 2c24222821500990 at term 2"} {"level":"info","ts":"2026-07-09T15:25:46.175989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became leader at term 2"} {"level":"info","ts":"2026-07-09T15:25:46.176014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2c24222821500990 elected leader 2c24222821500990 at term 2"} {"level":"info","ts":"2026-07-09T15:25:46.176969Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T15:25:46.178338Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f38faa6ba493daad","local-member-id":"2c24222821500990","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T15:25:46.178471Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T15:25:46.178506Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T15:25:46.178551Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2c24222821500990","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.91:2379]}","request-path":"/0/members/2c24222821500990/attributes","cluster-id":"f38faa6ba493daad","publish-timeout":"7s"} {"level":"info","ts":"2026-07-09T15:25:46.178754Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T15:25:46.179526Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T15:25:46.179779Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-09T15:25:46.179812Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-09T15:25:46.182565Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.91:2379"} {"level":"info","ts":"2026-07-09T15:25:46.182692Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-09T15:25:46.54942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.849701ms","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-09T15:25:46.549484Z","caller":"traceutil/trace.go:171","msg":"trace[488763134] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"247.968956ms","start":"2026-07-09T15:25:46.301498Z","end":"2026-07-09T15:25:46.549467Z","steps":["trace[488763134] 'count revisions from in-memory index tree' (duration: 247.734885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:46.549561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.190502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T15:25:46.549713Z","caller":"traceutil/trace.go:171","msg":"trace[314242909] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"144.341119ms","start":"2026-07-09T15:25:46.405325Z","end":"2026-07-09T15:25:46.549666Z","steps":["trace[314242909] 'count revisions from in-memory index tree' (duration: 144.156001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:46.549728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.509497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T15:25:46.549766Z","caller":"traceutil/trace.go:171","msg":"trace[527967859] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"233.555959ms","start":"2026-07-09T15:25:46.316195Z","end":"2026-07-09T15:25:46.549751Z","steps":["trace[527967859] 'count revisions from in-memory index tree' (duration: 233.411663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:46.549927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.867714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T15:25:46.54997Z","caller":"traceutil/trace.go:171","msg":"trace[1380048791] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"233.965799ms","start":"2026-07-09T15:25:46.315996Z","end":"2026-07-09T15:25:46.549962Z","steps":["trace[1380048791] 'range keys from in-memory index tree' (duration: 233.612141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:46.550038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.77112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-09T15:25:46.550074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.579352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T15:25:46.550108Z","caller":"traceutil/trace.go:171","msg":"trace[1768703244] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"216.633884ms","start":"2026-07-09T15:25:46.333465Z","end":"2026-07-09T15:25:46.550099Z","steps":["trace[1768703244] 'count revisions from in-memory index tree' (duration: 216.469126ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:46.550141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.876012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T15:25:46.550163Z","caller":"traceutil/trace.go:171","msg":"trace[688557397] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"125.899823ms","start":"2026-07-09T15:25:46.424257Z","end":"2026-07-09T15:25:46.550157Z","steps":["trace[688557397] 'range keys from in-memory index tree' (duration: 121.427133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:46.550244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.753251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-09T15:25:46.550281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.342124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T15:25:46.550307Z","caller":"traceutil/trace.go:171","msg":"trace[1271288714] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1; }","duration":"116.379026ms","start":"2026-07-09T15:25:46.433922Z","end":"2026-07-09T15:25:46.550301Z","steps":["trace[1271288714] 'count revisions from in-memory index tree' (duration: 116.318813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:46.550312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.859318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T15:25:46.550365Z","caller":"traceutil/trace.go:171","msg":"trace[285983652] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"202.966102ms","start":"2026-07-09T15:25:46.347393Z","end":"2026-07-09T15:25:46.550359Z","steps":["trace[285983652] 'range keys from in-memory index tree' (duration: 202.794925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:46.550434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.549494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T15:25:46.550459Z","caller":"traceutil/trace.go:171","msg":"trace[463398197] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1; }","duration":"116.589876ms","start":"2026-07-09T15:25:46.433864Z","end":"2026-07-09T15:25:46.550454Z","steps":["trace[463398197] 'range keys from in-memory index tree' (duration: 116.460269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:46.550527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.104149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-09T15:25:46.550558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.670356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T15:25:46.550557Z","caller":"traceutil/trace.go:171","msg":"trace[1598041945] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"203.156991ms","start":"2026-07-09T15:25:46.347393Z","end":"2026-07-09T15:25:46.55055Z","steps":["trace[1598041945] 'count revisions from in-memory index tree' (duration: 202.997554ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:25:46.550578Z","caller":"traceutil/trace.go:171","msg":"trace[117461471] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1; }","duration":"104.705507ms","start":"2026-07-09T15:25:46.445866Z","end":"2026-07-09T15:25:46.550572Z","steps":["trace[117461471] 'count revisions from in-memory index tree' (duration: 104.634964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:46.550675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.947663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-09T15:25:46.550692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.905837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T15:25:46.550705Z","caller":"traceutil/trace.go:171","msg":"trace[1264177446] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"195.996955ms","start":"2026-07-09T15:25:46.354701Z","end":"2026-07-09T15:25:46.550698Z","steps":["trace[1264177446] 'range keys from in-memory index tree' (duration: 195.854079ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:25:46.550734Z","caller":"traceutil/trace.go:171","msg":"trace[1105858067] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1; }","duration":"104.949109ms","start":"2026-07-09T15:25:46.445756Z","end":"2026-07-09T15:25:46.550705Z","steps":["trace[1105858067] 'range keys from in-memory index tree' (duration: 104.743689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:46.550791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.732023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T15:25:46.550828Z","caller":"traceutil/trace.go:171","msg":"trace[1821814575] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"195.775695ms","start":"2026-07-09T15:25:46.355037Z","end":"2026-07-09T15:25:46.550813Z","steps":["trace[1821814575] 'count revisions from in-memory index tree' (duration: 195.66779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:46.550888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.872159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T15:25:46.550951Z","caller":"traceutil/trace.go:171","msg":"trace[1168452914] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"182.947593ms","start":"2026-07-09T15:25:46.367996Z","end":"2026-07-09T15:25:46.550944Z","steps":["trace[1168452914] 'range keys from in-memory index tree' (duration: 182.836177ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:25:46.550284Z","caller":"traceutil/trace.go:171","msg":"trace[1929706359] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"216.875915ms","start":"2026-07-09T15:25:46.333399Z","end":"2026-07-09T15:25:46.550275Z","steps":["trace[1929706359] 'range keys from in-memory index tree' (duration: 216.636784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:46.551096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.949433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T15:25:46.551128Z","caller":"traceutil/trace.go:171","msg":"trace[1879016879] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"182.993054ms","start":"2026-07-09T15:25:46.368127Z","end":"2026-07-09T15:25:46.55112Z","steps":["trace[1879016879] 'count revisions from in-memory index tree' (duration: 182.921971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:46.551243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.054363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T15:25:46.551291Z","caller":"traceutil/trace.go:171","msg":"trace[1316472947] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"171.133118ms","start":"2026-07-09T15:25:46.380151Z","end":"2026-07-09T15:25:46.551284Z","steps":["trace[1316472947] 'range keys from in-memory index tree' (duration: 171.002031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:46.551459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.926288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T15:25:46.551491Z","caller":"traceutil/trace.go:171","msg":"trace[207078636] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"170.960629ms","start":"2026-07-09T15:25:46.380523Z","end":"2026-07-09T15:25:46.551484Z","steps":["trace[207078636] 'count revisions from in-memory index tree' (duration: 170.818922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:46.549399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.398636ms","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-09T15:25:46.551847Z","caller":"traceutil/trace.go:171","msg":"trace[1913376200] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"250.849681ms","start":"2026-07-09T15:25:46.300953Z","end":"2026-07-09T15:25:46.551803Z","steps":["trace[1913376200] 'range keys from in-memory index tree' (duration: 248.289071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:46.549291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.929811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T15:25:46.552007Z","caller":"traceutil/trace.go:171","msg":"trace[1427150825] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"159.752183ms","start":"2026-07-09T15:25:46.392246Z","end":"2026-07-09T15:25:46.551998Z","steps":["trace[1427150825] 'range keys from in-memory index tree' (duration: 156.70264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:46.54936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.465948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T15:25:46.552165Z","caller":"traceutil/trace.go:171","msg":"trace[42230724] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"268.27014ms","start":"2026-07-09T15:25:46.283885Z","end":"2026-07-09T15:25:46.552155Z","steps":["trace[42230724] 'count revisions from in-memory index tree' (duration: 265.272259ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:25:46.550083Z","caller":"traceutil/trace.go:171","msg":"trace[32416419] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"144.844703ms","start":"2026-07-09T15:25:46.405222Z","end":"2026-07-09T15:25:46.550067Z","steps":["trace[32416419] 'range keys from in-memory index tree' (duration: 144.638574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:46.550115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.322703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T15:25:46.553639Z","caller":"traceutil/trace.go:171","msg":"trace[1616813552] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"129.847088ms","start":"2026-07-09T15:25:46.423765Z","end":"2026-07-09T15:25:46.553612Z","steps":["trace[1616813552] 'count revisions from in-memory index tree' (duration: 126.214058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:46.554598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.666419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T15:25:46.554723Z","caller":"traceutil/trace.go:171","msg":"trace[945260862] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"162.802076ms","start":"2026-07-09T15:25:46.391898Z","end":"2026-07-09T15:25:46.5547Z","steps":["trace[945260862] 'count revisions from in-memory index tree' (duration: 159.275191ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:25:48.927882Z","caller":"traceutil/trace.go:171","msg":"trace[1471350098] transaction","detail":"{read_only:false; response_revision:132; number_of_response:1; }","duration":"141.696293ms","start":"2026-07-09T15:25:48.786161Z","end":"2026-07-09T15:25:48.927857Z","steps":["trace[1471350098] 'process raft request' (duration: 141.055843ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:25:54.399237Z","caller":"traceutil/trace.go:171","msg":"trace[1944592041] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"248.15025ms","start":"2026-07-09T15:25:54.151055Z","end":"2026-07-09T15:25:54.399205Z","steps":["trace[1944592041] 'process raft request' (duration: 205.267752ms)","trace[1944592041] 'compare' (duration: 42.732622ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:25:54.921219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.70692ms","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-09T15:25:54.921317Z","caller":"traceutil/trace.go:171","msg":"trace[1339173737] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:294; }","duration":"420.888677ms","start":"2026-07-09T15:25:54.500407Z","end":"2026-07-09T15:25:54.921295Z","steps":["trace[1339173737] 'range keys from in-memory index tree' (duration: 420.578844ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:54.921363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:54.50039Z","time spent":"420.964051ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-07-09T15:25:55.030931Z","caller":"traceutil/trace.go:171","msg":"trace[848904499] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"105.135634ms","start":"2026-07-09T15:25:54.925766Z","end":"2026-07-09T15:25:55.030901Z","steps":["trace[848904499] 'process raft request' (duration: 104.983267ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:55.538677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.344962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:25:55.538785Z","caller":"traceutil/trace.go:171","msg":"trace[915186969] linearizableReadLoop","detail":"{readStateIndex:308; appliedIndex:307; }","duration":"472.494355ms","start":"2026-07-09T15:25:55.066274Z","end":"2026-07-09T15:25:55.538769Z","steps":["trace[915186969] 'read index received' (duration: 152.575549ms)","trace[915186969] 'applied index is now lower than readState.Index' (duration: 319.917566ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:25:55.538887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.60585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-09T15:25:55.539002Z","caller":"traceutil/trace.go:171","msg":"trace[1437425126] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:296; }","duration":"472.763306ms","start":"2026-07-09T15:25:55.066227Z","end":"2026-07-09T15:25:55.53899Z","steps":["trace[1437425126] 'agreement among raft nodes before linearized reading' (duration: 472.588179ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:25:55.538898Z","caller":"traceutil/trace.go:171","msg":"trace[1789050968] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"612.291998ms","start":"2026-07-09T15:25:54.926554Z","end":"2026-07-09T15:25:55.538846Z","steps":["trace[1789050968] 'process raft request' (duration: 292.257268ms)","trace[1789050968] 'compare' (duration: 319.170274ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:25:55.539055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:55.066203Z","time spent":"472.83993ms","remote":"127.0.0.1:38326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T15:25:55.539694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:54.926527Z","time spent":"612.609552ms","remote":"127.0.0.1:38284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":219,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T15:25:56.046716Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689225872361843153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:25:56.586276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.443797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:25:56.586388Z","caller":"traceutil/trace.go:171","msg":"trace[1373013757] linearizableReadLoop","detail":"{readStateIndex:309; appliedIndex:308; }","duration":"1.040063618s","start":"2026-07-09T15:25:55.546306Z","end":"2026-07-09T15:25:56.58637Z","steps":["trace[1373013757] 'read index received' (duration: 616.182022ms)","trace[1373013757] 'applied index is now lower than readState.Index' (duration: 423.880346ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:25:56.586501Z","caller":"traceutil/trace.go:171","msg":"trace[958003333] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"1.042431939s","start":"2026-07-09T15:25:55.544052Z","end":"2026-07-09T15:25:56.586484Z","steps":["trace[958003333] 'process raft request' (duration: 618.501881ms)","trace[958003333] 'compare' (duration: 423.309542ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:25:56.58657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:55.544019Z","time spent":"1.042519742s","remote":"127.0.0.1:38326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:25:56.586624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.542406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-09T15:25:56.586674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.415694ms","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-09T15:25:56.586697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0403452s","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-09T15:25:56.586739Z","caller":"traceutil/trace.go:171","msg":"trace[902076721] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:297; }","duration":"637.507429ms","start":"2026-07-09T15:25:55.949215Z","end":"2026-07-09T15:25:56.586723Z","steps":["trace[902076721] 'agreement among raft nodes before linearized reading' (duration: 637.385714ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:25:56.586825Z","caller":"traceutil/trace.go:171","msg":"trace[386967108] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:297; }","duration":"1.040526178s","start":"2026-07-09T15:25:55.546273Z","end":"2026-07-09T15:25:56.5868Z","steps":["trace[386967108] 'agreement among raft nodes before linearized reading' (duration: 1.040278858s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:56.586886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:55.949198Z","time spent":"637.636484ms","remote":"127.0.0.1:38326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T15:25:56.586898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:55.546262Z","time spent":"1.040625392s","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-07-09T15:25:56.58674Z","caller":"traceutil/trace.go:171","msg":"trace[704048799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:297; }","duration":"648.727494ms","start":"2026-07-09T15:25:55.937982Z","end":"2026-07-09T15:25:56.58671Z","steps":["trace[704048799] 'agreement among raft nodes before linearized reading' (duration: 648.533676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:56.586972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:55.937952Z","time spent":"649.002686ms","remote":"127.0.0.1:38326","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-09T15:25:58.210763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.735786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:25:58.210896Z","caller":"traceutil/trace.go:171","msg":"trace[1934669431] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"1.618585205s","start":"2026-07-09T15:25:56.592286Z","end":"2026-07-09T15:25:58.210871Z","steps":["trace[1934669431] 'process raft request' (duration: 782.668081ms)","trace[1934669431] 'compare' (duration: 834.947004ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:25:58.211037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:56.592263Z","time spent":"1.618739321s","remote":"127.0.0.1:38284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T15:25:58.650152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.583724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:25:58.650653Z","caller":"traceutil/trace.go:171","msg":"trace[1585468766] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"718.429571ms","start":"2026-07-09T15:25:57.932205Z","end":"2026-07-09T15:25:58.650634Z","steps":["trace[1585468766] 'process raft request' (duration: 718.390779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:58.65091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:57.932175Z","time spent":"718.672852ms","remote":"127.0.0.1:38326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:25:58.65094Z","caller":"traceutil/trace.go:171","msg":"trace[1962255003] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"2.058550462s","start":"2026-07-09T15:25:56.592365Z","end":"2026-07-09T15:25:58.650915Z","steps":["trace[1962255003] 'process raft request' (duration: 1.772114562s)","trace[1962255003] 'compare' (duration: 285.368906ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:25:58.651226Z","caller":"traceutil/trace.go:171","msg":"trace[485987952] linearizableReadLoop","detail":"{readStateIndex:312; appliedIndex:309; }","duration":"1.65811664s","start":"2026-07-09T15:25:56.993067Z","end":"2026-07-09T15:25:58.651184Z","steps":["trace[485987952] 'read index received' (duration: 381.898115ms)","trace[485987952] 'applied index is now lower than readState.Index' (duration: 1.276214224s)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:25:58.651424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:56.592339Z","time spent":"2.058924267s","remote":"127.0.0.1:38326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:25:58.651407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.658314847s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:25:58.651527Z","caller":"traceutil/trace.go:171","msg":"trace[1996464633] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:301; }","duration":"1.658456843s","start":"2026-07-09T15:25:56.993057Z","end":"2026-07-09T15:25:58.651514Z","steps":["trace[1996464633] 'agreement among raft nodes before linearized reading' (duration: 1.658271996s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:58.651532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.61534945s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T15:25:58.651581Z","caller":"traceutil/trace.go:171","msg":"trace[1888879946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:301; }","duration":"1.615486075s","start":"2026-07-09T15:25:57.03608Z","end":"2026-07-09T15:25:58.651566Z","steps":["trace[1888879946] 'agreement among raft nodes before linearized reading' (duration: 1.615319638s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:58.651629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:57.036042Z","time spent":"1.615577379s","remote":"127.0.0.1:38326","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-09T15:25:58.65182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.806999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:25:58.651861Z","caller":"traceutil/trace.go:171","msg":"trace[1502753248] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:301; }","duration":"594.881533ms","start":"2026-07-09T15:25:58.056968Z","end":"2026-07-09T15:25:58.65185Z","steps":["trace[1502753248] 'agreement among raft nodes before linearized reading' (duration: 594.81638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:58.651899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:58.056953Z","time spent":"594.937915ms","remote":"127.0.0.1:38066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T15:25:58.651858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.281916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:25:58.651987Z","caller":"traceutil/trace.go:171","msg":"trace[2141865662] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:0; response_revision:301; }","duration":"436.465744ms","start":"2026-07-09T15:25:58.215495Z","end":"2026-07-09T15:25:58.651961Z","steps":["trace[2141865662] 'agreement among raft nodes before linearized reading' (duration: 436.253806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:58.652042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:58.215474Z","time spent":"436.558328ms","remote":"127.0.0.1:38284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"info","ts":"2026-07-09T15:25:58.652221Z","caller":"traceutil/trace.go:171","msg":"trace[1601910565] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"2.058789351s","start":"2026-07-09T15:25:56.592488Z","end":"2026-07-09T15:25:58.651277Z","steps":["trace[1601910565] 'process raft request' (duration: 2.05803215s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:25:58.652334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:56.592418Z","time spent":"2.059844135s","remote":"127.0.0.1:38326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:26:00.421493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154039891s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:26:00.421652Z","caller":"traceutil/trace.go:171","msg":"trace[927509718] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:331; }","duration":"1.154246149s","start":"2026-07-09T15:25:59.267386Z","end":"2026-07-09T15:26:00.421633Z","steps":["trace[927509718] 'range keys from in-memory index tree' (duration: 1.15402087s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:00.421851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.363048081s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:26:00.422004Z","caller":"traceutil/trace.go:171","msg":"trace[682574020] linearizableReadLoop","detail":"{readStateIndex:344; appliedIndex:343; }","duration":"1.405075306s","start":"2026-07-09T15:25:59.016904Z","end":"2026-07-09T15:26:00.421979Z","steps":["trace[682574020] 'read index received' (duration: 41.789986ms)","trace[682574020] 'applied index is now lower than readState.Index' (duration: 1.36328418s)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:26:00.422205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.415004609s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-07-09T15:26:00.422221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.405302065s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-07-09T15:26:00.422237Z","caller":"traceutil/trace.go:171","msg":"trace[45740357] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:332; }","duration":"1.415066201s","start":"2026-07-09T15:25:59.00716Z","end":"2026-07-09T15:26:00.422226Z","steps":["trace[45740357] 'agreement among raft nodes before linearized reading' (duration: 1.414915655s)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:26:00.422194Z","caller":"traceutil/trace.go:171","msg":"trace[353135524] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"1.408914032s","start":"2026-07-09T15:25:59.013207Z","end":"2026-07-09T15:26:00.422121Z","steps":["trace[353135524] 'process raft request' (duration: 45.473105ms)","trace[353135524] 'compare' (duration: 1.362875934s)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:26:00.422272Z","caller":"traceutil/trace.go:171","msg":"trace[102993058] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:332; }","duration":"1.405501833s","start":"2026-07-09T15:25:59.016756Z","end":"2026-07-09T15:26:00.422258Z","steps":["trace[102993058] 'agreement among raft nodes before linearized reading' (duration: 1.405283125s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:00.422283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:59.007146Z","time spent":"1.415129344s","remote":"127.0.0.1:38284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-07-09T15:26:00.422325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:59.016742Z","time spent":"1.405565835s","remote":"127.0.0.1:38284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-07-09T15:26:00.422422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:59.013197Z","time spent":"1.409112189s","remote":"127.0.0.1:38532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:26:00.429031Z","caller":"traceutil/trace.go:171","msg":"trace[1152891648] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"1.40549763s","start":"2026-07-09T15:25:59.02351Z","end":"2026-07-09T15:26:00.429008Z","steps":["trace[1152891648] 'process raft request' (duration: 1.405266011s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:00.429127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:59.0235Z","time spent":"1.405574333s","remote":"127.0.0.1:38252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T15:26:00.429159Z","caller":"traceutil/trace.go:171","msg":"trace[1155871973] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"1.405421127s","start":"2026-07-09T15:25:59.023717Z","end":"2026-07-09T15:26:00.429138Z","steps":["trace[1155871973] 'process raft request' (duration: 1.405164586s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:00.429276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:59.02371Z","time spent":"1.405505101s","remote":"127.0.0.1:38154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T15:26:00.42937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089157996s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.91\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-09T15:26:00.429406Z","caller":"traceutil/trace.go:171","msg":"trace[537801182] range","detail":"{range_begin:/registry/masterleases/199.19.213.91; range_end:; response_count:1; response_revision:337; }","duration":"1.089228829s","start":"2026-07-09T15:25:59.340167Z","end":"2026-07-09T15:26:00.429395Z","steps":["trace[537801182] 'agreement among raft nodes before linearized reading' (duration: 1.089140345s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:00.429426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:59.340135Z","time spent":"1.089286361s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.91\" "} {"level":"warn","ts":"2026-07-09T15:26:00.429471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259248097s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-07-09T15:26:00.4295Z","caller":"traceutil/trace.go:171","msg":"trace[59711679] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:337; }","duration":"1.259310629s","start":"2026-07-09T15:25:59.170183Z","end":"2026-07-09T15:26:00.429493Z","steps":["trace[59711679] 'agreement among raft nodes before linearized reading' (duration: 1.259246016s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:00.429516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:59.170163Z","time spent":"1.259349481s","remote":"127.0.0.1:38284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":208,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"info","ts":"2026-07-09T15:26:00.429543Z","caller":"traceutil/trace.go:171","msg":"trace[1621536886] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"1.40576692s","start":"2026-07-09T15:25:59.023766Z","end":"2026-07-09T15:26:00.429533Z","steps":["trace[1621536886] 'process raft request' (duration: 1.405164096s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:00.429597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:59.023761Z","time spent":"1.405806202s","remote":"127.0.0.1:38154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T15:26:00.429797Z","caller":"traceutil/trace.go:171","msg":"trace[1824434296] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"1.401463147s","start":"2026-07-09T15:25:59.028323Z","end":"2026-07-09T15:26:00.429786Z","steps":["trace[1824434296] 'process raft request' (duration: 1.400645814s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:00.429851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:59.028314Z","time spent":"1.401505318s","remote":"127.0.0.1:38252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4447,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:26:00.429976Z","caller":"traceutil/trace.go:171","msg":"trace[1503871047] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"1.398150222s","start":"2026-07-09T15:25:59.031812Z","end":"2026-07-09T15:26:00.429962Z","steps":["trace[1503871047] 'process raft request' (duration: 1.397226665s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:00.430054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:59.031802Z","time spent":"1.398206404s","remote":"127.0.0.1:38252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:26:00.430195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270468592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:26:00.430223Z","caller":"traceutil/trace.go:171","msg":"trace[1333572744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:337; }","duration":"1.270538534s","start":"2026-07-09T15:25:59.159676Z","end":"2026-07-09T15:26:00.430215Z","steps":["trace[1333572744] 'agreement among raft nodes before linearized reading' (duration: 1.270501572s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:00.43024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:59.159649Z","time spent":"1.270587157s","remote":"127.0.0.1:38066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T15:26:00.430343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.385474937s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-07-09T15:26:00.43036Z","caller":"traceutil/trace.go:171","msg":"trace[2045744680] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:337; }","duration":"1.385544419s","start":"2026-07-09T15:25:59.04481Z","end":"2026-07-09T15:26:00.430354Z","steps":["trace[2045744680] 'agreement among raft nodes before linearized reading' (duration: 1.385507278s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:00.430378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:25:59.044784Z","time spent":"1.385589041s","remote":"127.0.0.1:38284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-07-09T15:26:04.92697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.454194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-07-09T15:26:04.927064Z","caller":"traceutil/trace.go:171","msg":"trace[1737643695] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:370; }","duration":"376.599309ms","start":"2026-07-09T15:26:04.550442Z","end":"2026-07-09T15:26:04.927041Z","steps":["trace[1737643695] 'range keys from in-memory index tree' (duration: 376.35734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:04.927108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:04.550423Z","time spent":"376.676702ms","remote":"127.0.0.1:38252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4345,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-07-09T15:26:05.008866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.976964ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-09T15:26:05.008945Z","caller":"traceutil/trace.go:171","msg":"trace[1376008584] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:385; }","duration":"297.051119ms","start":"2026-07-09T15:26:04.711882Z","end":"2026-07-09T15:26:05.008933Z","steps":["trace[1376008584] 'read index received' (duration: 29.631µs)","trace[1376008584] 'applied index is now lower than readState.Index' (duration: 297.020508ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:26:05.009156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.229226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T15:26:05.009219Z","caller":"traceutil/trace.go:171","msg":"trace[440247645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:370; }","duration":"297.390713ms","start":"2026-07-09T15:26:04.711813Z","end":"2026-07-09T15:26:05.009203Z","steps":["trace[440247645] 'agreement among raft nodes before linearized reading' (duration: 297.241357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:05.016629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.637921ms","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-09T15:26:05.016712Z","caller":"traceutil/trace.go:171","msg":"trace[1316049059] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:371; }","duration":"295.762137ms","start":"2026-07-09T15:26:04.720928Z","end":"2026-07-09T15:26:05.016691Z","steps":["trace[1316049059] 'agreement among raft nodes before linearized reading' (duration: 295.630112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:05.016811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.246073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-09T15:26:05.01689Z","caller":"traceutil/trace.go:171","msg":"trace[1485994715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:371; }","duration":"278.375288ms","start":"2026-07-09T15:26:04.738497Z","end":"2026-07-09T15:26:05.016872Z","steps":["trace[1485994715] 'agreement among raft nodes before linearized reading' (duration: 278.243903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:05.01709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.142599ms","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-09T15:26:05.017165Z","caller":"traceutil/trace.go:171","msg":"trace[19908817] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:371; }","duration":"278.246243ms","start":"2026-07-09T15:26:04.738901Z","end":"2026-07-09T15:26:05.017148Z","steps":["trace[19908817] 'agreement among raft nodes before linearized reading' (duration: 277.800066ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:26:13.192021Z","caller":"traceutil/trace.go:171","msg":"trace[311331065] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:407; }","duration":"101.476433ms","start":"2026-07-09T15:26:13.090516Z","end":"2026-07-09T15:26:13.191992Z","steps":["trace[311331065] 'read index received' (duration: 101.283286ms)","trace[311331065] 'applied index is now lower than readState.Index' (duration: 169.366µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:26:13.192066Z","caller":"traceutil/trace.go:171","msg":"trace[278246068] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"135.374309ms","start":"2026-07-09T15:26:13.056643Z","end":"2026-07-09T15:26:13.192018Z","steps":["trace[278246068] 'process raft request' (duration: 135.167152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:13.192173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.633398ms","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-09T15:26:13.192227Z","caller":"traceutil/trace.go:171","msg":"trace[690208209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:391; }","duration":"101.753242ms","start":"2026-07-09T15:26:13.090459Z","end":"2026-07-09T15:26:13.192213Z","steps":["trace[690208209] 'agreement among raft nodes before linearized reading' (duration: 101.639398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:13.192239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.055718ms","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-09T15:26:13.192306Z","caller":"traceutil/trace.go:171","msg":"trace[2033801251] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:391; }","duration":"101.187463ms","start":"2026-07-09T15:26:13.091101Z","end":"2026-07-09T15:26:13.192288Z","steps":["trace[2033801251] 'agreement among raft nodes before linearized reading' (duration: 101.075239ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:17.408559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.254059ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:26:17.408656Z","caller":"traceutil/trace.go:171","msg":"trace[200845081] linearizableReadLoop","detail":"{readStateIndex:449; appliedIndex:448; }","duration":"128.72548ms","start":"2026-07-09T15:26:17.279916Z","end":"2026-07-09T15:26:17.408641Z","steps":["trace[200845081] 'read index received' (duration: 15.276794ms)","trace[200845081] 'applied index is now lower than readState.Index' (duration: 113.447496ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:26:17.408697Z","caller":"traceutil/trace.go:171","msg":"trace[1959868063] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"143.6334ms","start":"2026-07-09T15:26:17.265032Z","end":"2026-07-09T15:26:17.408666Z","steps":["trace[1959868063] 'process raft request' (duration: 30.195945ms)","trace[1959868063] 'compare' (duration: 113.137875ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:26:17.408754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.580614ms","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-09T15:26:17.408781Z","caller":"traceutil/trace.go:171","msg":"trace[1343021811] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:430; }","duration":"128.618996ms","start":"2026-07-09T15:26:17.280153Z","end":"2026-07-09T15:26:17.408772Z","steps":["trace[1343021811] 'agreement among raft nodes before linearized reading' (duration: 128.563624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:17.4088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.857904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-09T15:26:17.408874Z","caller":"traceutil/trace.go:171","msg":"trace[1025077754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:430; }","duration":"128.975488ms","start":"2026-07-09T15:26:17.27988Z","end":"2026-07-09T15:26:17.408856Z","steps":["trace[1025077754] 'agreement among raft nodes before linearized reading' (duration: 128.812762ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:26:25.552486Z","caller":"traceutil/trace.go:171","msg":"trace[1472888713] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"108.390524ms","start":"2026-07-09T15:26:25.44406Z","end":"2026-07-09T15:26:25.55245Z","steps":["trace[1472888713] 'process raft request' (duration: 108.212778ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:26:25.560906Z","caller":"traceutil/trace.go:171","msg":"trace[452200636] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"115.605318ms","start":"2026-07-09T15:26:25.445278Z","end":"2026-07-09T15:26:25.560883Z","steps":["trace[452200636] 'process raft request' (duration: 115.442333ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:26:25.561097Z","caller":"traceutil/trace.go:171","msg":"trace[713578468] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"115.766513ms","start":"2026-07-09T15:26:25.44531Z","end":"2026-07-09T15:26:25.561076Z","steps":["trace[713578468] 'process raft request' (duration: 115.531196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:28.77913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.101636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:26:28.779629Z","caller":"traceutil/trace.go:171","msg":"trace[612890486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:487; }","duration":"620.639862ms","start":"2026-07-09T15:26:28.158964Z","end":"2026-07-09T15:26:28.779604Z","steps":["trace[612890486] 'range keys from in-memory index tree' (duration: 620.031543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:28.779722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:28.158946Z","time spent":"620.759796ms","remote":"127.0.0.1:38066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T15:26:29.258403Z","caller":"traceutil/trace.go:171","msg":"trace[203783219] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"100.957737ms","start":"2026-07-09T15:26:29.157399Z","end":"2026-07-09T15:26:29.258357Z","steps":["trace[203783219] 'process raft request' (duration: 100.286167ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:26:29.98128Z","caller":"traceutil/trace.go:171","msg":"trace[55266071] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"112.360629ms","start":"2026-07-09T15:26:29.8689Z","end":"2026-07-09T15:26:29.98126Z","steps":["trace[55266071] 'process raft request' (duration: 98.195334ms)","trace[55266071] 'compare' (duration: 13.878536ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:26:29.987428Z","caller":"traceutil/trace.go:171","msg":"trace[2121490068] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"108.403731ms","start":"2026-07-09T15:26:29.878994Z","end":"2026-07-09T15:26:29.987397Z","steps":["trace[2121490068] 'process raft request' (duration: 103.467343ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:26:30.695962Z","caller":"traceutil/trace.go:171","msg":"trace[1002204706] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"459.83048ms","start":"2026-07-09T15:26:30.236112Z","end":"2026-07-09T15:26:30.695943Z","steps":["trace[1002204706] 'process raft request' (duration: 452.826792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:30.696067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:30.236097Z","time spent":"459.918243ms","remote":"127.0.0.1:38128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T15:26:30.69857Z","caller":"traceutil/trace.go:171","msg":"trace[656458164] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"374.989931ms","start":"2026-07-09T15:26:30.323569Z","end":"2026-07-09T15:26:30.698559Z","steps":["trace[656458164] 'process raft request' (duration: 374.693182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:30.698646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:30.32355Z","time spent":"375.055952ms","remote":"127.0.0.1:38128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:26:37.783998Z","caller":"traceutil/trace.go:171","msg":"trace[1007118771] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"384.632793ms","start":"2026-07-09T15:26:37.399349Z","end":"2026-07-09T15:26:37.783981Z","steps":["trace[1007118771] 'read index received' (duration: 384.342605ms)","trace[1007118771] 'applied index is now lower than readState.Index' (duration: 289.408µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:26:37.78418Z","caller":"traceutil/trace.go:171","msg":"trace[591397744] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"504.402766ms","start":"2026-07-09T15:26:37.279769Z","end":"2026-07-09T15:26:37.784171Z","steps":["trace[591397744] 'process raft request' (duration: 503.964784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:37.784313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:37.279748Z","time spent":"504.453047ms","remote":"127.0.0.1:38252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:26:37.784641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.273751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T15:26:37.784726Z","caller":"traceutil/trace.go:171","msg":"trace[1739329898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:610; }","duration":"385.406615ms","start":"2026-07-09T15:26:37.399297Z","end":"2026-07-09T15:26:37.784704Z","steps":["trace[1739329898] 'agreement among raft nodes before linearized reading' (duration: 385.121947ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:37.784403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.358952ms","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-09T15:26:37.78477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:37.399274Z","time spent":"385.487357ms","remote":"127.0.0.1:38326","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":"info","ts":"2026-07-09T15:26:37.784812Z","caller":"traceutil/trace.go:171","msg":"trace[1585783312] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:610; }","duration":"128.843516ms","start":"2026-07-09T15:26:37.655932Z","end":"2026-07-09T15:26:37.784776Z","steps":["trace[1585783312] 'agreement among raft nodes before linearized reading' (duration: 128.308471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:39.843277Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689225872361843758,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:26:40.343807Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689225872361843758,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:26:40.844997Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689225872361843758,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T15:26:40.929062Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.608646666s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T15:26:40.929603Z","caller":"traceutil/trace.go:171","msg":"trace[1714148104] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"1.586604692s","start":"2026-07-09T15:26:39.342966Z","end":"2026-07-09T15:26:40.929571Z","steps":["trace[1714148104] 'read index received' (duration: 1.586338975s)","trace[1714148104] 'applied index is now lower than readState.Index' (duration: 264.377µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:26:40.929864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112030765s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-09T15:26:40.92987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.586877659s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.91\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-09T15:26:40.929903Z","caller":"traceutil/trace.go:171","msg":"trace[1775638118] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:615; }","duration":"1.112137508s","start":"2026-07-09T15:26:39.817753Z","end":"2026-07-09T15:26:40.929891Z","steps":["trace[1775638118] 'agreement among raft nodes before linearized reading' (duration: 1.111941762s)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:26:40.929932Z","caller":"traceutil/trace.go:171","msg":"trace[121732510] range","detail":"{range_begin:/registry/masterleases/199.19.213.91; range_end:; response_count:1; response_revision:615; }","duration":"1.587004252s","start":"2026-07-09T15:26:39.342911Z","end":"2026-07-09T15:26:40.929915Z","steps":["trace[121732510] 'agreement among raft nodes before linearized reading' (duration: 1.586781596s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:40.929943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:39.817729Z","time spent":"1.1122039s","remote":"127.0.0.1:38326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T15:26:40.929988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:39.342849Z","time spent":"1.587127056s","remote":"127.0.0.1:38118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.91\" "} {"level":"warn","ts":"2026-07-09T15:26:40.930034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080386726s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-09T15:26:40.930136Z","caller":"traceutil/trace.go:171","msg":"trace[776867960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:615; }","duration":"1.080575852s","start":"2026-07-09T15:26:39.849538Z","end":"2026-07-09T15:26:40.930114Z","steps":["trace[776867960] 'agreement among raft nodes before linearized reading' (duration: 1.080283974s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:40.930035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080082838s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T15:26:40.930278Z","caller":"traceutil/trace.go:171","msg":"trace[1449986964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:615; }","duration":"1.080368356s","start":"2026-07-09T15:26:39.84986Z","end":"2026-07-09T15:26:40.930228Z","steps":["trace[1449986964] 'agreement among raft nodes before linearized reading' (duration: 1.079899593s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:40.930185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:39.849504Z","time spent":"1.080671955s","remote":"127.0.0.1:38326","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-09T15:26:40.930376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:39.849843Z","time spent":"1.08050824s","remote":"127.0.0.1:38326","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":"info","ts":"2026-07-09T15:26:40.93042Z","caller":"traceutil/trace.go:171","msg":"trace[281587932] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"1.609397307s","start":"2026-07-09T15:26:39.32028Z","end":"2026-07-09T15:26:40.929677Z","steps":["trace[281587932] 'process raft request' (duration: 1.609109029s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:40.93057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:39.320254Z","time spent":"1.610224119s","remote":"127.0.0.1:38326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:26:40.930695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079428271s","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-09T15:26:40.930753Z","caller":"traceutil/trace.go:171","msg":"trace[1706625422] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:615; }","duration":"1.080036927s","start":"2026-07-09T15:26:39.850702Z","end":"2026-07-09T15:26:40.930739Z","steps":["trace[1706625422] 'agreement among raft nodes before linearized reading' (duration: 1.079020109s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:40.930791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:39.850696Z","time spent":"1.080086588s","remote":"127.0.0.1:38326","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-09T15:26:41.431617Z","caller":"traceutil/trace.go:171","msg":"trace[1010644419] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"139.174863ms","start":"2026-07-09T15:26:41.292414Z","end":"2026-07-09T15:26:41.431589Z","steps":["trace[1010644419] 'process raft request' (duration: 136.363157ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:26:46.451286Z","caller":"traceutil/trace.go:171","msg":"trace[1494047911] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"115.514858ms","start":"2026-07-09T15:26:46.335747Z","end":"2026-07-09T15:26:46.451261Z","steps":["trace[1494047911] 'process raft request' (duration: 115.380824ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:26:46.697893Z","caller":"traceutil/trace.go:171","msg":"trace[2062138213] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"348.726619ms","start":"2026-07-09T15:26:46.349137Z","end":"2026-07-09T15:26:46.697863Z","steps":["trace[2062138213] 'process raft request' (duration: 306.342253ms)","trace[2062138213] 'compare' (duration: 42.267193ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:26:46.698037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:46.349103Z","time spent":"348.858653ms","remote":"127.0.0.1:38252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18497,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:26:46.918371Z","caller":"traceutil/trace.go:171","msg":"trace[475624703] linearizableReadLoop","detail":"{readStateIndex:720; appliedIndex:719; }","duration":"214.244091ms","start":"2026-07-09T15:26:46.704103Z","end":"2026-07-09T15:26:46.918347Z","steps":["trace[475624703] 'read index received' (duration: 163.701184ms)","trace[475624703] 'applied index is now lower than readState.Index' (duration: 50.542047ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:26:46.918488Z","caller":"traceutil/trace.go:171","msg":"trace[743183971] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"384.233708ms","start":"2026-07-09T15:26:46.534243Z","end":"2026-07-09T15:26:46.918477Z","steps":["trace[743183971] 'process raft request' (duration: 333.749603ms)","trace[743183971] 'compare' (duration: 50.125806ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:26:46.918579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:46.534204Z","time spent":"384.31184ms","remote":"127.0.0.1:38128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T15:26:46.918832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.719003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-07-09T15:26:46.918902Z","caller":"traceutil/trace.go:171","msg":"trace[817242713] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:691; }","duration":"214.824656ms","start":"2026-07-09T15:26:46.704061Z","end":"2026-07-09T15:26:46.918886Z","steps":["trace[817242713] 'agreement among raft nodes before linearized reading' (duration: 214.634001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:46.919226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.367673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T15:26:46.919352Z","caller":"traceutil/trace.go:171","msg":"trace[2091553168] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:691; }","duration":"137.546957ms","start":"2026-07-09T15:26:46.781779Z","end":"2026-07-09T15:26:46.919326Z","steps":["trace[2091553168] 'agreement among raft nodes before linearized reading' (duration: 137.231219ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:26:47.13348Z","caller":"traceutil/trace.go:171","msg":"trace[1764920251] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"200.978401ms","start":"2026-07-09T15:26:46.932482Z","end":"2026-07-09T15:26:47.13346Z","steps":["trace[1764920251] 'process raft request' (duration: 200.844208ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:26:47.139037Z","caller":"traceutil/trace.go:171","msg":"trace[1138486835] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:720; }","duration":"129.470082ms","start":"2026-07-09T15:26:47.009546Z","end":"2026-07-09T15:26:47.139016Z","steps":["trace[1138486835] 'read index received' (duration: 123.811926ms)","trace[1138486835] 'applied index is now lower than readState.Index' (duration: 5.657206ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:26:47.139196Z","caller":"traceutil/trace.go:171","msg":"trace[1406472410] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"131.941015ms","start":"2026-07-09T15:26:47.007244Z","end":"2026-07-09T15:26:47.139185Z","steps":["trace[1406472410] 'process raft request' (duration: 131.539515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:47.139521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.268936ms","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-09T15:26:47.139773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.131132ms","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-09T15:26:47.139813Z","caller":"traceutil/trace.go:171","msg":"trace[1633687882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:693; }","duration":"127.187723ms","start":"2026-07-09T15:26:47.012615Z","end":"2026-07-09T15:26:47.139802Z","steps":["trace[1633687882] 'agreement among raft nodes before linearized reading' (duration: 127.06187ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:47.139536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.980075ms","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-09T15:26:47.139942Z","caller":"traceutil/trace.go:171","msg":"trace[1995273004] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:693; }","duration":"130.336064ms","start":"2026-07-09T15:26:47.009504Z","end":"2026-07-09T15:26:47.13984Z","steps":["trace[1995273004] 'agreement among raft nodes before linearized reading' (duration: 129.902323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:47.140362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.408334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-09T15:26:47.140416Z","caller":"traceutil/trace.go:171","msg":"trace[305792867] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:693; }","duration":"128.500126ms","start":"2026-07-09T15:26:47.011905Z","end":"2026-07-09T15:26:47.140405Z","steps":["trace[305792867] 'agreement among raft nodes before linearized reading' (duration: 127.86857ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:26:47.140809Z","caller":"traceutil/trace.go:171","msg":"trace[1182292181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:693; }","duration":"129.40293ms","start":"2026-07-09T15:26:47.010197Z","end":"2026-07-09T15:26:47.1396Z","steps":["trace[1182292181] 'agreement among raft nodes before linearized reading' (duration: 129.167564ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:26:47.401911Z","caller":"traceutil/trace.go:171","msg":"trace[852951633] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"175.129743ms","start":"2026-07-09T15:26:47.226763Z","end":"2026-07-09T15:26:47.401893Z","steps":["trace[852951633] 'process raft request' (duration: 174.64428ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:26:47.589261Z","caller":"traceutil/trace.go:171","msg":"trace[923775051] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"175.843421ms","start":"2026-07-09T15:26:47.41339Z","end":"2026-07-09T15:26:47.589233Z","steps":["trace[923775051] 'process raft request' (duration: 175.435081ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:26:47.73497Z","caller":"traceutil/trace.go:171","msg":"trace[680636460] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"240.600522ms","start":"2026-07-09T15:26:47.494342Z","end":"2026-07-09T15:26:47.734943Z","steps":["trace[680636460] 'process raft request' (duration: 239.965856ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:26:47.734959Z","caller":"traceutil/trace.go:171","msg":"trace[2042382473] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:731; }","duration":"231.102539ms","start":"2026-07-09T15:26:47.503834Z","end":"2026-07-09T15:26:47.734937Z","steps":["trace[2042382473] 'read index received' (duration: 85.033732ms)","trace[2042382473] 'applied index is now lower than readState.Index' (duration: 146.067487ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:26:47.735571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.710165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-07-09T15:26:47.735636Z","caller":"traceutil/trace.go:171","msg":"trace[585207025] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:704; }","duration":"231.833238ms","start":"2026-07-09T15:26:47.503788Z","end":"2026-07-09T15:26:47.735621Z","steps":["trace[585207025] 'agreement among raft nodes before linearized reading' (duration: 231.318394ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:26:47.736076Z","caller":"traceutil/trace.go:171","msg":"trace[1976660951] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"136.881562ms","start":"2026-07-09T15:26:47.599172Z","end":"2026-07-09T15:26:47.736054Z","steps":["trace[1976660951] 'process raft request' (duration: 136.722818ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:26:53.836556Z","caller":"traceutil/trace.go:171","msg":"trace[1890325008] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"414.88115ms","start":"2026-07-09T15:26:53.421658Z","end":"2026-07-09T15:26:53.836539Z","steps":["trace[1890325008] 'process raft request' (duration: 414.766678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:53.836753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:53.421638Z","time spent":"415.056864ms","remote":"127.0.0.1:38238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:26:53.84336Z","caller":"traceutil/trace.go:171","msg":"trace[1873427965] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"410.910723ms","start":"2026-07-09T15:26:53.432441Z","end":"2026-07-09T15:26:53.843351Z","steps":["trace[1873427965] 'process raft request' (duration: 410.873232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:53.843699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:53.432399Z","time spent":"411.270732ms","remote":"127.0.0.1:38552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3852,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:26:53.843485Z","caller":"traceutil/trace.go:171","msg":"trace[1738797166] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"421.613505ms","start":"2026-07-09T15:26:53.421861Z","end":"2026-07-09T15:26:53.843474Z","steps":["trace[1738797166] 'process raft request' (duration: 421.39049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:53.843888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:53.421854Z","time spent":"421.954403ms","remote":"127.0.0.1:38342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:26:55.292574Z","caller":"traceutil/trace.go:171","msg":"trace[287904545] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"103.299988ms","start":"2026-07-09T15:26:55.189255Z","end":"2026-07-09T15:26:55.292555Z","steps":["trace[287904545] 'process raft request' (duration: 103.096153ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:26:55.599638Z","caller":"traceutil/trace.go:171","msg":"trace[1640878048] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"303.073761ms","start":"2026-07-09T15:26:55.296541Z","end":"2026-07-09T15:26:55.599615Z","steps":["trace[1640878048] 'process raft request' (duration: 302.877166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:55.599784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:55.296527Z","time spent":"303.179553ms","remote":"127.0.0.1:38326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:26:55.599865Z","caller":"traceutil/trace.go:171","msg":"trace[1080848857] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"302.763714ms","start":"2026-07-09T15:26:55.297085Z","end":"2026-07-09T15:26:55.599849Z","steps":["trace[1080848857] 'process raft request' (duration: 302.485737ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:26:55.599944Z","caller":"traceutil/trace.go:171","msg":"trace[2049380423] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"302.717422ms","start":"2026-07-09T15:26:55.297218Z","end":"2026-07-09T15:26:55.599935Z","steps":["trace[2049380423] 'process raft request' (duration: 302.580019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:55.59995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:55.297065Z","time spent":"302.842335ms","remote":"127.0.0.1:38326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:26:55.6Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:55.297206Z","time spent":"302.758994ms","remote":"127.0.0.1:38326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:26:57.523927Z","caller":"traceutil/trace.go:171","msg":"trace[551102257] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:807; }","duration":"194.026874ms","start":"2026-07-09T15:26:57.329875Z","end":"2026-07-09T15:26:57.523902Z","steps":["trace[551102257] 'read index received' (duration: 193.84387ms)","trace[551102257] 'applied index is now lower than readState.Index' (duration: 181.814µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:26:57.523951Z","caller":"traceutil/trace.go:171","msg":"trace[1718785959] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"223.848782ms","start":"2026-07-09T15:26:57.300079Z","end":"2026-07-09T15:26:57.523928Z","steps":["trace[1718785959] 'process raft request' (duration: 223.575015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:57.524409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.503866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-09T15:26:57.524489Z","caller":"traceutil/trace.go:171","msg":"trace[788372529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:775; }","duration":"194.633879ms","start":"2026-07-09T15:26:57.329843Z","end":"2026-07-09T15:26:57.524477Z","steps":["trace[788372529] 'agreement among raft nodes before linearized reading' (duration: 194.146997ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:58.103292Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689225872361844080,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T15:26:58.377957Z","caller":"traceutil/trace.go:171","msg":"trace[1979674078] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"977.217934ms","start":"2026-07-09T15:26:57.400717Z","end":"2026-07-09T15:26:58.377935Z","steps":["trace[1979674078] 'process raft request' (duration: 977.0781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:58.378097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:57.400694Z","time spent":"977.331447ms","remote":"127.0.0.1:38308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:26:58.382834Z","caller":"traceutil/trace.go:171","msg":"trace[1332407964] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:808; }","duration":"779.773987ms","start":"2026-07-09T15:26:57.603041Z","end":"2026-07-09T15:26:58.382815Z","steps":["trace[1332407964] 'read index received' (duration: 774.839991ms)","trace[1332407964] 'applied index is now lower than readState.Index' (duration: 4.933046ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:26:58.383017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.341707ms","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-09T15:26:58.383059Z","caller":"traceutil/trace.go:171","msg":"trace[837668382] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:777; }","duration":"779.395078ms","start":"2026-07-09T15:26:57.603652Z","end":"2026-07-09T15:26:58.383047Z","steps":["trace[837668382] 'agreement among raft nodes before linearized reading' (duration: 779.266985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:58.383098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:57.603593Z","time spent":"779.493781ms","remote":"127.0.0.1:38326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T15:26:58.383185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.102255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T15:26:58.383291Z","caller":"traceutil/trace.go:171","msg":"trace[623998639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:777; }","duration":"780.240318ms","start":"2026-07-09T15:26:57.602996Z","end":"2026-07-09T15:26:58.383236Z","steps":["trace[623998639] 'agreement among raft nodes before linearized reading' (duration: 779.915821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:58.383387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:57.602981Z","time spent":"780.356761ms","remote":"127.0.0.1:38326","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":"info","ts":"2026-07-09T15:26:58.383487Z","caller":"traceutil/trace.go:171","msg":"trace[25967839] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"855.347151ms","start":"2026-07-09T15:26:57.528126Z","end":"2026-07-09T15:26:58.383474Z","steps":["trace[25967839] 'process raft request' (duration: 854.596983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:58.383586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:57.528107Z","time spent":"855.417982ms","remote":"127.0.0.1:38326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:26:58.383586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.577713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-09T15:26:58.383723Z","caller":"traceutil/trace.go:171","msg":"trace[517972733] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:777; }","duration":"779.748536ms","start":"2026-07-09T15:26:57.603955Z","end":"2026-07-09T15:26:58.383704Z","steps":["trace[517972733] 'agreement among raft nodes before linearized reading' (duration: 779.237064ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:26:58.383826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:26:57.603948Z","time spent":"779.864749ms","remote":"127.0.0.1:38326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-09T15:27:01.632399Z","caller":"traceutil/trace.go:171","msg":"trace[1588939416] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"104.61618ms","start":"2026-07-09T15:27:01.527759Z","end":"2026-07-09T15:27:01.632375Z","steps":["trace[1588939416] 'process raft request' (duration: 104.434726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:02.634855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.517655ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:27:02.634952Z","caller":"traceutil/trace.go:171","msg":"trace[1699777699] linearizableReadLoop","detail":"{readStateIndex:860; appliedIndex:859; }","duration":"135.676884ms","start":"2026-07-09T15:27:02.499259Z","end":"2026-07-09T15:27:02.634936Z","steps":["trace[1699777699] 'read index received' (duration: 25.936364ms)","trace[1699777699] 'applied index is now lower than readState.Index' (duration: 109.73939ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:27:02.635252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.975801ms","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-09T15:27:02.635304Z","caller":"traceutil/trace.go:171","msg":"trace[1821386641] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"175.444903ms","start":"2026-07-09T15:27:02.459774Z","end":"2026-07-09T15:27:02.635219Z","steps":["trace[1821386641] 'process raft request' (duration: 65.478757ms)","trace[1821386641] 'compare' (duration: 109.403033ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:27:02.635377Z","caller":"traceutil/trace.go:171","msg":"trace[2027803855] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:826; }","duration":"136.109964ms","start":"2026-07-09T15:27:02.499247Z","end":"2026-07-09T15:27:02.635356Z","steps":["trace[2027803855] 'agreement among raft nodes before linearized reading' (duration: 135.838047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:02.635596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.470539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T15:27:02.635645Z","caller":"traceutil/trace.go:171","msg":"trace[2099433101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:826; }","duration":"135.538681ms","start":"2026-07-09T15:27:02.500093Z","end":"2026-07-09T15:27:02.635631Z","steps":["trace[2099433101] 'agreement among raft nodes before linearized reading' (duration: 135.343577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:02.636084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.223952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050264"} {"level":"info","ts":"2026-07-09T15:27:02.636148Z","caller":"traceutil/trace.go:171","msg":"trace[101508361] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:826; }","duration":"131.320624ms","start":"2026-07-09T15:27:02.504812Z","end":"2026-07-09T15:27:02.636132Z","steps":["trace[101508361] 'agreement among raft nodes before linearized reading' (duration: 130.666869ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:03.995606Z","caller":"traceutil/trace.go:171","msg":"trace[1824103449] linearizableReadLoop","detail":"{readStateIndex:871; appliedIndex:868; }","duration":"249.392445ms","start":"2026-07-09T15:27:03.746191Z","end":"2026-07-09T15:27:03.995583Z","steps":["trace[1824103449] 'read index received' (duration: 27.655458ms)","trace[1824103449] 'applied index is now lower than readState.Index' (duration: 221.735977ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:27:03.995714Z","caller":"traceutil/trace.go:171","msg":"trace[792860609] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"253.677842ms","start":"2026-07-09T15:27:03.742026Z","end":"2026-07-09T15:27:03.995704Z","steps":["trace[792860609] 'process raft request' (duration: 253.508318ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:03.995705Z","caller":"traceutil/trace.go:171","msg":"trace[407198743] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"253.963738ms","start":"2026-07-09T15:27:03.741706Z","end":"2026-07-09T15:27:03.995669Z","steps":["trace[407198743] 'process raft request' (duration: 246.919518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:03.996023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.804094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/\" range_end:\"/registry/serviceaccounts/envoy-gateway-system0\" limit:10000 ","response":"range_response_count:3 size:1862"} {"level":"info","ts":"2026-07-09T15:27:03.996635Z","caller":"traceutil/trace.go:171","msg":"trace[11039103] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/; range_end:/registry/serviceaccounts/envoy-gateway-system0; response_count:3; response_revision:837; }","duration":"250.454768ms","start":"2026-07-09T15:27:03.746166Z","end":"2026-07-09T15:27:03.996621Z","steps":["trace[11039103] 'agreement among raft nodes before linearized reading' (duration: 249.695821ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:03.996531Z","caller":"traceutil/trace.go:171","msg":"trace[725250952] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"144.671226ms","start":"2026-07-09T15:27:03.851847Z","end":"2026-07-09T15:27:03.996519Z","steps":["trace[725250952] 'process raft request' (duration: 144.38304ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:04.596604Z","caller":"traceutil/trace.go:171","msg":"trace[1825225002] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"127.673429ms","start":"2026-07-09T15:27:04.468906Z","end":"2026-07-09T15:27:04.596579Z","steps":["trace[1825225002] 'process raft request' (duration: 127.598808ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:04.596638Z","caller":"traceutil/trace.go:171","msg":"trace[172927900] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"128.628971ms","start":"2026-07-09T15:27:04.467965Z","end":"2026-07-09T15:27:04.596594Z","steps":["trace[172927900] 'process raft request' (duration: 117.49962ms)","trace[172927900] 'compare' (duration: 10.911286ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:27:09.611602Z","caller":"traceutil/trace.go:171","msg":"trace[1945799475] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"101.040851ms","start":"2026-07-09T15:27:09.510526Z","end":"2026-07-09T15:27:09.611567Z","steps":["trace[1945799475] 'process raft request' (duration: 100.97298ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:09.611623Z","caller":"traceutil/trace.go:171","msg":"trace[615698859] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"101.526272ms","start":"2026-07-09T15:27:09.51006Z","end":"2026-07-09T15:27:09.611586Z","steps":["trace[615698859] 'process raft request' (duration: 97.513274ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:10.90428Z","caller":"traceutil/trace.go:171","msg":"trace[642273042] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"116.095252ms","start":"2026-07-09T15:27:10.788158Z","end":"2026-07-09T15:27:10.904253Z","steps":["trace[642273042] 'process raft request' (duration: 115.632962ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:10.904557Z","caller":"traceutil/trace.go:171","msg":"trace[951231709] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"113.880825ms","start":"2026-07-09T15:27:10.790629Z","end":"2026-07-09T15:27:10.904509Z","steps":["trace[951231709] 'process raft request' (duration: 113.577598ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:28.362619Z","caller":"traceutil/trace.go:171","msg":"trace[1019862587] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"126.427179ms","start":"2026-07-09T15:27:28.236166Z","end":"2026-07-09T15:27:28.362594Z","steps":["trace[1019862587] 'process raft request' (duration: 126.281476ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:29.083929Z","caller":"traceutil/trace.go:171","msg":"trace[109235010] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"136.162199ms","start":"2026-07-09T15:27:28.947726Z","end":"2026-07-09T15:27:29.083888Z","steps":["trace[109235010] 'process raft request' (duration: 44.373169ms)","trace[109235010] 'compare' (duration: 91.592526ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:27:31.156003Z","caller":"traceutil/trace.go:171","msg":"trace[870358865] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"142.32396ms","start":"2026-07-09T15:27:31.013615Z","end":"2026-07-09T15:27:31.155939Z","steps":["trace[870358865] 'process raft request' (duration: 142.109136ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:31.346018Z","caller":"traceutil/trace.go:171","msg":"trace[2050214249] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"104.023141ms","start":"2026-07-09T15:27:31.241963Z","end":"2026-07-09T15:27:31.345986Z","steps":["trace[2050214249] 'process raft request' (duration: 103.397999ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:33.641162Z","caller":"traceutil/trace.go:171","msg":"trace[1461022831] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"227.10327ms","start":"2026-07-09T15:27:33.414027Z","end":"2026-07-09T15:27:33.64113Z","steps":["trace[1461022831] 'process raft request' (duration: 166.206163ms)","trace[1461022831] 'compare' (duration: 60.731654ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:27:45.374526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.327527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-09T15:27:45.374609Z","caller":"traceutil/trace.go:171","msg":"trace[1835978996] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1061; }","duration":"146.453018ms","start":"2026-07-09T15:27:45.228137Z","end":"2026-07-09T15:27:45.374591Z","steps":["trace[1835978996] 'range keys from in-memory index tree' (duration: 146.134413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:45.374813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.714008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:27:45.374853Z","caller":"traceutil/trace.go:171","msg":"trace[1831561170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1061; }","duration":"215.776059ms","start":"2026-07-09T15:27:45.159065Z","end":"2026-07-09T15:27:45.374841Z","steps":["trace[1831561170] 'range keys from in-memory index tree' (duration: 215.662477ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:45.508152Z","caller":"traceutil/trace.go:171","msg":"trace[2050410421] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"127.580383ms","start":"2026-07-09T15:27:45.380547Z","end":"2026-07-09T15:27:45.508127Z","steps":["trace[2050410421] 'process raft request' (duration: 102.425207ms)","trace[2050410421] 'compare' (duration: 24.83817ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:27:51.369709Z","caller":"traceutil/trace.go:171","msg":"trace[276764470] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1124; }","duration":"209.766702ms","start":"2026-07-09T15:27:51.15992Z","end":"2026-07-09T15:27:51.369687Z","steps":["trace[276764470] 'read index received' (duration: 209.556258ms)","trace[276764470] 'applied index is now lower than readState.Index' (duration: 209.144µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:27:51.369752Z","caller":"traceutil/trace.go:171","msg":"trace[639260312] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"298.25808ms","start":"2026-07-09T15:27:51.07147Z","end":"2026-07-09T15:27:51.369728Z","steps":["trace[639260312] 'process raft request' (duration: 298.084247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:51.369819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.881794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:27:51.369854Z","caller":"traceutil/trace.go:171","msg":"trace[542089239] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1080; }","duration":"209.978556ms","start":"2026-07-09T15:27:51.159865Z","end":"2026-07-09T15:27:51.369844Z","steps":["trace[542089239] 'agreement among raft nodes before linearized reading' (duration: 209.909345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:51.717121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.231129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T15:27:51.717207Z","caller":"traceutil/trace.go:171","msg":"trace[437332044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1080; }","duration":"174.363331ms","start":"2026-07-09T15:27:51.542823Z","end":"2026-07-09T15:27:51.717187Z","steps":["trace[437332044] 'range keys from in-memory index tree' (duration: 174.087546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:51.717122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.8937ms","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-09T15:27:51.717325Z","caller":"traceutil/trace.go:171","msg":"trace[1305463387] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1080; }","duration":"179.144114ms","start":"2026-07-09T15:27:51.538145Z","end":"2026-07-09T15:27:51.71729Z","steps":["trace[1305463387] 'range keys from in-memory index tree' (duration: 178.704876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:51.717365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.311129ms","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-09T15:27:51.717453Z","caller":"traceutil/trace.go:171","msg":"trace[761741582] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1080; }","duration":"244.452301ms","start":"2026-07-09T15:27:51.47298Z","end":"2026-07-09T15:27:51.717432Z","steps":["trace[761741582] 'range keys from in-memory index tree' (duration: 244.061384ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:52.183329Z","caller":"traceutil/trace.go:171","msg":"trace[1563948957] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"462.001504ms","start":"2026-07-09T15:27:51.721308Z","end":"2026-07-09T15:27:52.183309Z","steps":["trace[1563948957] 'process raft request' (duration: 461.883362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:52.183451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:27:51.721286Z","time spent":"462.101636ms","remote":"127.0.0.1:38326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:27:52.215767Z","caller":"traceutil/trace.go:171","msg":"trace[497302332] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"493.832841ms","start":"2026-07-09T15:27:51.721914Z","end":"2026-07-09T15:27:52.215747Z","steps":["trace[497302332] 'process raft request' (duration: 493.782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:52.215886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:27:51.721893Z","time spent":"493.931963ms","remote":"127.0.0.1:38326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:27:52.215865Z","caller":"traceutil/trace.go:171","msg":"trace[207641370] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"494.226209ms","start":"2026-07-09T15:27:51.721597Z","end":"2026-07-09T15:27:52.215824Z","steps":["trace[207641370] 'process raft request' (duration: 493.986814ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:27:52.216069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:27:51.721575Z","time spent":"494.383041ms","remote":"127.0.0.1:38326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:27:52.335114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.521503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-09T15:27:52.335208Z","caller":"traceutil/trace.go:171","msg":"trace[398665131] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1083; }","duration":"102.663235ms","start":"2026-07-09T15:27:52.232529Z","end":"2026-07-09T15:27:52.335192Z","steps":["trace[398665131] 'range keys from in-memory index tree' (duration: 102.36432ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:52.455034Z","caller":"traceutil/trace.go:171","msg":"trace[463494453] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"115.951073ms","start":"2026-07-09T15:27:52.33903Z","end":"2026-07-09T15:27:52.454981Z","steps":["trace[463494453] 'process raft request' (duration: 115.617837ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:52.777008Z","caller":"traceutil/trace.go:171","msg":"trace[80070852] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"267.384896ms","start":"2026-07-09T15:27:52.509589Z","end":"2026-07-09T15:27:52.776974Z","steps":["trace[80070852] 'process raft request' (duration: 173.486452ms)","trace[80070852] 'compare' (duration: 93.763772ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:27:53.604809Z","caller":"traceutil/trace.go:171","msg":"trace[496294699] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"227.853851ms","start":"2026-07-09T15:27:53.376936Z","end":"2026-07-09T15:27:53.604789Z","steps":["trace[496294699] 'process raft request' (duration: 227.735959ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:54.251764Z","caller":"traceutil/trace.go:171","msg":"trace[764692451] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"127.070486ms","start":"2026-07-09T15:27:54.124666Z","end":"2026-07-09T15:27:54.251736Z","steps":["trace[764692451] 'process raft request' (duration: 126.840532ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:54.375368Z","caller":"traceutil/trace.go:171","msg":"trace[1868487338] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"118.943498ms","start":"2026-07-09T15:27:54.256388Z","end":"2026-07-09T15:27:54.375332Z","steps":["trace[1868487338] 'process raft request' (duration: 44.369426ms)","trace[1868487338] 'compare' (duration: 74.358888ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:27:54.375543Z","caller":"traceutil/trace.go:171","msg":"trace[346580748] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"118.669693ms","start":"2026-07-09T15:27:54.256851Z","end":"2026-07-09T15:27:54.375521Z","steps":["trace[346580748] 'process raft request' (duration: 118.413308ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:54.375769Z","caller":"traceutil/trace.go:171","msg":"trace[689832042] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"118.385288ms","start":"2026-07-09T15:27:54.257368Z","end":"2026-07-09T15:27:54.375754Z","steps":["trace[689832042] 'process raft request' (duration: 118.073112ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:54.890735Z","caller":"traceutil/trace.go:171","msg":"trace[235446767] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"108.390558ms","start":"2026-07-09T15:27:54.782325Z","end":"2026-07-09T15:27:54.890715Z","steps":["trace[235446767] 'process raft request' (duration: 108.275566ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:27:57.048918Z","caller":"traceutil/trace.go:171","msg":"trace[1589245624] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"153.462071ms","start":"2026-07-09T15:27:56.895425Z","end":"2026-07-09T15:27:57.048887Z","steps":["trace[1589245624] 'process raft request' (duration: 153.302258ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:28:07.898658Z","caller":"traceutil/trace.go:171","msg":"trace[1216326175] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"141.472131ms","start":"2026-07-09T15:28:07.757161Z","end":"2026-07-09T15:28:07.898633Z","steps":["trace[1216326175] 'process raft request' (duration: 141.257497ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:28:09.531853Z","caller":"traceutil/trace.go:171","msg":"trace[1207105736] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"172.487823ms","start":"2026-07-09T15:28:09.359314Z","end":"2026-07-09T15:28:09.531802Z","steps":["trace[1207105736] 'process raft request' (duration: 142.136385ms)","trace[1207105736] 'compare' (duration: 30.146695ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:28:16.778209Z","caller":"traceutil/trace.go:171","msg":"trace[1132911085] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"134.598543ms","start":"2026-07-09T15:28:16.643569Z","end":"2026-07-09T15:28:16.778167Z","steps":["trace[1132911085] 'process raft request' (duration: 104.756094ms)","trace[1132911085] 'compare' (duration: 29.624205ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:28:16.778352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.270777ms","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-09T15:28:16.778376Z","caller":"traceutil/trace.go:171","msg":"trace[1961582370] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1162; }","duration":"127.334899ms","start":"2026-07-09T15:28:16.651034Z","end":"2026-07-09T15:28:16.778369Z","steps":["trace[1961582370] 'agreement among raft nodes before linearized reading' (duration: 127.234917ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:28:16.778655Z","caller":"traceutil/trace.go:171","msg":"trace[1694497442] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1211; }","duration":"127.044764ms","start":"2026-07-09T15:28:16.651076Z","end":"2026-07-09T15:28:16.778121Z","steps":["trace[1694497442] 'read index received' (duration: 97.199305ms)","trace[1694497442] 'applied index is now lower than readState.Index' (duration: 29.844309ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:28:19.045639Z","caller":"traceutil/trace.go:171","msg":"trace[1685999932] linearizableReadLoop","detail":"{readStateIndex:1218; appliedIndex:1217; }","duration":"214.031157ms","start":"2026-07-09T15:28:18.831583Z","end":"2026-07-09T15:28:19.045614Z","steps":["trace[1685999932] 'read index received' (duration: 213.813664ms)","trace[1685999932] 'applied index is now lower than readState.Index' (duration: 216.363µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:28:19.045683Z","caller":"traceutil/trace.go:171","msg":"trace[1235939093] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"238.480768ms","start":"2026-07-09T15:28:18.807173Z","end":"2026-07-09T15:28:19.045654Z","steps":["trace[1235939093] 'process raft request' (duration: 238.282014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:28:19.045936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.322451ms","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-09T15:28:19.046009Z","caller":"traceutil/trace.go:171","msg":"trace[994518897] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1168; }","duration":"214.428653ms","start":"2026-07-09T15:28:18.831562Z","end":"2026-07-09T15:28:19.04599Z","steps":["trace[994518897] 'agreement among raft nodes before linearized reading' (duration: 214.173289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:28:19.045916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.43758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-09T15:28:19.047403Z","caller":"traceutil/trace.go:171","msg":"trace[1431456326] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1168; }","duration":"211.974264ms","start":"2026-07-09T15:28:18.835408Z","end":"2026-07-09T15:28:19.047383Z","steps":["trace[1431456326] 'agreement among raft nodes before linearized reading' (duration: 210.363159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:28:23.409494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.027823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:28:23.409605Z","caller":"traceutil/trace.go:171","msg":"trace[399808063] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"163.943578ms","start":"2026-07-09T15:28:23.245648Z","end":"2026-07-09T15:28:23.409592Z","steps":["trace[399808063] 'process raft request' (duration: 163.912198ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:28:23.409744Z","caller":"traceutil/trace.go:171","msg":"trace[1340787787] linearizableReadLoop","detail":"{readStateIndex:1234; appliedIndex:1233; }","duration":"280.515319ms","start":"2026-07-09T15:28:23.129222Z","end":"2026-07-09T15:28:23.409738Z","steps":["trace[1340787787] 'read index received' (duration: 147.201261ms)","trace[1340787787] 'applied index is now lower than readState.Index' (duration: 133.313158ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:28:23.409792Z","caller":"traceutil/trace.go:171","msg":"trace[329004679] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"305.566023ms","start":"2026-07-09T15:28:23.104177Z","end":"2026-07-09T15:28:23.409743Z","steps":["trace[329004679] 'process raft request' (duration: 172.229135ms)","trace[329004679] 'compare' (duration: 132.945072ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:28:23.40996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.741932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"warn","ts":"2026-07-09T15:28:23.409973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:28:23.104163Z","time spent":"305.724916ms","remote":"127.0.0.1:38326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T15:28:23.409992Z","caller":"traceutil/trace.go:171","msg":"trace[227356094] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1184; }","duration":"280.774302ms","start":"2026-07-09T15:28:23.129207Z","end":"2026-07-09T15:28:23.409982Z","steps":["trace[227356094] 'agreement among raft nodes before linearized reading' (duration: 280.60029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:28:23.932506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.437491ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:28:23.932598Z","caller":"traceutil/trace.go:171","msg":"trace[56998574] linearizableReadLoop","detail":"{readStateIndex:1236; appliedIndex:1235; }","duration":"522.82119ms","start":"2026-07-09T15:28:23.409763Z","end":"2026-07-09T15:28:23.932584Z","steps":["trace[56998574] 'read index received' (duration: 148.193256ms)","trace[56998574] 'applied index is now lower than readState.Index' (duration: 374.626734ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:28:23.932654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.909151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T15:28:23.932674Z","caller":"traceutil/trace.go:171","msg":"trace[1242498044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1185; }","duration":"772.969221ms","start":"2026-07-09T15:28:23.159697Z","end":"2026-07-09T15:28:23.932667Z","steps":["trace[1242498044] 'agreement among raft nodes before linearized reading' (duration: 772.91993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:28:23.932695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:28:23.159679Z","time spent":"773.011272ms","remote":"127.0.0.1:38066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T15:28:23.932752Z","caller":"traceutil/trace.go:171","msg":"trace[1183163417] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"570.106276ms","start":"2026-07-09T15:28:23.362617Z","end":"2026-07-09T15:28:23.932723Z","steps":["trace[1183163417] 'process raft request' (duration: 195.35857ms)","trace[1183163417] 'compare' (duration: 374.049635ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:28:23.93289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:28:23.362587Z","time spent":"570.227627ms","remote":"127.0.0.1:38326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:28:25.504125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.10798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:28:25.504244Z","caller":"traceutil/trace.go:171","msg":"trace[596596209] linearizableReadLoop","detail":"{readStateIndex:1242; appliedIndex:1241; }","duration":"246.358132ms","start":"2026-07-09T15:28:25.257866Z","end":"2026-07-09T15:28:25.504224Z","steps":["trace[596596209] 'read index received' (duration: 38.871µs)","trace[596596209] 'applied index is now lower than readState.Index' (duration: 246.314711ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:28:25.504301Z","caller":"traceutil/trace.go:171","msg":"trace[542174042] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"341.454824ms","start":"2026-07-09T15:28:25.162823Z","end":"2026-07-09T15:28:25.504278Z","steps":["trace[542174042] 'process raft request' (duration: 30.1029ms)","trace[542174042] 'compare' (duration: 310.990988ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:28:25.5044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:28:25.162807Z","time spent":"341.544755ms","remote":"127.0.0.1:38326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:28:25.505487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.619211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050264"} {"level":"info","ts":"2026-07-09T15:28:25.505604Z","caller":"traceutil/trace.go:171","msg":"trace[1955351564] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:1190; }","duration":"247.740733ms","start":"2026-07-09T15:28:25.257851Z","end":"2026-07-09T15:28:25.505591Z","steps":["trace[1955351564] 'agreement among raft nodes before linearized reading' (duration: 246.454933ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:28:25.695572Z","caller":"traceutil/trace.go:171","msg":"trace[1245050669] linearizableReadLoop","detail":"{readStateIndex:1243; appliedIndex:1242; }","duration":"191.26966ms","start":"2026-07-09T15:28:25.504277Z","end":"2026-07-09T15:28:25.695546Z","steps":["trace[1245050669] 'read index received' (duration: 113.479292ms)","trace[1245050669] 'applied index is now lower than readState.Index' (duration: 77.789248ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:28:25.695816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.790623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-09T15:28:25.695877Z","caller":"traceutil/trace.go:171","msg":"trace[242438184] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1191; }","duration":"281.901734ms","start":"2026-07-09T15:28:25.41396Z","end":"2026-07-09T15:28:25.695862Z","steps":["trace[242438184] 'agreement among raft nodes before linearized reading' (duration: 281.682111ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:28:25.695929Z","caller":"traceutil/trace.go:171","msg":"trace[257466229] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"280.616835ms","start":"2026-07-09T15:28:25.415289Z","end":"2026-07-09T15:28:25.695905Z","steps":["trace[257466229] 'process raft request' (duration: 202.520372ms)","trace[257466229] 'compare' (duration: 77.610595ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:28:26.135033Z","caller":"traceutil/trace.go:171","msg":"trace[64920240] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"128.599278ms","start":"2026-07-09T15:28:26.006409Z","end":"2026-07-09T15:28:26.135008Z","steps":["trace[64920240] 'process raft request' (duration: 128.413255ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:28:31.86254Z","caller":"traceutil/trace.go:171","msg":"trace[55196900] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1262; }","duration":"105.397132ms","start":"2026-07-09T15:28:31.757114Z","end":"2026-07-09T15:28:31.862511Z","steps":["trace[55196900] 'read index received' (duration: 33.279029ms)","trace[55196900] 'applied index is now lower than readState.Index' (duration: 72.110253ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:28:31.86256Z","caller":"traceutil/trace.go:171","msg":"trace[719424295] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"140.584422ms","start":"2026-07-09T15:28:31.72194Z","end":"2026-07-09T15:28:31.862524Z","steps":["trace[719424295] 'process raft request' (duration: 68.444158ms)","trace[719424295] 'compare' (duration: 71.835489ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:28:31.862748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.615035ms","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-09T15:28:31.862811Z","caller":"traceutil/trace.go:171","msg":"trace[1967446466] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1210; }","duration":"105.730018ms","start":"2026-07-09T15:28:31.757064Z","end":"2026-07-09T15:28:31.862794Z","steps":["trace[1967446466] 'agreement among raft nodes before linearized reading' (duration: 105.506824ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:28:36.137448Z","caller":"traceutil/trace.go:171","msg":"trace[417571185] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"152.088495ms","start":"2026-07-09T15:28:35.98534Z","end":"2026-07-09T15:28:36.137428Z","steps":["trace[417571185] 'process raft request' (duration: 151.971163ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:28:37.795248Z","caller":"traceutil/trace.go:171","msg":"trace[461684514] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"225.541114ms","start":"2026-07-09T15:28:37.56967Z","end":"2026-07-09T15:28:37.795211Z","steps":["trace[461684514] 'process raft request' (duration: 225.227719ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:28:38.009776Z","caller":"traceutil/trace.go:171","msg":"trace[264155853] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"128.851404ms","start":"2026-07-09T15:28:37.880899Z","end":"2026-07-09T15:28:38.00975Z","steps":["trace[264155853] 'process raft request' (duration: 128.530749ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:28:38.009801Z","caller":"traceutil/trace.go:171","msg":"trace[371239917] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"160.243248ms","start":"2026-07-09T15:28:37.849529Z","end":"2026-07-09T15:28:38.009772Z","steps":["trace[371239917] 'process raft request' (duration: 102.057158ms)","trace[371239917] 'compare' (duration: 57.579871ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:28:38.129455Z","caller":"traceutil/trace.go:171","msg":"trace[135194713] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1293; }","duration":"107.591516ms","start":"2026-07-09T15:28:38.021764Z","end":"2026-07-09T15:28:38.129355Z","steps":["trace[135194713] 'read index received' (duration: 93.735352ms)","trace[135194713] 'applied index is now lower than readState.Index' (duration: 13.852744ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:28:38.12971Z","caller":"traceutil/trace.go:171","msg":"trace[230468201] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"108.192205ms","start":"2026-07-09T15:28:38.021492Z","end":"2026-07-09T15:28:38.129685Z","steps":["trace[230468201] 'process raft request' (duration: 93.988336ms)","trace[230468201] 'compare' (duration: 13.695462ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:28:38.129975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6150a7fd-a621-41b9-81c5-a1d44752cd8f\" ","response":"range_response_count:1 size:3578"} {"level":"info","ts":"2026-07-09T15:28:38.130096Z","caller":"traceutil/trace.go:171","msg":"trace[1178851337] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6150a7fd-a621-41b9-81c5-a1d44752cd8f; range_end:; response_count:1; response_revision:1239; }","duration":"108.344778ms","start":"2026-07-09T15:28:38.021735Z","end":"2026-07-09T15:28:38.130079Z","steps":["trace[1178851337] 'agreement among raft nodes before linearized reading' (duration: 107.839461ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:28:41.143019Z","caller":"traceutil/trace.go:171","msg":"trace[1769425040] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"143.773604ms","start":"2026-07-09T15:28:40.999206Z","end":"2026-07-09T15:28:41.14298Z","steps":["trace[1769425040] 'process raft request' (duration: 143.536221ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:28:42.46126Z","caller":"traceutil/trace.go:171","msg":"trace[308994681] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"108.87861ms","start":"2026-07-09T15:28:42.352353Z","end":"2026-07-09T15:28:42.461231Z","steps":["trace[308994681] 'process raft request' (duration: 108.608536ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:28:44.058361Z","caller":"traceutil/trace.go:171","msg":"trace[1650923157] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"193.693389ms","start":"2026-07-09T15:28:43.864638Z","end":"2026-07-09T15:28:44.058332Z","steps":["trace[1650923157] 'process raft request' (duration: 193.527087ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:28:44.367838Z","caller":"traceutil/trace.go:171","msg":"trace[1500222338] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"180.490774ms","start":"2026-07-09T15:28:44.187244Z","end":"2026-07-09T15:28:44.367735Z","steps":["trace[1500222338] 'process raft request' (duration: 158.662026ms)","trace[1500222338] 'compare' (duration: 21.700136ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:28:45.423449Z","caller":"traceutil/trace.go:171","msg":"trace[1432513366] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"129.302965ms","start":"2026-07-09T15:28:45.29412Z","end":"2026-07-09T15:28:45.423423Z","steps":["trace[1432513366] 'process raft request' (duration: 127.653591ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:28:47.597101Z","caller":"traceutil/trace.go:171","msg":"trace[1776230467] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1349; }","duration":"130.902031ms","start":"2026-07-09T15:28:47.466174Z","end":"2026-07-09T15:28:47.597076Z","steps":["trace[1776230467] 'read index received' (duration: 130.673808ms)","trace[1776230467] 'applied index is now lower than readState.Index' (duration: 227.123µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:28:47.597137Z","caller":"traceutil/trace.go:171","msg":"trace[1817782575] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"159.835118ms","start":"2026-07-09T15:28:47.437277Z","end":"2026-07-09T15:28:47.597112Z","steps":["trace[1817782575] 'process raft request' (duration: 159.657976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:28:47.597293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.102514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:34953"} {"level":"info","ts":"2026-07-09T15:28:47.59732Z","caller":"traceutil/trace.go:171","msg":"trace[117807774] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1292; }","duration":"131.171124ms","start":"2026-07-09T15:28:47.466142Z","end":"2026-07-09T15:28:47.597313Z","steps":["trace[117807774] 'agreement among raft nodes before linearized reading' (duration: 131.038892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:28:47.597381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.973592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-09T15:28:47.597455Z","caller":"traceutil/trace.go:171","msg":"trace[404990153] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1292; }","duration":"131.080283ms","start":"2026-07-09T15:28:47.466358Z","end":"2026-07-09T15:28:47.597439Z","steps":["trace[404990153] 'agreement among raft nodes before linearized reading' (duration: 130.942161ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:28:48.885123Z","caller":"traceutil/trace.go:171","msg":"trace[508670801] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"118.411837ms","start":"2026-07-09T15:28:48.766678Z","end":"2026-07-09T15:28:48.88509Z","steps":["trace[508670801] 'process raft request' (duration: 53.057634ms)","trace[508670801] 'compare' (duration: 65.226291ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:28:50.370578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.196821ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T15:28:50.370696Z","caller":"traceutil/trace.go:171","msg":"trace[1751841419] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"186.490836ms","start":"2026-07-09T15:28:50.184183Z","end":"2026-07-09T15:28:50.370674Z","steps":["trace[1751841419] 'process raft request' (duration: 67.093462ms)","trace[1751841419] 'compare' (duration: 118.950807ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:28:50.531731Z","caller":"traceutil/trace.go:171","msg":"trace[1061751244] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1364; }","duration":"101.362465ms","start":"2026-07-09T15:28:50.430344Z","end":"2026-07-09T15:28:50.531707Z","steps":["trace[1061751244] 'read index received' (duration: 100.901148ms)","trace[1061751244] 'applied index is now lower than readState.Index' (duration: 460.217µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:28:50.531804Z","caller":"traceutil/trace.go:171","msg":"trace[264919451] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"115.742441ms","start":"2026-07-09T15:28:50.416033Z","end":"2026-07-09T15:28:50.531776Z","steps":["trace[264919451] 'process raft request' (duration: 115.165512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:28:50.531865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.510367ms","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-09T15:28:50.53192Z","caller":"traceutil/trace.go:171","msg":"trace[2002976817] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1306; }","duration":"101.578368ms","start":"2026-07-09T15:28:50.43033Z","end":"2026-07-09T15:28:50.531908Z","steps":["trace[2002976817] 'agreement among raft nodes before linearized reading' (duration: 101.459126ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:28:54.741535Z","caller":"traceutil/trace.go:171","msg":"trace[1081035153] linearizableReadLoop","detail":"{readStateIndex:1378; appliedIndex:1377; }","duration":"194.577969ms","start":"2026-07-09T15:28:54.546934Z","end":"2026-07-09T15:28:54.741512Z","steps":["trace[1081035153] 'read index received' (duration: 157.792156ms)","trace[1081035153] 'applied index is now lower than readState.Index' (duration: 36.784943ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T15:28:54.741753Z","caller":"traceutil/trace.go:171","msg":"trace[672046042] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"356.527645ms","start":"2026-07-09T15:28:54.385209Z","end":"2026-07-09T15:28:54.741737Z","steps":["trace[672046042] 'process raft request' (duration: 319.423257ms)","trace[672046042] 'compare' (duration: 36.766953ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:28:54.742081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T15:28:54.385184Z","time spent":"356.819428ms","remote":"127.0.0.1:38326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T15:28:54.74207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.124867ms","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-09T15:28:54.742295Z","caller":"traceutil/trace.go:171","msg":"trace[1303775640] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1319; }","duration":"195.404861ms","start":"2026-07-09T15:28:54.546871Z","end":"2026-07-09T15:28:54.742276Z","steps":["trace[1303775640] 'agreement among raft nodes before linearized reading' (duration: 195.017436ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:28:54.79732Z","caller":"traceutil/trace.go:171","msg":"trace[742537825] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"231.352652ms","start":"2026-07-09T15:28:54.565944Z","end":"2026-07-09T15:28:54.797297Z","steps":["trace[742537825] 'process raft request' (duration: 231.149109ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:28:54.797477Z","caller":"traceutil/trace.go:171","msg":"trace[19263061] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"222.868652ms","start":"2026-07-09T15:28:54.574579Z","end":"2026-07-09T15:28:54.797448Z","steps":["trace[19263061] 'process raft request' (duration: 222.664829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:28:54.798071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.198378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-09T15:28:54.79814Z","caller":"traceutil/trace.go:171","msg":"trace[1479282702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1321; }","duration":"221.29632ms","start":"2026-07-09T15:28:54.576827Z","end":"2026-07-09T15:28:54.798123Z","steps":["trace[1479282702] 'agreement among raft nodes before linearized reading' (duration: 220.56819ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:28:59.591392Z","caller":"traceutil/trace.go:171","msg":"trace[469195404] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1402; }","duration":"166.00283ms","start":"2026-07-09T15:28:59.425365Z","end":"2026-07-09T15:28:59.591368Z","steps":["trace[469195404] 'read index received' (duration: 10.344106ms)","trace[469195404] 'applied index is now lower than readState.Index' (duration: 155.657644ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T15:28:59.591652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.89264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-07-09T15:28:59.591692Z","caller":"traceutil/trace.go:171","msg":"trace[1326570821] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1342; }","duration":"153.97042ms","start":"2026-07-09T15:28:59.437712Z","end":"2026-07-09T15:28:59.591683Z","steps":["trace[1326570821] 'agreement among raft nodes before linearized reading' (duration: 153.797428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T15:28:59.591698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.330204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-07-09T15:28:59.59175Z","caller":"traceutil/trace.go:171","msg":"trace[701888896] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1342; }","duration":"166.408446ms","start":"2026-07-09T15:28:59.425326Z","end":"2026-07-09T15:28:59.591734Z","steps":["trace[701888896] 'agreement among raft nodes before linearized reading' (duration: 166.121282ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T15:28:59.59184Z","caller":"traceutil/trace.go:171","msg":"trace[732648604] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"211.623963ms","start":"2026-07-09T15:28:59.379798Z","end":"2026-07-09T15:28:59.591422Z","steps":["trace[732648604] 'process raft request' (duration: 186.968986ms)","trace[732648604] 'compare' (duration: 24.498945ms)"],"step_count":2}