{"level":"warn","ts":"2026-07-09T23:52:28.760248Z","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-09T23:52:28.760353Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.77:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.77:2380","--initial-cluster=instance=https://199.204.45.77:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.77:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.77: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-09T23:52:28.76043Z","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-09T23:52:28.760442Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-07-09T23:52:28.760464Z","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-09T23:52:28.761323Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"]} {"level":"info","ts":"2026-07-09T23:52:28.761432Z","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.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.77: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-09T23:52:28.769248Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.540571ms"} {"level":"info","ts":"2026-07-09T23:52:28.779715Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9133559b3198d4ec","cluster-id":"60f1aee33b0786f5"} {"level":"info","ts":"2026-07-09T23:52:28.779778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=()"} {"level":"info","ts":"2026-07-09T23:52:28.779794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 0"} {"level":"info","ts":"2026-07-09T23:52:28.779804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9133559b3198d4ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-09T23:52:28.779815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 1"} {"level":"info","ts":"2026-07-09T23:52:28.779843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"warn","ts":"2026-07-09T23:52:28.807333Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-09T23:52:28.810551Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-09T23:52:28.812513Z","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-09T23:52:28.816648Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9133559b3198d4ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-09T23:52:28.822624Z","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-09T23:52:28.822785Z","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-09T23:52:28.822796Z","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-09T23:52:28.822908Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9133559b3198d4ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-09T23:52:28.825017Z","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-09T23:52:28.825237Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-07-09T23:52:28.825246Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-07-09T23:52:28.825479Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9133559b3198d4ec","initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-09T23:52:28.825504Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-09T23:52:28.828767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"info","ts":"2026-07-09T23:52:28.828881Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","added-peer-id":"9133559b3198d4ec","added-peer-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-07-09T23:52:29.381028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec is starting a new election at term 1"} {"level":"info","ts":"2026-07-09T23:52:29.381104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became pre-candidate at term 1"} {"level":"info","ts":"2026-07-09T23:52:29.38113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgPreVoteResp from 9133559b3198d4ec at term 1"} {"level":"info","ts":"2026-07-09T23:52:29.381153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became candidate at term 2"} {"level":"info","ts":"2026-07-09T23:52:29.381168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgVoteResp from 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-07-09T23:52:29.38119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became leader at term 2"} {"level":"info","ts":"2026-07-09T23:52:29.381204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9133559b3198d4ec elected leader 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-07-09T23:52:29.637898Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T23:52:30.03078Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T23:52:30.030772Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9133559b3198d4ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.77:2379]}","request-path":"/0/members/9133559b3198d4ec/attributes","cluster-id":"60f1aee33b0786f5","publish-timeout":"7s"} {"level":"info","ts":"2026-07-09T23:52:30.030809Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T23:52:30.031123Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-09T23:52:30.031229Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-09T23:52:30.034382Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.77:2379"} {"level":"info","ts":"2026-07-09T23:52:30.034395Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-09T23:52:30.144181Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T23:52:30.144369Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T23:52:30.144487Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-07-09T23:52:30.500333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.105925ms","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-09T23:52:30.500388Z","caller":"traceutil/trace.go:171","msg":"trace[901594359] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"129.193456ms","start":"2026-07-09T23:52:30.371184Z","end":"2026-07-09T23:52:30.500377Z","steps":["trace[901594359] 'count revisions from in-memory index tree' (duration: 129.031093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:30.500503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.414235ms","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":"info","ts":"2026-07-09T23:52:30.500523Z","caller":"traceutil/trace.go:171","msg":"trace[1122171407] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"115.454426ms","start":"2026-07-09T23:52:30.385063Z","end":"2026-07-09T23:52:30.500518Z","steps":["trace[1122171407] 'range keys from in-memory index tree' (duration: 115.362044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:30.500603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.436916ms","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-09T23:52:30.500623Z","caller":"traceutil/trace.go:171","msg":"trace[1856180831] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"115.477316ms","start":"2026-07-09T23:52:30.385139Z","end":"2026-07-09T23:52:30.500616Z","steps":["trace[1856180831] 'count revisions from in-memory index tree' (duration: 115.377205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:30.500305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.186526ms","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":"warn","ts":"2026-07-09T23:52:30.500759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.303324ms","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-09T23:52:30.500789Z","caller":"traceutil/trace.go:171","msg":"trace[1526092029] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"217.350566ms","start":"2026-07-09T23:52:30.283426Z","end":"2026-07-09T23:52:30.500776Z","steps":["trace[1526092029] 'range keys from in-memory index tree' (duration: 217.266714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:30.500933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.358005ms","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-09T23:52:30.500957Z","caller":"traceutil/trace.go:171","msg":"trace[55345460] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"217.412467ms","start":"2026-07-09T23:52:30.283538Z","end":"2026-07-09T23:52:30.500951Z","steps":["trace[55345460] 'count revisions from in-memory index tree' (duration: 217.285524ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:30.501024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.087651ms","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":"warn","ts":"2026-07-09T23:52:30.501035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.13709ms","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-09T23:52:30.501047Z","caller":"traceutil/trace.go:171","msg":"trace[568600267] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"106.124451ms","start":"2026-07-09T23:52:30.394917Z","end":"2026-07-09T23:52:30.501042Z","steps":["trace[568600267] 'count revisions from in-memory index tree' (duration: 106.04318ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:30.501059Z","caller":"traceutil/trace.go:171","msg":"trace[1693656861] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"207.172661ms","start":"2026-07-09T23:52:30.29388Z","end":"2026-07-09T23:52:30.501053Z","steps":["trace[1693656861] 'range keys from in-memory index tree' (duration: 207.10495ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:30.501052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.942997ms","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":"warn","ts":"2026-07-09T23:52:30.501164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.589291ms","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-09T23:52:30.500762Z","caller":"traceutil/trace.go:171","msg":"trace[1513301992] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"129.736869ms","start":"2026-07-09T23:52:30.371019Z","end":"2026-07-09T23:52:30.500756Z","steps":["trace[1513301992] 'range keys from in-memory index tree' (duration: 129.131185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:30.501186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.675223ms","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-09T23:52:30.501233Z","caller":"traceutil/trace.go:171","msg":"trace[1411749949] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"155.757814ms","start":"2026-07-09T23:52:30.345462Z","end":"2026-07-09T23:52:30.501219Z","steps":["trace[1411749949] 'range keys from in-memory index tree' (duration: 155.55992ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:30.500732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.284615ms","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-09T23:52:30.501216Z","caller":"traceutil/trace.go:171","msg":"trace[1391236326] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"106.240784ms","start":"2026-07-09T23:52:30.394951Z","end":"2026-07-09T23:52:30.501192Z","steps":["trace[1391236326] 'range keys from in-memory index tree' (duration: 105.825664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:30.501252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.242016ms","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-09T23:52:30.501318Z","caller":"traceutil/trace.go:171","msg":"trace[2144065453] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"141.330288ms","start":"2026-07-09T23:52:30.35997Z","end":"2026-07-09T23:52:30.5013Z","steps":["trace[2144065453] 'range keys from in-memory index tree' (duration: 141.194244ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:30.501281Z","caller":"traceutil/trace.go:171","msg":"trace[1052913826] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"227.860918ms","start":"2026-07-09T23:52:30.273413Z","end":"2026-07-09T23:52:30.501274Z","steps":["trace[1052913826] 'count revisions from in-memory index tree' (duration: 227.222533ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:30.501389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.416296ms","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-09T23:52:30.501398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.606255ms","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-09T23:52:30.501424Z","caller":"traceutil/trace.go:171","msg":"trace[1373667368] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"197.448387ms","start":"2026-07-09T23:52:30.303954Z","end":"2026-07-09T23:52:30.501403Z","steps":["trace[1373667368] 'range keys from in-memory index tree' (duration: 197.375645ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:30.501425Z","caller":"traceutil/trace.go:171","msg":"trace[1157595260] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"141.645425ms","start":"2026-07-09T23:52:30.359768Z","end":"2026-07-09T23:52:30.501414Z","steps":["trace[1157595260] 'count revisions from in-memory index tree' (duration: 141.506562ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:30.501189Z","caller":"traceutil/trace.go:171","msg":"trace[47675870] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"155.634202ms","start":"2026-07-09T23:52:30.345548Z","end":"2026-07-09T23:52:30.501182Z","steps":["trace[47675870] 'count revisions from in-memory index tree' (duration: 155.54153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:30.501356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.357486ms","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":"warn","ts":"2026-07-09T23:52:30.501527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.961361ms","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":"info","ts":"2026-07-09T23:52:30.501536Z","caller":"traceutil/trace.go:171","msg":"trace[710867151] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"207.556741ms","start":"2026-07-09T23:52:30.293967Z","end":"2026-07-09T23:52:30.501524Z","steps":["trace[710867151] 'count revisions from in-memory index tree' (duration: 207.292874ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:30.501546Z","caller":"traceutil/trace.go:171","msg":"trace[1274849151] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"170.990371ms","start":"2026-07-09T23:52:30.33055Z","end":"2026-07-09T23:52:30.50154Z","steps":["trace[1274849151] 'range keys from in-memory index tree' (duration: 170.9238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:30.50155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.722575ms","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-09T23:52:30.501573Z","caller":"traceutil/trace.go:171","msg":"trace[1218732529] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"170.774726ms","start":"2026-07-09T23:52:30.330791Z","end":"2026-07-09T23:52:30.501566Z","steps":["trace[1218732529] 'count revisions from in-memory index tree' (duration: 170.682594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:30.501761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.706541ms","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-09T23:52:30.501783Z","caller":"traceutil/trace.go:171","msg":"trace[872442754] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"184.768333ms","start":"2026-07-09T23:52:30.317009Z","end":"2026-07-09T23:52:30.501777Z","steps":["trace[872442754] 'range keys from in-memory index tree' (duration: 184.6653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:30.50187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.773995ms","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-09T23:52:30.501893Z","caller":"traceutil/trace.go:171","msg":"trace[830939056] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"197.803585ms","start":"2026-07-09T23:52:30.304081Z","end":"2026-07-09T23:52:30.501884Z","steps":["trace[830939056] 'count revisions from in-memory index tree' (duration: 197.740745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:30.501924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.804313ms","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":"info","ts":"2026-07-09T23:52:30.501959Z","caller":"traceutil/trace.go:171","msg":"trace[1434105249] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"184.864495ms","start":"2026-07-09T23:52:30.317084Z","end":"2026-07-09T23:52:30.501948Z","steps":["trace[1434105249] 'count revisions from in-memory index tree' (duration: 184.734022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:38.656253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.193916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-07-09T23:52:38.656373Z","caller":"traceutil/trace.go:171","msg":"trace[536222694] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:330; }","duration":"124.36372ms","start":"2026-07-09T23:52:38.53199Z","end":"2026-07-09T23:52:38.656354Z","steps":["trace[536222694] 'range keys from in-memory index tree' (duration: 124.111544ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:40.309069Z","caller":"traceutil/trace.go:171","msg":"trace[537306081] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"101.928091ms","start":"2026-07-09T23:52:40.207116Z","end":"2026-07-09T23:52:40.309044Z","steps":["trace[537306081] 'process raft request' (duration: 36.19112ms)","trace[537306081] 'compare' (duration: 65.451064ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:52:40.418893Z","caller":"traceutil/trace.go:171","msg":"trace[593380100] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"104.868512ms","start":"2026-07-09T23:52:40.313993Z","end":"2026-07-09T23:52:40.418861Z","steps":["trace[593380100] 'process raft request' (duration: 98.052338ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:40.419037Z","caller":"traceutil/trace.go:171","msg":"trace[360363925] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"104.055822ms","start":"2026-07-09T23:52:40.314971Z","end":"2026-07-09T23:52:40.419027Z","steps":["trace[360363925] 'process raft request' (duration: 103.815366ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:40.419371Z","caller":"traceutil/trace.go:171","msg":"trace[1369242523] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"103.274393ms","start":"2026-07-09T23:52:40.316082Z","end":"2026-07-09T23:52:40.419357Z","steps":["trace[1369242523] 'process raft request' (duration: 102.7446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:56.358418Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813167701815039,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T23:52:56.463519Z","caller":"traceutil/trace.go:171","msg":"trace[1321150182] linearizableReadLoop","detail":"{readStateIndex:483; appliedIndex:482; }","duration":"605.449877ms","start":"2026-07-09T23:52:55.858044Z","end":"2026-07-09T23:52:56.463494Z","steps":["trace[1321150182] 'read index received' (duration: 605.216791ms)","trace[1321150182] 'applied index is now lower than readState.Index' (duration: 231.766µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:52:56.463554Z","caller":"traceutil/trace.go:171","msg":"trace[758498186] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"613.45908ms","start":"2026-07-09T23:52:55.850076Z","end":"2026-07-09T23:52:56.463535Z","steps":["trace[758498186] 'process raft request' (duration: 613.267695ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:56.463631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.581401ms","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-09T23:52:56.46366Z","caller":"traceutil/trace.go:171","msg":"trace[1226312351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:470; }","duration":"605.648851ms","start":"2026-07-09T23:52:55.858003Z","end":"2026-07-09T23:52:56.463652Z","steps":["trace[1226312351] 'agreement among raft nodes before linearized reading' (duration: 605.58639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:56.463682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:55.857989Z","time spent":"605.687883ms","remote":"127.0.0.1:35880","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-09T23:52:56.464374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:55.850056Z","time spent":"613.588232ms","remote":"127.0.0.1:35880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:52:56.568316Z","caller":"traceutil/trace.go:171","msg":"trace[1725068485] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"100.20152ms","start":"2026-07-09T23:52:56.468094Z","end":"2026-07-09T23:52:56.568296Z","steps":["trace[1725068485] 'process raft request' (duration: 71.212193ms)","trace[1725068485] 'compare' (duration: 28.862874ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:53:08.762344Z","caller":"traceutil/trace.go:171","msg":"trace[514730465] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"620.949522ms","start":"2026-07-09T23:53:08.141369Z","end":"2026-07-09T23:53:08.762318Z","steps":["trace[514730465] 'process raft request' (duration: 620.834361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:08.762575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:08.141347Z","time spent":"621.157903ms","remote":"127.0.0.1:35648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:53:08.788844Z","caller":"traceutil/trace.go:171","msg":"trace[111380339] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"642.887262ms","start":"2026-07-09T23:53:08.145903Z","end":"2026-07-09T23:53:08.78879Z","steps":["trace[111380339] 'process raft request' (duration: 642.43538ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:53:08.788891Z","caller":"traceutil/trace.go:171","msg":"trace[620320046] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"635.423154ms","start":"2026-07-09T23:53:08.153435Z","end":"2026-07-09T23:53:08.788858Z","steps":["trace[620320046] 'process raft request' (duration: 635.234814ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:08.788998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:08.145885Z","time spent":"643.044123ms","remote":"127.0.0.1:35648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:53:08.789201Z","caller":"traceutil/trace.go:171","msg":"trace[843698115] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"619.120252ms","start":"2026-07-09T23:53:08.170066Z","end":"2026-07-09T23:53:08.789186Z","steps":["trace[843698115] 'process raft request' (duration: 618.68575ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:53:08.789248Z","caller":"traceutil/trace.go:171","msg":"trace[2064654617] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"611.914446ms","start":"2026-07-09T23:53:08.177298Z","end":"2026-07-09T23:53:08.789212Z","steps":["trace[2064654617] 'process raft request' (duration: 611.652125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:08.789282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:08.170046Z","time spent":"619.200142ms","remote":"127.0.0.1:35648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:53:08.789244Z","caller":"traceutil/trace.go:171","msg":"trace[1338149588] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"605.271892ms","start":"2026-07-09T23:53:08.183966Z","end":"2026-07-09T23:53:08.789238Z","steps":["trace[1338149588] 'process raft request' (duration: 605.025921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:08.789358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:08.17728Z","time spent":"612.028457ms","remote":"127.0.0.1:35648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T23:53:08.789207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:08.153419Z","time spent":"635.734936ms","remote":"127.0.0.1:35648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T23:53:08.789414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:08.183947Z","time spent":"605.448904ms","remote":"127.0.0.1:35648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:53:08.789483Z","caller":"traceutil/trace.go:171","msg":"trace[1631752230] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"615.071122ms","start":"2026-07-09T23:53:08.174033Z","end":"2026-07-09T23:53:08.789104Z","steps":["trace[1631752230] 'process raft request' (duration: 614.75251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:08.789547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:08.174011Z","time spent":"615.509394ms","remote":"127.0.0.1:35648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:53:08.789689Z","caller":"traceutil/trace.go:171","msg":"trace[313637567] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"604.496509ms","start":"2026-07-09T23:53:08.185182Z","end":"2026-07-09T23:53:08.789679Z","steps":["trace[313637567] 'process raft request' (duration: 603.839805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:08.789774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:08.185164Z","time spent":"604.553219ms","remote":"127.0.0.1:35648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:53:08.789849Z","caller":"traceutil/trace.go:171","msg":"trace[1591659308] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"594.942161ms","start":"2026-07-09T23:53:08.194872Z","end":"2026-07-09T23:53:08.789814Z","steps":["trace[1591659308] 'process raft request' (duration: 594.186037ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:53:08.789907Z","caller":"traceutil/trace.go:171","msg":"trace[1318228766] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"476.739289ms","start":"2026-07-09T23:53:08.313162Z","end":"2026-07-09T23:53:08.789901Z","steps":["trace[1318228766] 'process raft request' (duration: 476.605848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:08.789958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:08.194852Z","time spent":"595.056762ms","remote":"127.0.0.1:35648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:53:08.789878Z","caller":"traceutil/trace.go:171","msg":"trace[1603430109] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"586.207348ms","start":"2026-07-09T23:53:08.203662Z","end":"2026-07-09T23:53:08.789869Z","steps":["trace[1603430109] 'process raft request' (duration: 585.978126ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:08.790098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:08.203636Z","time spent":"586.422978ms","remote":"127.0.0.1:35648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T23:53:08.789978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:08.313137Z","time spent":"476.821949ms","remote":"127.0.0.1:35648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:53:08.789223Z","caller":"traceutil/trace.go:171","msg":"trace[1757256583] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"629.075243ms","start":"2026-07-09T23:53:08.16007Z","end":"2026-07-09T23:53:08.789145Z","steps":["trace[1757256583] 'process raft request' (duration: 628.654931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:08.790633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:08.160052Z","time spent":"630.42685ms","remote":"127.0.0.1:35648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:53:08.796614Z","caller":"traceutil/trace.go:171","msg":"trace[662219969] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:547; }","duration":"279.35744ms","start":"2026-07-09T23:53:08.517218Z","end":"2026-07-09T23:53:08.796576Z","steps":["trace[662219969] 'read index received' (duration: 245.104749ms)","trace[662219969] 'applied index is now lower than readState.Index' (duration: 34.251841ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:53:08.797049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.823452ms","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-09T23:53:08.797098Z","caller":"traceutil/trace.go:171","msg":"trace[679905166] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:545; }","duration":"279.917323ms","start":"2026-07-09T23:53:08.517172Z","end":"2026-07-09T23:53:08.79709Z","steps":["trace[679905166] 'agreement among raft nodes before linearized reading' (duration: 279.802902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:08.797262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.868772ms","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-09T23:53:08.797288Z","caller":"traceutil/trace.go:171","msg":"trace[777401117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:545; }","duration":"171.918892ms","start":"2026-07-09T23:53:08.625363Z","end":"2026-07-09T23:53:08.797282Z","steps":["trace[777401117] 'agreement among raft nodes before linearized reading' (duration: 171.871591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:08.797525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.474649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:53:08.797609Z","caller":"traceutil/trace.go:171","msg":"trace[419229057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:545; }","duration":"153.622819ms","start":"2026-07-09T23:53:08.643965Z","end":"2026-07-09T23:53:08.797588Z","steps":["trace[419229057] 'agreement among raft nodes before linearized reading' (duration: 153.489628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:15.321305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.713789ms","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-09T23:53:15.321373Z","caller":"traceutil/trace.go:171","msg":"trace[1161353539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"474.812139ms","start":"2026-07-09T23:53:14.846546Z","end":"2026-07-09T23:53:15.321358Z","steps":["trace[1161353539] 'range keys from in-memory index tree' (duration: 474.569928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:15.321402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:14.846533Z","time spent":"474.86271ms","remote":"127.0.0.1:35880","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-09T23:53:15.321442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.514903ms","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-09T23:53:15.321556Z","caller":"traceutil/trace.go:171","msg":"trace[391901888] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"485.644363ms","start":"2026-07-09T23:53:14.835884Z","end":"2026-07-09T23:53:15.321529Z","steps":["trace[391901888] 'range keys from in-memory index tree' (duration: 485.268172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:15.321612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:14.8358Z","time spent":"485.798525ms","remote":"127.0.0.1:35880","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-09T23:53:15.669231Z","caller":"traceutil/trace.go:171","msg":"trace[223971123] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"553.702364ms","start":"2026-07-09T23:53:15.115506Z","end":"2026-07-09T23:53:15.669208Z","steps":["trace[223971123] 'process raft request' (duration: 553.117161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:15.669376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:15.115488Z","time spent":"553.808635ms","remote":"127.0.0.1:35704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:53:15.687713Z","caller":"traceutil/trace.go:171","msg":"trace[1039753366] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"362.446206ms","start":"2026-07-09T23:53:15.325244Z","end":"2026-07-09T23:53:15.68769Z","steps":["trace[1039753366] 'process raft request' (duration: 362.293355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:15.687835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:15.325221Z","time spent":"362.551956ms","remote":"127.0.0.1:35880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:53:15.688155Z","caller":"traceutil/trace.go:171","msg":"trace[747043966] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:647; }","duration":"308.961708ms","start":"2026-07-09T23:53:15.379185Z","end":"2026-07-09T23:53:15.688146Z","steps":["trace[747043966] 'read index received' (duration: 289.461901ms)","trace[747043966] 'applied index is now lower than readState.Index' (duration: 19.499077ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:53:15.688263Z","caller":"traceutil/trace.go:171","msg":"trace[216179270] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"362.938479ms","start":"2026-07-09T23:53:15.325318Z","end":"2026-07-09T23:53:15.688257Z","steps":["trace[216179270] 'process raft request' (duration: 362.319006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:15.688308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:15.325287Z","time spent":"362.996949ms","remote":"127.0.0.1:35880","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-09T23:53:15.688421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.23504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fqfsm\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-09T23:53:15.688446Z","caller":"traceutil/trace.go:171","msg":"trace[943249258] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fqfsm; range_end:; response_count:1; response_revision:632; }","duration":"309.28111ms","start":"2026-07-09T23:53:15.379158Z","end":"2026-07-09T23:53:15.688439Z","steps":["trace[943249258] 'agreement among raft nodes before linearized reading' (duration: 309.196839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:15.688464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:15.379146Z","time spent":"309.31423ms","remote":"127.0.0.1:35786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-fqfsm\" "} {"level":"warn","ts":"2026-07-09T23:53:16.196917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.057088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:53:16.197084Z","caller":"traceutil/trace.go:171","msg":"trace[1790037878] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"489.312629ms","start":"2026-07-09T23:53:15.707747Z","end":"2026-07-09T23:53:16.197059Z","steps":["trace[1790037878] 'process raft request' (duration: 334.42141ms)","trace[1790037878] 'compare' (duration: 153.336001ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:53:16.197167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:15.707734Z","time spent":"489.39261ms","remote":"127.0.0.1:35880","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-09T23:53:16.218396Z","caller":"traceutil/trace.go:171","msg":"trace[32398763] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:650; }","duration":"407.878957ms","start":"2026-07-09T23:53:15.810497Z","end":"2026-07-09T23:53:16.218376Z","steps":["trace[32398763] 'read index received' (duration: 231.810721ms)","trace[32398763] 'applied index is now lower than readState.Index' (duration: 176.067486ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:53:16.218534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.025291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T23:53:16.218575Z","caller":"traceutil/trace.go:171","msg":"trace[1147224267] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"408.110613ms","start":"2026-07-09T23:53:15.810454Z","end":"2026-07-09T23:53:16.218565Z","steps":["trace[1147224267] 'agreement among raft nodes before linearized reading' (duration: 407.99372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:16.218612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:15.810434Z","time spent":"408.170614ms","remote":"127.0.0.1:35880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-09T23:53:16.218727Z","caller":"traceutil/trace.go:171","msg":"trace[556961819] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"491.482621ms","start":"2026-07-09T23:53:15.727219Z","end":"2026-07-09T23:53:16.218702Z","steps":["trace[556961819] 'process raft request' (duration: 491.116032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:16.218837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:15.727196Z","time spent":"491.590894ms","remote":"127.0.0.1:35916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:53:16.218592Z","caller":"traceutil/trace.go:171","msg":"trace[2145902399] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"504.366276ms","start":"2026-07-09T23:53:15.714051Z","end":"2026-07-09T23:53:16.218417Z","steps":["trace[2145902399] 'process raft request' (duration: 504.150001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:16.219135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:15.714014Z","time spent":"505.032043ms","remote":"127.0.0.1:35786","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-09T23:53:17.581864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187033453s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:53:17.581983Z","caller":"traceutil/trace.go:171","msg":"trace[1493795505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"1.187198447s","start":"2026-07-09T23:53:16.394754Z","end":"2026-07-09T23:53:17.581952Z","steps":["trace[1493795505] 'range keys from in-memory index tree' (duration: 1.186948621s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:17.582051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:16.394734Z","time spent":"1.18730273s","remote":"127.0.0.1:35586","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-09T23:53:17.582221Z","caller":"traceutil/trace.go:171","msg":"trace[576403393] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.131761981s","start":"2026-07-09T23:53:16.450427Z","end":"2026-07-09T23:53:17.582189Z","steps":["trace[576403393] 'process raft request' (duration: 1.119598023s)","trace[576403393] 'compare' (duration: 11.540712ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:53:17.583031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:16.450408Z","time spent":"1.131873404s","remote":"127.0.0.1:35880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:53:17.609242Z","caller":"traceutil/trace.go:171","msg":"trace[263802943] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"956.336441ms","start":"2026-07-09T23:53:16.652869Z","end":"2026-07-09T23:53:17.609206Z","steps":["trace[263802943] 'process raft request' (duration: 955.726996ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:17.60944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:16.652845Z","time spent":"956.496015ms","remote":"127.0.0.1:35704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:53:18.755958Z","caller":"traceutil/trace.go:171","msg":"trace[657222191] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"113.847424ms","start":"2026-07-09T23:53:18.64209Z","end":"2026-07-09T23:53:18.755937Z","steps":["trace[657222191] 'read index received' (duration: 113.149807ms)","trace[657222191] 'applied index is now lower than readState.Index' (duration: 696.657µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:53:18.75606Z","caller":"traceutil/trace.go:171","msg":"trace[399969464] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"149.380864ms","start":"2026-07-09T23:53:18.60663Z","end":"2026-07-09T23:53:18.756011Z","steps":["trace[399969464] 'process raft request' (duration: 148.894422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:18.756136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.020719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:53:18.75623Z","caller":"traceutil/trace.go:171","msg":"trace[70023779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"114.160352ms","start":"2026-07-09T23:53:18.642048Z","end":"2026-07-09T23:53:18.756209Z","steps":["trace[70023779] 'agreement among raft nodes before linearized reading' (duration: 113.975507ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:53:33.592776Z","caller":"traceutil/trace.go:171","msg":"trace[1229995133] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"108.944615ms","start":"2026-07-09T23:53:33.483798Z","end":"2026-07-09T23:53:33.592743Z","steps":["trace[1229995133] 'read index received' (duration: 108.797001ms)","trace[1229995133] 'applied index is now lower than readState.Index' (duration: 144.234µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:53:33.593032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.211161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-x7tx4\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-07-09T23:53:33.59311Z","caller":"traceutil/trace.go:171","msg":"trace[1655133910] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-x7tx4; range_end:; response_count:1; response_revision:809; }","duration":"109.335764ms","start":"2026-07-09T23:53:33.483753Z","end":"2026-07-09T23:53:33.593088Z","steps":["trace[1655133910] 'agreement among raft nodes before linearized reading' (duration: 109.118289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:34.199545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.112775ms","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-09T23:53:34.19963Z","caller":"traceutil/trace.go:171","msg":"trace[1475655420] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:809; }","duration":"402.211047ms","start":"2026-07-09T23:53:33.797406Z","end":"2026-07-09T23:53:34.199617Z","steps":["trace[1475655420] 'range keys from in-memory index tree' (duration: 402.097255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:34.199755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.968298ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:53:34.199809Z","caller":"traceutil/trace.go:171","msg":"trace[896534475] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"605.981272ms","start":"2026-07-09T23:53:33.593819Z","end":"2026-07-09T23:53:34.1998Z","steps":["trace[896534475] 'process raft request' (duration: 121.894262ms)","trace[896534475] 'compare' (duration: 483.723731ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:53:34.199852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:33.5938Z","time spent":"606.033783ms","remote":"127.0.0.1:35642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:53:34.355331Z","caller":"traceutil/trace.go:171","msg":"trace[1857147528] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:834; }","duration":"712.350913ms","start":"2026-07-09T23:53:33.642957Z","end":"2026-07-09T23:53:34.355308Z","steps":["trace[1857147528] 'read index received' (duration: 72.855622ms)","trace[1857147528] 'applied index is now lower than readState.Index' (duration: 639.494361ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:53:34.355745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.672061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:53:34.355739Z","caller":"traceutil/trace.go:171","msg":"trace[132576455] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"754.767081ms","start":"2026-07-09T23:53:33.600943Z","end":"2026-07-09T23:53:34.355711Z","steps":["trace[132576455] 'process raft request' (duration: 754.238578ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:53:34.355824Z","caller":"traceutil/trace.go:171","msg":"trace[1632129122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:811; }","duration":"712.894437ms","start":"2026-07-09T23:53:33.642912Z","end":"2026-07-09T23:53:34.355807Z","steps":["trace[1632129122] 'agreement among raft nodes before linearized reading' (duration: 712.649731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:34.355862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:33.642895Z","time spent":"712.959009ms","remote":"127.0.0.1:35586","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-09T23:53:34.355888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.356536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"warn","ts":"2026-07-09T23:53:34.355927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:33.600929Z","time spent":"754.920455ms","remote":"127.0.0.1:35786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:53:34.355959Z","caller":"traceutil/trace.go:171","msg":"trace[174886332] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:811; }","duration":"154.473919ms","start":"2026-07-09T23:53:34.201467Z","end":"2026-07-09T23:53:34.355941Z","steps":["trace[174886332] 'agreement among raft nodes before linearized reading' (duration: 154.329415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:34.355962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.318077ms","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-09T23:53:34.356032Z","caller":"traceutil/trace.go:171","msg":"trace[822198958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:811; }","duration":"574.42317ms","start":"2026-07-09T23:53:33.781591Z","end":"2026-07-09T23:53:34.356014Z","steps":["trace[822198958] 'agreement among raft nodes before linearized reading' (duration: 574.222885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:34.356074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:33.781583Z","time spent":"574.483431ms","remote":"127.0.0.1:35880","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-09T23:53:34.356383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.825528ms","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-09T23:53:34.356446Z","caller":"traceutil/trace.go:171","msg":"trace[1068796006] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:811; }","duration":"576.928351ms","start":"2026-07-09T23:53:33.779504Z","end":"2026-07-09T23:53:34.356433Z","steps":["trace[1068796006] 'agreement among raft nodes before linearized reading' (duration: 576.251684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:34.356479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:33.779484Z","time spent":"576.985903ms","remote":"127.0.0.1:35880","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-09T23:54:09.171898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.306306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:54:09.171969Z","caller":"traceutil/trace.go:171","msg":"trace[804273329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1024; }","duration":"529.418799ms","start":"2026-07-09T23:54:08.642534Z","end":"2026-07-09T23:54:09.171953Z","steps":["trace[804273329] 'range keys from in-memory index tree' (duration: 529.208123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:54:09.171996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:54:08.642518Z","time spent":"529.471451ms","remote":"127.0.0.1:35586","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-09T23:54:09.172153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.107305ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:54:09.172253Z","caller":"traceutil/trace.go:171","msg":"trace[669360781] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"403.95104ms","start":"2026-07-09T23:54:08.768281Z","end":"2026-07-09T23:54:09.172232Z","steps":["trace[669360781] 'process raft request' (duration: 28.684321ms)","trace[669360781] 'compare' (duration: 374.861679ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:54:09.172314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:54:08.768262Z","time spent":"404.023182ms","remote":"127.0.0.1:35880","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-09T23:54:09.866917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.262021ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:54:09.867002Z","caller":"traceutil/trace.go:171","msg":"trace[2054292113] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1058; }","duration":"223.991728ms","start":"2026-07-09T23:54:09.642997Z","end":"2026-07-09T23:54:09.866989Z","steps":["trace[2054292113] 'read index received' (duration: 31.761µs)","trace[2054292113] 'applied index is now lower than readState.Index' (duration: 223.959137ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:54:09.867049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.05164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:54:09.867078Z","caller":"traceutil/trace.go:171","msg":"trace[1014121757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1026; }","duration":"224.113341ms","start":"2026-07-09T23:54:09.642958Z","end":"2026-07-09T23:54:09.867071Z","steps":["trace[1014121757] 'agreement among raft nodes before linearized reading' (duration: 224.06533ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:54:09.867256Z","caller":"traceutil/trace.go:171","msg":"trace[146646511] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"597.061444ms","start":"2026-07-09T23:54:09.270185Z","end":"2026-07-09T23:54:09.867247Z","steps":["trace[146646511] 'process raft request' (duration: 355.377842ms)","trace[146646511] 'compare' (duration: 240.853391ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:54:09.867311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:54:09.270174Z","time spent":"597.109695ms","remote":"127.0.0.1:35880","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-09T23:54:31.917208Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813167701816442,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T23:54:32.066135Z","caller":"traceutil/trace.go:171","msg":"trace[609694258] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1133; }","duration":"649.505469ms","start":"2026-07-09T23:54:31.416602Z","end":"2026-07-09T23:54:32.066107Z","steps":["trace[609694258] 'read index received' (duration: 649.202362ms)","trace[609694258] 'applied index is now lower than readState.Index' (duration: 301.767µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:54:32.066182Z","caller":"traceutil/trace.go:171","msg":"trace[772824791] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"818.046169ms","start":"2026-07-09T23:54:31.248107Z","end":"2026-07-09T23:54:32.066153Z","steps":["trace[772824791] 'process raft request' (duration: 817.780663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:54:32.066317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.686743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T23:54:32.066387Z","caller":"traceutil/trace.go:171","msg":"trace[392655297] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1097; }","duration":"649.819087ms","start":"2026-07-09T23:54:31.416551Z","end":"2026-07-09T23:54:32.06637Z","steps":["trace[392655297] 'agreement among raft nodes before linearized reading' (duration: 649.680383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:54:32.066441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:54:31.41653Z","time spent":"649.895749ms","remote":"127.0.0.1:37300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-07-09T23:54:32.066539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:54:31.248079Z","time spent":"818.384897ms","remote":"127.0.0.1:35880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:54:32.066561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.4657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:54:32.0667Z","caller":"traceutil/trace.go:171","msg":"trace[14656344] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1097; }","duration":"199.652804ms","start":"2026-07-09T23:54:31.867032Z","end":"2026-07-09T23:54:32.066685Z","steps":["trace[14656344] 'agreement among raft nodes before linearized reading' (duration: 199.417769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:54:32.066799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.556638ms","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":"warn","ts":"2026-07-09T23:54:32.066808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.984652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T23:54:32.066845Z","caller":"traceutil/trace.go:171","msg":"trace[2037502128] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1097; }","duration":"105.63247ms","start":"2026-07-09T23:54:31.961198Z","end":"2026-07-09T23:54:32.066831Z","steps":["trace[2037502128] 'agreement among raft nodes before linearized reading' (duration: 105.23293ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:54:32.067214Z","caller":"traceutil/trace.go:171","msg":"trace[1107197360] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1097; }","duration":"518.416392ms","start":"2026-07-09T23:54:31.548671Z","end":"2026-07-09T23:54:32.067087Z","steps":["trace[1107197360] 'agreement among raft nodes before linearized reading' (duration: 517.683454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:54:32.067345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:54:31.548651Z","time spent":"518.673308ms","remote":"127.0.0.1:35648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-07-09T23:54:32.762227Z","caller":"traceutil/trace.go:171","msg":"trace[1791463182] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1134; }","duration":"118.15169ms","start":"2026-07-09T23:54:32.644045Z","end":"2026-07-09T23:54:32.762196Z","steps":["trace[1791463182] 'read index received' (duration: 118.040707ms)","trace[1791463182] 'applied index is now lower than readState.Index' (duration: 109.693µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:54:32.762351Z","caller":"traceutil/trace.go:171","msg":"trace[1673340734] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"691.37579ms","start":"2026-07-09T23:54:32.070943Z","end":"2026-07-09T23:54:32.762318Z","steps":["trace[1673340734] 'process raft request' (duration: 691.066803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:54:32.762546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.451727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T23:54:32.762717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:54:32.070921Z","time spent":"691.713078ms","remote":"127.0.0.1:35880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:54:32.76269Z","caller":"traceutil/trace.go:171","msg":"trace[1946935605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"118.643252ms","start":"2026-07-09T23:54:32.643995Z","end":"2026-07-09T23:54:32.762639Z","steps":["trace[1946935605] 'agreement among raft nodes before linearized reading' (duration: 118.347125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:54:52.762144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.657096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:54:52.762262Z","caller":"traceutil/trace.go:171","msg":"trace[667379059] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1214; }","duration":"119.78291ms","start":"2026-07-09T23:54:52.642429Z","end":"2026-07-09T23:54:52.762212Z","steps":["trace[667379059] 'range keys from in-memory index tree' (duration: 119.588895ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:55:07.097458Z","caller":"traceutil/trace.go:171","msg":"trace[207793081] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1316; }","duration":"126.658728ms","start":"2026-07-09T23:55:06.970774Z","end":"2026-07-09T23:55:07.097433Z","steps":["trace[207793081] 'read index received' (duration: 60.450878ms)","trace[207793081] 'applied index is now lower than readState.Index' (duration: 66.20647ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:55:07.097548Z","caller":"traceutil/trace.go:171","msg":"trace[2046295109] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"139.557876ms","start":"2026-07-09T23:55:06.95797Z","end":"2026-07-09T23:55:07.097528Z","steps":["trace[2046295109] 'process raft request' (duration: 122.854044ms)","trace[2046295109] 'compare' (duration: 16.502947ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:55:07.097878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.080769ms","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-09T23:55:07.097935Z","caller":"traceutil/trace.go:171","msg":"trace[932032054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1272; }","duration":"127.16244ms","start":"2026-07-09T23:55:06.970757Z","end":"2026-07-09T23:55:07.097919Z","steps":["trace[932032054] 'agreement among raft nodes before linearized reading' (duration: 126.823112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:07.099119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.601734ms","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-09T23:55:07.099165Z","caller":"traceutil/trace.go:171","msg":"trace[1218415580] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1272; }","duration":"121.685756ms","start":"2026-07-09T23:55:06.977466Z","end":"2026-07-09T23:55:07.099152Z","steps":["trace[1218415580] 'agreement among raft nodes before linearized reading' (duration: 120.321972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:07.803725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.539107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:55:07.80383Z","caller":"traceutil/trace.go:171","msg":"trace[1658559166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1275; }","duration":"159.697551ms","start":"2026-07-09T23:55:07.644117Z","end":"2026-07-09T23:55:07.803815Z","steps":["trace[1658559166] 'range keys from in-memory index tree' (duration: 159.427135ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:09.14512Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813167701816866,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T23:55:09.477526Z","caller":"traceutil/trace.go:171","msg":"trace[1187281135] linearizableReadLoop","detail":"{readStateIndex:1330; appliedIndex:1329; }","duration":"832.823394ms","start":"2026-07-09T23:55:08.644676Z","end":"2026-07-09T23:55:09.477499Z","steps":["trace[1187281135] 'read index received' (duration: 832.65152ms)","trace[1187281135] 'applied index is now lower than readState.Index' (duration: 170.944µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:55:09.477599Z","caller":"traceutil/trace.go:171","msg":"trace[1758252901] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"926.599853ms","start":"2026-07-09T23:55:08.550976Z","end":"2026-07-09T23:55:09.477575Z","steps":["trace[1758252901] 'process raft request' (duration: 926.373427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:09.477779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:08.550947Z","time spent":"926.736706ms","remote":"127.0.0.1:35880","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-09T23:55:09.477948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.259654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:55:09.478152Z","caller":"traceutil/trace.go:171","msg":"trace[661654020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1283; }","duration":"833.49872ms","start":"2026-07-09T23:55:08.644632Z","end":"2026-07-09T23:55:09.47813Z","steps":["trace[661654020] 'agreement among raft nodes before linearized reading' (duration: 833.003468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:09.478367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:08.644607Z","time spent":"833.737557ms","remote":"127.0.0.1:35586","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-09T23:55:09.843533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.797259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:55:09.844957Z","caller":"traceutil/trace.go:171","msg":"trace[920535663] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"586.601592ms","start":"2026-07-09T23:55:09.258041Z","end":"2026-07-09T23:55:09.844642Z","steps":["trace[920535663] 'process raft request' (duration: 452.605033ms)","trace[920535663] 'compare' (duration: 132.618315ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:55:09.845043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.231816ms","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-09T23:55:09.845033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.512225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T23:55:09.845086Z","caller":"traceutil/trace.go:171","msg":"trace[60892481] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1284; }","duration":"736.297718ms","start":"2026-07-09T23:55:09.108778Z","end":"2026-07-09T23:55:09.845076Z","steps":["trace[60892481] 'agreement among raft nodes before linearized reading' (duration: 735.937329ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:55:09.845113Z","caller":"traceutil/trace.go:171","msg":"trace[837863302] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1284; }","duration":"743.631898ms","start":"2026-07-09T23:55:09.101458Z","end":"2026-07-09T23:55:09.84509Z","steps":["trace[837863302] 'agreement among raft nodes before linearized reading' (duration: 743.227078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:09.845108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.440651ms","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-09T23:55:09.845126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:09.108772Z","time spent":"736.346589ms","remote":"127.0.0.1:35880","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-09T23:55:09.845126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:09.258013Z","time spent":"587.019093ms","remote":"127.0.0.1:35880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:55:09.845153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:09.101438Z","time spent":"743.70674ms","remote":"127.0.0.1:35880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T23:55:09.84518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.256454ms","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-09T23:55:09.845171Z","caller":"traceutil/trace.go:171","msg":"trace[827292765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1284; }","duration":"736.550873ms","start":"2026-07-09T23:55:09.108601Z","end":"2026-07-09T23:55:09.845152Z","steps":["trace[827292765] 'agreement among raft nodes before linearized reading' (duration: 736.356169ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:55:09.8452Z","caller":"traceutil/trace.go:171","msg":"trace[1354721171] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1284; }","duration":"887.298515ms","start":"2026-07-09T23:55:08.957895Z","end":"2026-07-09T23:55:09.845194Z","steps":["trace[1354721171] 'agreement among raft nodes before linearized reading' (duration: 887.254534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:09.845161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.120786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T23:55:09.845221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:09.108579Z","time spent":"736.628516ms","remote":"127.0.0.1:35880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-09T23:55:09.845264Z","caller":"traceutil/trace.go:171","msg":"trace[646787358] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1284; }","duration":"875.275559ms","start":"2026-07-09T23:55:08.969966Z","end":"2026-07-09T23:55:09.845241Z","steps":["trace[646787358] 'agreement among raft nodes before linearized reading' (duration: 875.081664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:09.845333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025768714s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-cfwfj\" ","response":"range_response_count:1 size:11057"} {"level":"warn","ts":"2026-07-09T23:55:09.845322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:08.969949Z","time spent":"875.358401ms","remote":"127.0.0.1:35904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-07-09T23:55:09.84535Z","caller":"traceutil/trace.go:171","msg":"trace[772183229] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-cfwfj; range_end:; response_count:1; response_revision:1284; }","duration":"1.025811825s","start":"2026-07-09T23:55:08.819533Z","end":"2026-07-09T23:55:09.845345Z","steps":["trace[772183229] 'agreement among raft nodes before linearized reading' (duration: 1.025764504s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:09.845366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:08.819516Z","time spent":"1.025847556s","remote":"127.0.0.1:35786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11080,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-cfwfj\" "} {"level":"warn","ts":"2026-07-09T23:55:09.845216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:08.95788Z","time spent":"887.333096ms","remote":"127.0.0.1:35880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T23:55:09.845125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.48007ms","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-09T23:55:09.845534Z","caller":"traceutil/trace.go:171","msg":"trace[1053193984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1284; }","duration":"809.92916ms","start":"2026-07-09T23:55:09.035587Z","end":"2026-07-09T23:55:09.845516Z","steps":["trace[1053193984] 'agreement among raft nodes before linearized reading' (duration: 809.477159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:09.845607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:09.035567Z","time spent":"810.005702ms","remote":"127.0.0.1:35880","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-09T23:55:09.844641Z","caller":"traceutil/trace.go:171","msg":"trace[807883205] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1330; }","duration":"367.013836ms","start":"2026-07-09T23:55:09.477603Z","end":"2026-07-09T23:55:09.844617Z","steps":["trace[807883205] 'read index received' (duration: 233.014047ms)","trace[807883205] 'applied index is now lower than readState.Index' (duration: 133.997189ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:55:09.84657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.911793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T23:55:09.84658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.611008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:55:09.846626Z","caller":"traceutil/trace.go:171","msg":"trace[1713125599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1284; }","duration":"366.966944ms","start":"2026-07-09T23:55:09.479641Z","end":"2026-07-09T23:55:09.846608Z","steps":["trace[1713125599] 'agreement among raft nodes before linearized reading' (duration: 366.849501ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:55:09.846653Z","caller":"traceutil/trace.go:171","msg":"trace[915889701] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1284; }","duration":"230.729021ms","start":"2026-07-09T23:55:09.615906Z","end":"2026-07-09T23:55:09.846635Z","steps":["trace[915889701] 'agreement among raft nodes before linearized reading' (duration: 230.608318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:09.846667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:09.479593Z","time spent":"367.064478ms","remote":"127.0.0.1:35596","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-09T23:55:10.497936Z","caller":"traceutil/trace.go:171","msg":"trace[1587149732] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"646.790034ms","start":"2026-07-09T23:55:09.851126Z","end":"2026-07-09T23:55:10.497916Z","steps":["trace[1587149732] 'process raft request' (duration: 646.484246ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:55:10.497938Z","caller":"traceutil/trace.go:171","msg":"trace[78439757] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"646.688032ms","start":"2026-07-09T23:55:09.851226Z","end":"2026-07-09T23:55:10.497914Z","steps":["trace[78439757] 'process raft request' (duration: 646.576359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:10.49806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:09.851112Z","time spent":"646.885797ms","remote":"127.0.0.1:35880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:55:10.498069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:09.851207Z","time spent":"646.798864ms","remote":"127.0.0.1:35880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:55:10.498303Z","caller":"traceutil/trace.go:171","msg":"trace[1402373885] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"646.877666ms","start":"2026-07-09T23:55:09.851408Z","end":"2026-07-09T23:55:10.498285Z","steps":["trace[1402373885] 'process raft request' (duration: 646.455955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:10.498388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:09.851389Z","time spent":"646.952238ms","remote":"127.0.0.1:35880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:55:10.49914Z","caller":"traceutil/trace.go:171","msg":"trace[1808697804] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1331; }","duration":"642.850947ms","start":"2026-07-09T23:55:09.85627Z","end":"2026-07-09T23:55:10.499121Z","steps":["trace[1808697804] 'read index received' (duration: 60.744536ms)","trace[1808697804] 'applied index is now lower than readState.Index' (duration: 582.105331ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:55:10.499265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.97401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:55:10.4993Z","caller":"traceutil/trace.go:171","msg":"trace[685264992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1290; }","duration":"643.050322ms","start":"2026-07-09T23:55:09.856241Z","end":"2026-07-09T23:55:10.499292Z","steps":["trace[685264992] 'agreement among raft nodes before linearized reading' (duration: 642.953679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:10.499324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:09.856231Z","time spent":"643.087713ms","remote":"127.0.0.1:35586","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-09T23:55:10.499356Z","caller":"traceutil/trace.go:171","msg":"trace[1110552455] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"647.669665ms","start":"2026-07-09T23:55:09.851654Z","end":"2026-07-09T23:55:10.499323Z","steps":["trace[1110552455] 'process raft request' (duration: 646.577698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:10.499526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:09.851648Z","time spent":"647.795569ms","remote":"127.0.0.1:35880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:55:10.499544Z","caller":"traceutil/trace.go:171","msg":"trace[407037772] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"641.475074ms","start":"2026-07-09T23:55:09.858059Z","end":"2026-07-09T23:55:10.499534Z","steps":["trace[407037772] 'process raft request' (duration: 640.956621ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:10.499608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:09.858031Z","time spent":"641.531634ms","remote":"127.0.0.1:35786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11371,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:55:10.735645Z","caller":"traceutil/trace.go:171","msg":"trace[1553208394] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"230.459714ms","start":"2026-07-09T23:55:10.50516Z","end":"2026-07-09T23:55:10.73562Z","steps":["trace[1553208394] 'process raft request' (duration: 230.29548ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:55:10.982918Z","caller":"traceutil/trace.go:171","msg":"trace[1956048564] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"477.66212ms","start":"2026-07-09T23:55:10.505236Z","end":"2026-07-09T23:55:10.982898Z","steps":["trace[1956048564] 'process raft request' (duration: 477.505076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:10.983041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:10.505222Z","time spent":"477.758473ms","remote":"127.0.0.1:35774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":914,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:55:10.983173Z","caller":"traceutil/trace.go:171","msg":"trace[446935201] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"474.126093ms","start":"2026-07-09T23:55:10.509019Z","end":"2026-07-09T23:55:10.983145Z","steps":["trace[446935201] 'process raft request' (duration: 473.838516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:10.983311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:10.509003Z","time spent":"474.237165ms","remote":"127.0.0.1:36046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:55:12.327519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.027144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:55:12.327601Z","caller":"traceutil/trace.go:171","msg":"trace[535681108] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1341; }","duration":"683.010296ms","start":"2026-07-09T23:55:11.644579Z","end":"2026-07-09T23:55:12.327589Z","steps":["trace[535681108] 'read index received' (duration: 229.720476ms)","trace[535681108] 'applied index is now lower than readState.Index' (duration: 453.28854ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:55:12.327776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.646056ms","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-09T23:55:12.32774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.148089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:55:12.327834Z","caller":"traceutil/trace.go:171","msg":"trace[1732615454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1295; }","duration":"683.278503ms","start":"2026-07-09T23:55:11.64454Z","end":"2026-07-09T23:55:12.327819Z","steps":["trace[1732615454] 'agreement among raft nodes before linearized reading' (duration: 683.078618ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:55:12.3278Z","caller":"traceutil/trace.go:171","msg":"trace[1801137629] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1295; }","duration":"407.715878ms","start":"2026-07-09T23:55:11.920078Z","end":"2026-07-09T23:55:12.327793Z","steps":["trace[1801137629] 'agreement among raft nodes before linearized reading' (duration: 407.582745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:12.327883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:11.644525Z","time spent":"683.349654ms","remote":"127.0.0.1:35586","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-09T23:55:12.327909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:11.920056Z","time spent":"407.845152ms","remote":"127.0.0.1:35880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-09T23:55:12.328015Z","caller":"traceutil/trace.go:171","msg":"trace[1053831364] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"720.9515ms","start":"2026-07-09T23:55:11.60666Z","end":"2026-07-09T23:55:12.327612Z","steps":["trace[1053831364] 'process raft request' (duration: 267.771422ms)","trace[1053831364] 'compare' (duration: 452.621074ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:55:12.328195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:11.606628Z","time spent":"721.500813ms","remote":"127.0.0.1:35690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T23:55:12.659704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.202571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:55:12.659798Z","caller":"traceutil/trace.go:171","msg":"trace[1482017734] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1342; }","duration":"157.117539ms","start":"2026-07-09T23:55:12.502665Z","end":"2026-07-09T23:55:12.659782Z","steps":["trace[1482017734] 'read index received' (duration: 33.921µs)","trace[1482017734] 'applied index is now lower than readState.Index' (duration: 157.082198ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:55:12.659951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.282963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-09T23:55:12.659972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.295783ms","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-09T23:55:12.659986Z","caller":"traceutil/trace.go:171","msg":"trace[1412476501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1296; }","duration":"157.350384ms","start":"2026-07-09T23:55:12.502626Z","end":"2026-07-09T23:55:12.659977Z","steps":["trace[1412476501] 'agreement among raft nodes before linearized reading' (duration: 157.238042ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:55:12.660015Z","caller":"traceutil/trace.go:171","msg":"trace[1833648895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1296; }","duration":"157.340074ms","start":"2026-07-09T23:55:12.502663Z","end":"2026-07-09T23:55:12.660003Z","steps":["trace[1833648895] 'agreement among raft nodes before linearized reading' (duration: 157.18961ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:55:12.659981Z","caller":"traceutil/trace.go:171","msg":"trace[515844513] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"326.491818ms","start":"2026-07-09T23:55:12.333466Z","end":"2026-07-09T23:55:12.659958Z","steps":["trace[515844513] 'process raft request' (duration: 129.964109ms)","trace[515844513] 'compare' (duration: 196.022306ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:55:12.660129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.395975ms","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":"warn","ts":"2026-07-09T23:55:12.660174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.229412ms","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-09T23:55:12.660229Z","caller":"traceutil/trace.go:171","msg":"trace[301628874] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1296; }","duration":"157.552999ms","start":"2026-07-09T23:55:12.502656Z","end":"2026-07-09T23:55:12.660209Z","steps":["trace[301628874] 'agreement among raft nodes before linearized reading' (duration: 157.297373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:12.660102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:12.33344Z","time spent":"326.618881ms","remote":"127.0.0.1:35880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:55:12.660286Z","caller":"traceutil/trace.go:171","msg":"trace[1755275090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1296; }","duration":"157.372764ms","start":"2026-07-09T23:55:12.502891Z","end":"2026-07-09T23:55:12.660264Z","steps":["trace[1755275090] 'agreement among raft nodes before linearized reading' (duration: 157.095898ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:13.909092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.844029ms","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-09T23:55:13.909205Z","caller":"traceutil/trace.go:171","msg":"trace[2013398680] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1300; }","duration":"110.967842ms","start":"2026-07-09T23:55:13.79822Z","end":"2026-07-09T23:55:13.909188Z","steps":["trace[2013398680] 'range keys from in-memory index tree' (duration: 110.827509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:13.909863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.391204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:55:13.910154Z","caller":"traceutil/trace.go:171","msg":"trace[1040589234] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1348; }","duration":"367.446516ms","start":"2026-07-09T23:55:13.542689Z","end":"2026-07-09T23:55:13.910136Z","steps":["trace[1040589234] 'read index received' (duration: 257.629373ms)","trace[1040589234] 'applied index is now lower than readState.Index' (duration: 109.816343ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:55:13.91026Z","caller":"traceutil/trace.go:171","msg":"trace[139678898] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"380.218521ms","start":"2026-07-09T23:55:13.530028Z","end":"2026-07-09T23:55:13.910247Z","steps":["trace[139678898] 'process raft request' (duration: 270.277244ms)","trace[139678898] 'compare' (duration: 109.23562ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:55:13.910334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:13.530007Z","time spent":"380.286163ms","remote":"127.0.0.1:35642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:55:13.910488Z","caller":"traceutil/trace.go:171","msg":"trace[802105072] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"119.899492ms","start":"2026-07-09T23:55:13.790539Z","end":"2026-07-09T23:55:13.910438Z","steps":["trace[802105072] 'process raft request' (duration: 119.550253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:13.91058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.876837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:55:13.910653Z","caller":"traceutil/trace.go:171","msg":"trace[53676677] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1303; }","duration":"367.98337ms","start":"2026-07-09T23:55:13.542657Z","end":"2026-07-09T23:55:13.91064Z","steps":["trace[53676677] 'agreement among raft nodes before linearized reading' (duration: 367.849217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:13.910725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.975877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:55:13.910817Z","caller":"traceutil/trace.go:171","msg":"trace[1839657930] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"373.932146ms","start":"2026-07-09T23:55:13.536857Z","end":"2026-07-09T23:55:13.91079Z","steps":["trace[1839657930] 'process raft request' (duration: 373.137857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:13.910902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:13.536838Z","time spent":"374.003118ms","remote":"127.0.0.1:35880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:55:13.911843Z","caller":"traceutil/trace.go:171","msg":"trace[1617792832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1303; }","duration":"269.28802ms","start":"2026-07-09T23:55:13.642535Z","end":"2026-07-09T23:55:13.911823Z","steps":["trace[1617792832] 'agreement among raft nodes before linearized reading' (duration: 267.946147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:13.910744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:13.542643Z","time spent":"368.088592ms","remote":"127.0.0.1:35894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-07-09T23:55:14.12079Z","caller":"traceutil/trace.go:171","msg":"trace[515706503] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"205.53837ms","start":"2026-07-09T23:55:13.915233Z","end":"2026-07-09T23:55:14.120772Z","steps":["trace[515706503] 'process raft request' (duration: 205.12363ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:55:14.121151Z","caller":"traceutil/trace.go:171","msg":"trace[637779844] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1351; }","duration":"205.295274ms","start":"2026-07-09T23:55:13.915388Z","end":"2026-07-09T23:55:14.120684Z","steps":["trace[637779844] 'read index received' (duration: 204.860003ms)","trace[637779844] 'applied index is now lower than readState.Index' (duration: 434.601µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:55:14.121349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.946711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-09T23:55:14.12138Z","caller":"traceutil/trace.go:171","msg":"trace[530361362] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1304; }","duration":"206.021192ms","start":"2026-07-09T23:55:13.915352Z","end":"2026-07-09T23:55:14.121373Z","steps":["trace[530361362] 'agreement among raft nodes before linearized reading' (duration: 205.822407ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:55:14.156869Z","caller":"traceutil/trace.go:171","msg":"trace[1234870606] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"233.714184ms","start":"2026-07-09T23:55:13.923134Z","end":"2026-07-09T23:55:14.156848Z","steps":["trace[1234870606] 'process raft request' (duration: 233.619342ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:55:14.775096Z","caller":"traceutil/trace.go:171","msg":"trace[308800328] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"105.66439ms","start":"2026-07-09T23:55:14.669403Z","end":"2026-07-09T23:55:14.775067Z","steps":["trace[308800328] 'process raft request' (duration: 105.450674ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:55:18.970254Z","caller":"traceutil/trace.go:171","msg":"trace[1281221636] linearizableReadLoop","detail":"{readStateIndex:1403; appliedIndex:1401; }","duration":"105.707955ms","start":"2026-07-09T23:55:18.864523Z","end":"2026-07-09T23:55:18.970231Z","steps":["trace[1281221636] 'read index received' (duration: 28.984844ms)","trace[1281221636] 'applied index is now lower than readState.Index' (duration: 76.722241ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:55:18.970374Z","caller":"traceutil/trace.go:171","msg":"trace[1898298957] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"128.20879ms","start":"2026-07-09T23:55:18.842107Z","end":"2026-07-09T23:55:18.970316Z","steps":["trace[1898298957] 'process raft request' (duration: 51.441428ms)","trace[1898298957] 'compare' (duration: 76.515826ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:55:18.970295Z","caller":"traceutil/trace.go:171","msg":"trace[1841290882] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"128.097627ms","start":"2026-07-09T23:55:18.842178Z","end":"2026-07-09T23:55:18.970275Z","steps":["trace[1841290882] 'process raft request' (duration: 128.004015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:18.970622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.002483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-rxnmg\" ","response":"range_response_count:1 size:12641"} {"level":"info","ts":"2026-07-09T23:55:18.970694Z","caller":"traceutil/trace.go:171","msg":"trace[207907516] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-rxnmg; range_end:; response_count:1; response_revision:1354; }","duration":"106.195448ms","start":"2026-07-09T23:55:18.864478Z","end":"2026-07-09T23:55:18.970674Z","steps":["trace[207907516] 'agreement among raft nodes before linearized reading' (duration: 105.916631ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:55:19.126106Z","caller":"traceutil/trace.go:171","msg":"trace[1379489999] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"144.266745ms","start":"2026-07-09T23:55:18.981807Z","end":"2026-07-09T23:55:19.126074Z","steps":["trace[1379489999] 'process raft request' (duration: 112.317018ms)","trace[1379489999] 'compare' (duration: 31.689821ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:55:23.006476Z","caller":"traceutil/trace.go:171","msg":"trace[860904452] linearizableReadLoop","detail":"{readStateIndex:1422; appliedIndex:1421; }","duration":"156.500067ms","start":"2026-07-09T23:55:22.849953Z","end":"2026-07-09T23:55:23.006453Z","steps":["trace[860904452] 'read index received' (duration: 156.288362ms)","trace[860904452] 'applied index is now lower than readState.Index' (duration: 210.665µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:55:23.006518Z","caller":"traceutil/trace.go:171","msg":"trace[1374089014] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"172.727637ms","start":"2026-07-09T23:55:22.833764Z","end":"2026-07-09T23:55:23.006492Z","steps":["trace[1374089014] 'process raft request' (duration: 172.534372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:23.00699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.0128ms","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-09T23:55:23.007051Z","caller":"traceutil/trace.go:171","msg":"trace[644122877] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1373; }","duration":"157.113552ms","start":"2026-07-09T23:55:22.849922Z","end":"2026-07-09T23:55:23.007036Z","steps":["trace[644122877] 'agreement among raft nodes before linearized reading' (duration: 156.677051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:23.006986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.510525ms","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-09T23:55:23.007107Z","caller":"traceutil/trace.go:171","msg":"trace[1551055161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1373; }","duration":"134.641338ms","start":"2026-07-09T23:55:22.872451Z","end":"2026-07-09T23:55:23.007092Z","steps":["trace[1551055161] 'agreement among raft nodes before linearized reading' (duration: 134.189387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:23.523158Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813167701817029,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T23:55:23.727981Z","caller":"traceutil/trace.go:171","msg":"trace[142843664] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"717.175684ms","start":"2026-07-09T23:55:23.010781Z","end":"2026-07-09T23:55:23.727956Z","steps":["trace[142843664] 'process raft request' (duration: 717.028591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:23.728132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:23.010764Z","time spent":"717.290028ms","remote":"127.0.0.1:35880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:55:23.790533Z","caller":"traceutil/trace.go:171","msg":"trace[316401801] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"779.670825ms","start":"2026-07-09T23:55:23.010833Z","end":"2026-07-09T23:55:23.790504Z","steps":["trace[316401801] 'process raft request' (duration: 779.4609ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:55:23.790859Z","caller":"traceutil/trace.go:171","msg":"trace[604981556] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"779.89672ms","start":"2026-07-09T23:55:23.010945Z","end":"2026-07-09T23:55:23.790842Z","steps":["trace[604981556] 'process raft request' (duration: 779.50405ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:23.790925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.182242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-07-09T23:55:23.790958Z","caller":"traceutil/trace.go:171","msg":"trace[1611981947] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1377; }","duration":"768.252344ms","start":"2026-07-09T23:55:23.022698Z","end":"2026-07-09T23:55:23.79095Z","steps":["trace[1611981947] 'agreement among raft nodes before linearized reading' (duration: 768.139581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:23.790957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:23.010935Z","time spent":"779.965092ms","remote":"127.0.0.1:35880","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-09T23:55:23.790981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:23.022684Z","time spent":"768.291294ms","remote":"127.0.0.1:35880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-09T23:55:23.790957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:23.01081Z","time spent":"780.067714ms","remote":"127.0.0.1:35880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:55:23.790798Z","caller":"traceutil/trace.go:171","msg":"trace[941996730] linearizableReadLoop","detail":"{readStateIndex:1426; appliedIndex:1423; }","duration":"768.051149ms","start":"2026-07-09T23:55:23.022727Z","end":"2026-07-09T23:55:23.790778Z","steps":["trace[941996730] 'read index received' (duration: 705.715812ms)","trace[941996730] 'applied index is now lower than readState.Index' (duration: 62.334647ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:55:23.790806Z","caller":"traceutil/trace.go:171","msg":"trace[644605639] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"779.380748ms","start":"2026-07-09T23:55:23.011406Z","end":"2026-07-09T23:55:23.790787Z","steps":["trace[644605639] 'process raft request' (duration: 779.292136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:23.791311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.496303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.77\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-09T23:55:23.791366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.588658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:55:23.791395Z","caller":"traceutil/trace.go:171","msg":"trace[977854523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1377; }","duration":"145.65577ms","start":"2026-07-09T23:55:23.645732Z","end":"2026-07-09T23:55:23.791388Z","steps":["trace[977854523] 'agreement among raft nodes before linearized reading' (duration: 145.609158ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:55:23.791424Z","caller":"traceutil/trace.go:171","msg":"trace[914154675] range","detail":"{range_begin:/registry/masterleases/199.204.45.77; range_end:; response_count:1; response_revision:1377; }","duration":"491.607875ms","start":"2026-07-09T23:55:23.299765Z","end":"2026-07-09T23:55:23.791373Z","steps":["trace[914154675] 'agreement among raft nodes before linearized reading' (duration: 491.425091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:55:23.791641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:23.299717Z","time spent":"491.906953ms","remote":"127.0.0.1:35642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.77\" "} {"level":"warn","ts":"2026-07-09T23:55:23.791672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:55:23.011361Z","time spent":"779.747617ms","remote":"127.0.0.1:35880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:56:40.401533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.07823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:56:40.401664Z","caller":"traceutil/trace.go:171","msg":"trace[401457106] linearizableReadLoop","detail":"{readStateIndex:1792; appliedIndex:1791; }","duration":"185.292765ms","start":"2026-07-09T23:56:40.216339Z","end":"2026-07-09T23:56:40.401632Z","steps":["trace[401457106] 'read index received' (duration: 70.948248ms)","trace[401457106] 'applied index is now lower than readState.Index' (duration: 114.343367ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:56:40.401953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.965682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-09T23:56:40.401972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.615112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-09T23:56:40.401981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.856556ms","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-09T23:56:40.401991Z","caller":"traceutil/trace.go:171","msg":"trace[336860110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1727; }","duration":"143.038444ms","start":"2026-07-09T23:56:40.258943Z","end":"2026-07-09T23:56:40.401981Z","steps":["trace[336860110] 'agreement among raft nodes before linearized reading' (duration: 142.906371ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:56:40.402034Z","caller":"traceutil/trace.go:171","msg":"trace[1115790789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1727; }","duration":"185.683414ms","start":"2026-07-09T23:56:40.216335Z","end":"2026-07-09T23:56:40.402018Z","steps":["trace[1115790789] 'agreement among raft nodes before linearized reading' (duration: 185.51191ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:56:40.402045Z","caller":"traceutil/trace.go:171","msg":"trace[419844988] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1727; }","duration":"137.946498ms","start":"2026-07-09T23:56:40.264083Z","end":"2026-07-09T23:56:40.40203Z","steps":["trace[419844988] 'agreement among raft nodes before linearized reading' (duration: 137.782864ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:56:40.402201Z","caller":"traceutil/trace.go:171","msg":"trace[1433282471] transaction","detail":"{read_only:false; response_revision:1727; number_of_response:1; }","duration":"189.471587ms","start":"2026-07-09T23:56:40.212713Z","end":"2026-07-09T23:56:40.402184Z","steps":["trace[1433282471] 'process raft request' (duration: 74.666669ms)","trace[1433282471] 'compare' (duration: 113.958567ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:57:14.770549Z","caller":"traceutil/trace.go:171","msg":"trace[228200171] linearizableReadLoop","detail":"{readStateIndex:1958; appliedIndex:1957; }","duration":"126.201797ms","start":"2026-07-09T23:57:14.644324Z","end":"2026-07-09T23:57:14.770526Z","steps":["trace[228200171] 'read index received' (duration: 125.040039ms)","trace[228200171] 'applied index is now lower than readState.Index' (duration: 1.160238ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:57:14.770992Z","caller":"traceutil/trace.go:171","msg":"trace[21852026] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"160.534743ms","start":"2026-07-09T23:57:14.610041Z","end":"2026-07-09T23:57:14.770576Z","steps":["trace[21852026] 'process raft request' (duration: 159.287252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:57:14.771013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.677509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T23:57:14.77108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.159737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-09T23:57:14.771264Z","caller":"traceutil/trace.go:171","msg":"trace[1809895202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1886; }","duration":"124.407443ms","start":"2026-07-09T23:57:14.646842Z","end":"2026-07-09T23:57:14.771249Z","steps":["trace[1809895202] 'agreement among raft nodes before linearized reading' (duration: 124.062655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:57:14.771216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.27183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-09T23:57:14.771218Z","caller":"traceutil/trace.go:171","msg":"trace[172373347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1886; }","duration":"126.915655ms","start":"2026-07-09T23:57:14.644274Z","end":"2026-07-09T23:57:14.77119Z","steps":["trace[172373347] 'agreement among raft nodes before linearized reading' (duration: 126.357871ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:57:14.771378Z","caller":"traceutil/trace.go:171","msg":"trace[310894385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1886; }","duration":"124.446114ms","start":"2026-07-09T23:57:14.646874Z","end":"2026-07-09T23:57:14.77132Z","steps":["trace[310894385] 'agreement among raft nodes before linearized reading' (duration: 123.978702ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:57:14.936916Z","caller":"traceutil/trace.go:171","msg":"trace[1009028105] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"101.141752ms","start":"2026-07-09T23:57:14.835732Z","end":"2026-07-09T23:57:14.936873Z","steps":["trace[1009028105] 'process raft request' (duration: 38.854488ms)","trace[1009028105] 'compare' (duration: 62.11396ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:59:45.353263Z","caller":"traceutil/trace.go:171","msg":"trace[1845946996] transaction","detail":"{read_only:false; response_revision:2601; number_of_response:1; }","duration":"280.120965ms","start":"2026-07-09T23:59:45.073122Z","end":"2026-07-09T23:59:45.353243Z","steps":["trace[1845946996] 'process raft request' (duration: 280.011872ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:59:45.35339Z","caller":"traceutil/trace.go:171","msg":"trace[874712818] linearizableReadLoop","detail":"{readStateIndex:2704; appliedIndex:2704; }","duration":"271.469344ms","start":"2026-07-09T23:59:45.081887Z","end":"2026-07-09T23:59:45.353356Z","steps":["trace[874712818] 'read index received' (duration: 271.455353ms)","trace[874712818] 'applied index is now lower than readState.Index' (duration: 11.311µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:59:45.353572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.666188ms","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-09T23:59:45.353625Z","caller":"traceutil/trace.go:171","msg":"trace[1845952265] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2601; }","duration":"271.755351ms","start":"2026-07-09T23:59:45.081856Z","end":"2026-07-09T23:59:45.353612Z","steps":["trace[1845952265] 'agreement among raft nodes before linearized reading' (duration: 271.582707ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:59:45.633341Z","caller":"traceutil/trace.go:171","msg":"trace[1814610417] transaction","detail":"{read_only:false; response_revision:2602; number_of_response:1; }","duration":"333.923548ms","start":"2026-07-09T23:59:45.299395Z","end":"2026-07-09T23:59:45.633318Z","steps":["trace[1814610417] 'process raft request' (duration: 333.791715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:59:45.633477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:59:45.299372Z","time spent":"334.03905ms","remote":"127.0.0.1:35880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:59:45.638048Z","caller":"traceutil/trace.go:171","msg":"trace[1292662681] linearizableReadLoop","detail":"{readStateIndex:2706; appliedIndex:2704; }","duration":"171.641148ms","start":"2026-07-09T23:59:45.466388Z","end":"2026-07-09T23:59:45.638029Z","steps":["trace[1292662681] 'read index received' (duration: 166.79821ms)","trace[1292662681] 'applied index is now lower than readState.Index' (duration: 4.842198ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:59:45.638307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.661108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-09T23:59:45.638313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.915904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T23:59:45.638339Z","caller":"traceutil/trace.go:171","msg":"trace[445112309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2603; }","duration":"121.74254ms","start":"2026-07-09T23:59:45.516589Z","end":"2026-07-09T23:59:45.638331Z","steps":["trace[445112309] 'agreement among raft nodes before linearized reading' (duration: 121.631177ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:59:45.63835Z","caller":"traceutil/trace.go:171","msg":"trace[459903137] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2603; }","duration":"171.998566ms","start":"2026-07-09T23:59:45.466341Z","end":"2026-07-09T23:59:45.638339Z","steps":["trace[459903137] 'agreement among raft nodes before linearized reading' (duration: 171.852573ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:59:45.638424Z","caller":"traceutil/trace.go:171","msg":"trace[1142223577] transaction","detail":"{read_only:false; response_revision:2603; number_of_response:1; }","duration":"280.858853ms","start":"2026-07-09T23:59:45.357557Z","end":"2026-07-09T23:59:45.638416Z","steps":["trace[1142223577] 'process raft request' (duration: 280.398052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:59:45.638534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.037614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-09T23:59:45.638562Z","caller":"traceutil/trace.go:171","msg":"trace[74935595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2603; }","duration":"119.085346ms","start":"2026-07-09T23:59:45.519471Z","end":"2026-07-09T23:59:45.638556Z","steps":["trace[74935595] 'agreement among raft nodes before linearized reading' (duration: 119.019454ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T00:00:11.991029Z","caller":"traceutil/trace.go:171","msg":"trace[43238194] linearizableReadLoop","detail":"{readStateIndex:2841; appliedIndex:2840; }","duration":"191.097423ms","start":"2026-07-10T00:00:11.799899Z","end":"2026-07-10T00:00:11.990996Z","steps":["trace[43238194] 'read index received' (duration: 115.820486ms)","trace[43238194] 'applied index is now lower than readState.Index' (duration: 75.275507ms)"],"step_count":2} {"level":"info","ts":"2026-07-10T00:00:11.991091Z","caller":"traceutil/trace.go:171","msg":"trace[1757688107] transaction","detail":"{read_only:false; response_revision:2732; number_of_response:1; }","duration":"212.781962ms","start":"2026-07-10T00:00:11.778278Z","end":"2026-07-10T00:00:11.99106Z","steps":["trace[1757688107] 'process raft request' (duration: 137.500204ms)","trace[1757688107] 'compare' (duration: 75.059002ms)"],"step_count":2} {"level":"warn","ts":"2026-07-10T00:00:11.991333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.42113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-10T00:00:11.991398Z","caller":"traceutil/trace.go:171","msg":"trace[503837246] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2732; }","duration":"191.543433ms","start":"2026-07-10T00:00:11.799838Z","end":"2026-07-10T00:00:11.991381Z","steps":["trace[503837246] 'agreement among raft nodes before linearized reading' (duration: 191.329168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T00:00:11.991342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.629808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-07-10T00:00:11.991509Z","caller":"traceutil/trace.go:171","msg":"trace[1845558030] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:2732; }","duration":"180.835732ms","start":"2026-07-10T00:00:11.810652Z","end":"2026-07-10T00:00:11.991488Z","steps":["trace[1845558030] 'agreement among raft nodes before linearized reading' (duration: 180.612847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-10T00:00:11.991513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.956392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-07-10T00:00:11.991681Z","caller":"traceutil/trace.go:171","msg":"trace[1048955984] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:2732; }","duration":"137.157156ms","start":"2026-07-10T00:00:11.854508Z","end":"2026-07-10T00:00:11.991666Z","steps":["trace[1048955984] 'agreement among raft nodes before linearized reading' (duration: 136.850079ms)"],"step_count":1} {"level":"info","ts":"2026-07-10T00:00:12.488522Z","caller":"traceutil/trace.go:171","msg":"trace[2137841589] transaction","detail":"{read_only:false; response_revision:2735; number_of_response:1; }","duration":"114.701589ms","start":"2026-07-10T00:00:12.373779Z","end":"2026-07-10T00:00:12.488481Z","steps":["trace[2137841589] 'process raft request' (duration: 51.551678ms)","trace[2137841589] 'compare' (duration: 62.963207ms)"],"step_count":2}