{"level":"warn","ts":"2026-07-25T14:13:08.237123Z","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-25T14:13:08.237193Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223: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.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223: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-25T14:13:08.237251Z","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-25T14:13:08.237264Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-07-25T14:13:08.237301Z","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-25T14:13:08.237977Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-07-25T14:13:08.238056Z","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.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223: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-25T14:13:08.245757Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.516084ms"} {"level":"info","ts":"2026-07-25T14:13:08.252042Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-07-25T14:13:08.252137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-07-25T14:13:08.252165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-07-25T14:13:08.252201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-25T14:13:08.25221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-07-25T14:13:08.252265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-07-25T14:13:08.259762Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-25T14:13:08.261465Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-25T14:13:08.263457Z","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-25T14:13:08.266867Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-25T14:13:08.268738Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-25T14:13:08.26881Z","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-25T14:13:08.268863Z","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-25T14:13:08.268903Z","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-25T14:13:08.271168Z","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-25T14:13:08.271235Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-07-25T14:13:08.271263Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-07-25T14:13:08.27142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-07-25T14:13:08.272485Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-07-25T14:13:08.272494Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-25T14:13:08.272531Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-25T14:13:08.753494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-07-25T14:13:08.75354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-25T14:13:08.75357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-07-25T14:13:08.753586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-07-25T14:13:08.753594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-07-25T14:13:08.753604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-07-25T14:13:08.753614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-07-25T14:13:08.75445Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-07-25T14:13:08.754455Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T14:13:08.75449Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T14:13:08.754683Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T14:13:08.755171Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-25T14:13:08.7552Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-25T14:13:08.755379Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T14:13:08.75552Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T14:13:08.755558Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T14:13:08.757026Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-25T14:13:08.757785Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"warn","ts":"2026-07-25T14:13:19.273216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.408678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-fc4n5\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-07-25T14:13:19.273374Z","caller":"traceutil/trace.go:171","msg":"trace[1659434571] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-fc4n5; range_end:; response_count:1; response_revision:323; }","duration":"124.621913ms","start":"2026-07-25T14:13:19.148737Z","end":"2026-07-25T14:13:19.273359Z","steps":["trace[1659434571] 'range keys from in-memory index tree' (duration: 124.285854ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:19.273751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.897176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-25T14:13:19.273859Z","caller":"traceutil/trace.go:171","msg":"trace[1281035093] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"124.663975ms","start":"2026-07-25T14:13:19.149178Z","end":"2026-07-25T14:13:19.273842Z","steps":["trace[1281035093] 'process raft request' (duration: 18.200103ms)","trace[1281035093] 'compare' (duration: 105.753032ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T14:13:19.275471Z","caller":"traceutil/trace.go:171","msg":"trace[1010470322] linearizableReadLoop","detail":"{readStateIndex:333; appliedIndex:330; }","duration":"120.034242ms","start":"2026-07-25T14:13:19.155418Z","end":"2026-07-25T14:13:19.275453Z","steps":["trace[1010470322] 'read index received' (duration: 11.964373ms)","trace[1010470322] 'applied index is now lower than readState.Index' (duration: 108.069199ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T14:13:19.275531Z","caller":"traceutil/trace.go:171","msg":"trace[1588505475] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"121.615467ms","start":"2026-07-25T14:13:19.153909Z","end":"2026-07-25T14:13:19.275524Z","steps":["trace[1588505475] 'process raft request' (duration: 121.467883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:19.27567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.909606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-07-25T14:13:19.27567Z","caller":"traceutil/trace.go:171","msg":"trace[1394501935] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"119.113596ms","start":"2026-07-25T14:13:19.156511Z","end":"2026-07-25T14:13:19.275624Z","steps":["trace[1394501935] 'process raft request' (duration: 118.92034ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:19.275727Z","caller":"traceutil/trace.go:171","msg":"trace[1545990291] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:328; }","duration":"104.996779ms","start":"2026-07-25T14:13:19.170716Z","end":"2026-07-25T14:13:19.275713Z","steps":["trace[1545990291] 'agreement among raft nodes before linearized reading' (duration: 104.887586ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:19.275532Z","caller":"traceutil/trace.go:171","msg":"trace[1656013782] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"122.633387ms","start":"2026-07-25T14:13:19.152877Z","end":"2026-07-25T14:13:19.27551Z","steps":["trace[1656013782] 'process raft request' (duration: 122.441962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:19.275632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.207868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-07-25T14:13:19.275858Z","caller":"traceutil/trace.go:171","msg":"trace[724614074] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:328; }","duration":"120.450234ms","start":"2026-07-25T14:13:19.155395Z","end":"2026-07-25T14:13:19.275845Z","steps":["trace[724614074] 'agreement among raft nodes before linearized reading' (duration: 120.188746ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:19.276132Z","caller":"traceutil/trace.go:171","msg":"trace[1011242961] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"119.714243ms","start":"2026-07-25T14:13:19.155889Z","end":"2026-07-25T14:13:19.275603Z","steps":["trace[1011242961] 'process raft request' (duration: 119.521998ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:19.433647Z","caller":"traceutil/trace.go:171","msg":"trace[1697464327] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"150.380914ms","start":"2026-07-25T14:13:19.283243Z","end":"2026-07-25T14:13:19.433624Z","steps":["trace[1697464327] 'process raft request' (duration: 150.144667ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:19.433756Z","caller":"traceutil/trace.go:171","msg":"trace[1876980644] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"148.620404ms","start":"2026-07-25T14:13:19.285109Z","end":"2026-07-25T14:13:19.43373Z","steps":["trace[1876980644] 'process raft request' (duration: 148.342556ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:19.433775Z","caller":"traceutil/trace.go:171","msg":"trace[2122501706] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"148.109619ms","start":"2026-07-25T14:13:19.285648Z","end":"2026-07-25T14:13:19.433758Z","steps":["trace[2122501706] 'process raft request' (duration: 147.846221ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:19.433668Z","caller":"traceutil/trace.go:171","msg":"trace[1802379824] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"151.012694ms","start":"2026-07-25T14:13:19.28263Z","end":"2026-07-25T14:13:19.433643Z","steps":["trace[1802379824] 'process raft request' (duration: 100.163041ms)","trace[1802379824] 'compare' (duration: 50.460882ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T14:13:19.434077Z","caller":"traceutil/trace.go:171","msg":"trace[2109937627] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:335; }","duration":"147.880973ms","start":"2026-07-25T14:13:19.286174Z","end":"2026-07-25T14:13:19.434055Z","steps":["trace[2109937627] 'read index received' (duration: 71.741463ms)","trace[2109937627] 'applied index is now lower than readState.Index' (duration: 76.13867ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T14:13:19.434214Z","caller":"traceutil/trace.go:171","msg":"trace[428486557] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"148.394997ms","start":"2026-07-25T14:13:19.285808Z","end":"2026-07-25T14:13:19.434202Z","steps":["trace[428486557] 'process raft request' (duration: 147.925503ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:19.434258Z","caller":"traceutil/trace.go:171","msg":"trace[1166797920] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"147.474892ms","start":"2026-07-25T14:13:19.286771Z","end":"2026-07-25T14:13:19.434246Z","steps":["trace[1166797920] 'process raft request' (duration: 147.216554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:19.434389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.195712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-07-25T14:13:19.434435Z","caller":"traceutil/trace.go:171","msg":"trace[1844649519] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:335; }","duration":"148.257264ms","start":"2026-07-25T14:13:19.286162Z","end":"2026-07-25T14:13:19.434419Z","steps":["trace[1844649519] 'agreement among raft nodes before linearized reading' (duration: 148.147291ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:30.140286Z","caller":"traceutil/trace.go:171","msg":"trace[1801554330] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"125.361478ms","start":"2026-07-25T14:13:30.014893Z","end":"2026-07-25T14:13:30.140254Z","steps":["trace[1801554330] 'process raft request' (duration: 125.135083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:32.272548Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227753896943483,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T14:13:32.773004Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227753896943483,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T14:13:32.931196Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.196818891s","expected-duration":"1s"} {"level":"info","ts":"2026-07-25T14:13:32.931572Z","caller":"traceutil/trace.go:171","msg":"trace[912482826] linearizableReadLoop","detail":"{readStateIndex:400; appliedIndex:399; }","duration":"1.159999623s","start":"2026-07-25T14:13:31.771552Z","end":"2026-07-25T14:13:32.931552Z","steps":["trace[912482826] 'read index received' (duration: 1.159845459s)","trace[912482826] 'applied index is now lower than readState.Index' (duration: 153.034µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T14:13:32.931703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.160137937s","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-25T14:13:32.931742Z","caller":"traceutil/trace.go:171","msg":"trace[366380079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:389; }","duration":"1.160192589s","start":"2026-07-25T14:13:31.771539Z","end":"2026-07-25T14:13:32.931731Z","steps":["trace[366380079] 'agreement among raft nodes before linearized reading' (duration: 1.160097636s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:32.931779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:31.7715Z","time spent":"1.160270181s","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-25T14:13:32.931813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.515958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-25T14:13:32.93189Z","caller":"traceutil/trace.go:171","msg":"trace[1463692401] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:389; }","duration":"787.650031ms","start":"2026-07-25T14:13:32.14422Z","end":"2026-07-25T14:13:32.93187Z","steps":["trace[1463692401] 'agreement among raft nodes before linearized reading' (duration: 787.478507ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:32.931907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.988904ms","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-25T14:13:32.931946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:32.1442Z","time spent":"787.737043ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-25T14:13:32.931928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.109314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-07-25T14:13:32.931999Z","caller":"traceutil/trace.go:171","msg":"trace[667399598] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:389; }","duration":"756.135948ms","start":"2026-07-25T14:13:32.175842Z","end":"2026-07-25T14:13:32.931978Z","steps":["trace[667399598] 'agreement among raft nodes before linearized reading' (duration: 755.946873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:32.932009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.597851ms","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-25T14:13:32.932052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:32.17582Z","time spent":"756.223879ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-25T14:13:32.93183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:31.734215Z","time spent":"1.19760461s","remote":"127.0.0.1:38852","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-25T14:13:32.932029Z","caller":"traceutil/trace.go:171","msg":"trace[1228495635] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:389; }","duration":"651.241716ms","start":"2026-07-25T14:13:32.280756Z","end":"2026-07-25T14:13:32.931998Z","steps":["trace[1228495635] 'agreement among raft nodes before linearized reading' (duration: 651.029691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:32.932261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:32.280738Z","time spent":"651.503693ms","remote":"127.0.0.1:39010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4391,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-25T14:13:32.932104Z","caller":"traceutil/trace.go:171","msg":"trace[779502840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:389; }","duration":"424.747014ms","start":"2026-07-25T14:13:32.507336Z","end":"2026-07-25T14:13:32.932083Z","steps":["trace[779502840] 'agreement among raft nodes before linearized reading' (duration: 424.512979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:32.932349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:32.507315Z","time spent":"425.018501ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-25T14:13:33.526313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.748962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T14:13:33.526388Z","caller":"traceutil/trace.go:171","msg":"trace[1168026384] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"592.125369ms","start":"2026-07-25T14:13:32.93425Z","end":"2026-07-25T14:13:33.526376Z","steps":["trace[1168026384] 'process raft request' (duration: 294.185472ms)","trace[1168026384] 'compare' (duration: 297.65124ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T14:13:33.526422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:32.934229Z","time spent":"592.18004ms","remote":"127.0.0.1:38852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T14:13:33.53278Z","caller":"traceutil/trace.go:171","msg":"trace[1772230947] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"596.183701ms","start":"2026-07-25T14:13:32.93656Z","end":"2026-07-25T14:13:33.532744Z","steps":["trace[1772230947] 'process raft request' (duration: 596.013006ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:33.532803Z","caller":"traceutil/trace.go:171","msg":"trace[351678767] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"595.829661ms","start":"2026-07-25T14:13:32.936957Z","end":"2026-07-25T14:13:33.532787Z","steps":["trace[351678767] 'process raft request' (duration: 595.775109ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:33.532819Z","caller":"traceutil/trace.go:171","msg":"trace[1294151809] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"595.929173ms","start":"2026-07-25T14:13:32.936869Z","end":"2026-07-25T14:13:33.532798Z","steps":["trace[1294151809] 'process raft request' (duration: 595.840021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:33.532839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:32.936532Z","time spent":"596.279102ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T14:13:33.532912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:32.936943Z","time spent":"595.908802ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T14:13:33.532929Z","caller":"traceutil/trace.go:171","msg":"trace[584633495] linearizableReadLoop","detail":"{readStateIndex:406; appliedIndex:401; }","duration":"130.127264ms","start":"2026-07-25T14:13:33.402793Z","end":"2026-07-25T14:13:33.532921Z","steps":["trace[584633495] 'read index received' (duration: 129.765185ms)","trace[584633495] 'applied index is now lower than readState.Index' (duration: 361.309µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T14:13:33.532958Z","caller":"traceutil/trace.go:171","msg":"trace[764119435] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"596.19884ms","start":"2026-07-25T14:13:32.936749Z","end":"2026-07-25T14:13:33.532948Z","steps":["trace[764119435] 'process raft request' (duration: 595.933833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:33.533008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:32.936719Z","time spent":"596.263792ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T14:13:33.533072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.285318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T14:13:33.533098Z","caller":"traceutil/trace.go:171","msg":"trace[267338173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:395; }","duration":"130.348749ms","start":"2026-07-25T14:13:33.402742Z","end":"2026-07-25T14:13:33.53309Z","steps":["trace[267338173] 'agreement among raft nodes before linearized reading' (duration: 130.260837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:33.532919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:32.936852Z","time spent":"596.009385ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T14:13:33.533208Z","caller":"traceutil/trace.go:171","msg":"trace[861022649] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"589.550193ms","start":"2026-07-25T14:13:32.94365Z","end":"2026-07-25T14:13:33.5332Z","steps":["trace[861022649] 'process raft request' (duration: 589.102762ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:33.533244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:32.943638Z","time spent":"589.585513ms","remote":"127.0.0.1:39010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4361,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T14:13:35.205304Z","caller":"traceutil/trace.go:171","msg":"trace[543057667] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"148.89315ms","start":"2026-07-25T14:13:35.056389Z","end":"2026-07-25T14:13:35.205282Z","steps":["trace[543057667] 'process raft request' (duration: 148.745837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:35.462652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.20188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-25T14:13:35.462801Z","caller":"traceutil/trace.go:171","msg":"trace[688069106] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"251.234262ms","start":"2026-07-25T14:13:35.211539Z","end":"2026-07-25T14:13:35.462773Z","steps":["trace[688069106] 'process raft request' (duration: 141.827297ms)","trace[688069106] 'compare' (duration: 109.030026ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T14:13:35.869659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.317051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T14:13:35.869788Z","caller":"traceutil/trace.go:171","msg":"trace[1749010887] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:410; }","duration":"332.661374ms","start":"2026-07-25T14:13:35.537114Z","end":"2026-07-25T14:13:35.869776Z","steps":["trace[1749010887] 'read index received' (duration: 66.105306ms)","trace[1749010887] 'applied index is now lower than readState.Index' (duration: 266.555438ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T14:13:35.869839Z","caller":"traceutil/trace.go:171","msg":"trace[691973583] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"401.369744ms","start":"2026-07-25T14:13:35.46843Z","end":"2026-07-25T14:13:35.869799Z","steps":["trace[691973583] 'process raft request' (duration: 134.849967ms)","trace[691973583] 'compare' (duration: 266.004724ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T14:13:35.869964Z","caller":"traceutil/trace.go:171","msg":"trace[1450222710] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"401.482067ms","start":"2026-07-25T14:13:35.468473Z","end":"2026-07-25T14:13:35.869955Z","steps":["trace[1450222710] 'process raft request' (duration: 401.262382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:35.870013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:35.468455Z","time spent":"401.534018ms","remote":"127.0.0.1:39024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T14:13:35.870019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:35.468412Z","time spent":"401.533788ms","remote":"127.0.0.1:39296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T14:13:35.870162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.51667ms","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-25T14:13:35.870173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.877139ms","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-25T14:13:35.870195Z","caller":"traceutil/trace.go:171","msg":"trace[1025013478] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:401; }","duration":"332.562172ms","start":"2026-07-25T14:13:35.537622Z","end":"2026-07-25T14:13:35.870185Z","steps":["trace[1025013478] 'agreement among raft nodes before linearized reading' (duration: 332.50467ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:35.870227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:35.537613Z","time spent":"332.607203ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-25T14:13:35.870242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.121025ms","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-25T14:13:35.870243Z","caller":"traceutil/trace.go:171","msg":"trace[202978032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:401; }","duration":"332.961401ms","start":"2026-07-25T14:13:35.537252Z","end":"2026-07-25T14:13:35.870213Z","steps":["trace[202978032] 'agreement among raft nodes before linearized reading' (duration: 332.843879ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:35.870266Z","caller":"traceutil/trace.go:171","msg":"trace[1811927707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:401; }","duration":"333.188526ms","start":"2026-07-25T14:13:35.537069Z","end":"2026-07-25T14:13:35.870258Z","steps":["trace[1811927707] 'agreement among raft nodes before linearized reading' (duration: 333.135845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:35.870277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:35.537246Z","time spent":"333.023873ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-25T14:13:35.870288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:35.537049Z","time spent":"333.235157ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-25T14:13:35.870335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.814208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-25T14:13:35.870408Z","caller":"traceutil/trace.go:171","msg":"trace[1568406785] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:401; }","duration":"332.932301ms","start":"2026-07-25T14:13:35.537457Z","end":"2026-07-25T14:13:35.870389Z","steps":["trace[1568406785] 'agreement among raft nodes before linearized reading' (duration: 332.772527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:35.870482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:35.537439Z","time spent":"333.031433ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-25T14:13:36.164367Z","caller":"traceutil/trace.go:171","msg":"trace[1714755895] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"288.299982ms","start":"2026-07-25T14:13:35.87603Z","end":"2026-07-25T14:13:36.16433Z","steps":["trace[1714755895] 'process raft request' (duration: 251.403788ms)","trace[1714755895] 'compare' (duration: 36.766381ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T14:13:36.172126Z","caller":"traceutil/trace.go:171","msg":"trace[1876789956] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"295.72127ms","start":"2026-07-25T14:13:35.876388Z","end":"2026-07-25T14:13:36.172109Z","steps":["trace[1876789956] 'process raft request' (duration: 295.679359ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:36.172176Z","caller":"traceutil/trace.go:171","msg":"trace[448132965] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"295.874024ms","start":"2026-07-25T14:13:35.876273Z","end":"2026-07-25T14:13:36.172147Z","steps":["trace[448132965] 'process raft request' (duration: 295.728051ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:36.172226Z","caller":"traceutil/trace.go:171","msg":"trace[1087754732] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"295.839664ms","start":"2026-07-25T14:13:35.876361Z","end":"2026-07-25T14:13:36.172201Z","steps":["trace[1087754732] 'process raft request' (duration: 295.68017ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:36.172499Z","caller":"traceutil/trace.go:171","msg":"trace[1710858257] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"296.035309ms","start":"2026-07-25T14:13:35.876401Z","end":"2026-07-25T14:13:36.172436Z","steps":["trace[1710858257] 'process raft request' (duration: 295.68554ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:36.172746Z","caller":"traceutil/trace.go:171","msg":"trace[1331995049] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"296.325336ms","start":"2026-07-25T14:13:35.876405Z","end":"2026-07-25T14:13:36.172731Z","steps":["trace[1331995049] 'process raft request' (duration: 295.871555ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:36.172913Z","caller":"traceutil/trace.go:171","msg":"trace[629153765] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"296.208403ms","start":"2026-07-25T14:13:35.876691Z","end":"2026-07-25T14:13:36.172899Z","steps":["trace[629153765] 'process raft request' (duration: 295.780273ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:36.173178Z","caller":"traceutil/trace.go:171","msg":"trace[965479974] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"296.236873ms","start":"2026-07-25T14:13:35.876931Z","end":"2026-07-25T14:13:36.173168Z","steps":["trace[965479974] 'process raft request' (duration: 295.760152ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:36.173284Z","caller":"traceutil/trace.go:171","msg":"trace[622713345] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"295.886884ms","start":"2026-07-25T14:13:35.876243Z","end":"2026-07-25T14:13:36.17213Z","steps":["trace[622713345] 'process raft request' (duration: 295.655458ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:36.346755Z","caller":"traceutil/trace.go:171","msg":"trace[784458821] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"104.449249ms","start":"2026-07-25T14:13:36.242292Z","end":"2026-07-25T14:13:36.346741Z","steps":["trace[784458821] 'process raft request' (duration: 104.409939ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:36.346764Z","caller":"traceutil/trace.go:171","msg":"trace[1175058747] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"106.891758ms","start":"2026-07-25T14:13:36.239847Z","end":"2026-07-25T14:13:36.346739Z","steps":["trace[1175058747] 'process raft request' (duration: 27.350542ms)","trace[1175058747] 'compare' (duration: 79.364892ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T14:13:36.34697Z","caller":"traceutil/trace.go:171","msg":"trace[2060634393] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"106.416647ms","start":"2026-07-25T14:13:36.240546Z","end":"2026-07-25T14:13:36.346963Z","steps":["trace[2060634393] 'process raft request' (duration: 106.11544ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:38.441277Z","caller":"traceutil/trace.go:171","msg":"trace[1918273607] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"260.14663ms","start":"2026-07-25T14:13:38.180784Z","end":"2026-07-25T14:13:38.440931Z","steps":["trace[1918273607] 'process raft request' (duration: 161.854445ms)","trace[1918273607] 'compare' (duration: 98.169952ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T14:13:38.442526Z","caller":"traceutil/trace.go:171","msg":"trace[1693306174] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"261.688297ms","start":"2026-07-25T14:13:38.180784Z","end":"2026-07-25T14:13:38.442473Z","steps":["trace[1693306174] 'process raft request' (duration: 260.113959ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:38.442647Z","caller":"traceutil/trace.go:171","msg":"trace[1595323619] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"259.885084ms","start":"2026-07-25T14:13:38.182725Z","end":"2026-07-25T14:13:38.44261Z","steps":["trace[1595323619] 'process raft request' (duration: 259.504415ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:42.579344Z","caller":"traceutil/trace.go:171","msg":"trace[1423736937] linearizableReadLoop","detail":"{readStateIndex:455; appliedIndex:454; }","duration":"178.086225ms","start":"2026-07-25T14:13:42.401233Z","end":"2026-07-25T14:13:42.579319Z","steps":["trace[1423736937] 'read index received' (duration: 177.959072ms)","trace[1423736937] 'applied index is now lower than readState.Index' (duration: 125.933µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T14:13:42.579676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.426973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T14:13:42.579753Z","caller":"traceutil/trace.go:171","msg":"trace[1407875043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:441; }","duration":"178.541665ms","start":"2026-07-25T14:13:42.401197Z","end":"2026-07-25T14:13:42.579738Z","steps":["trace[1407875043] 'agreement among raft nodes before linearized reading' (duration: 178.435622ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:42.579735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.658776ms","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-25T14:13:42.579753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.164468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-25T14:13:42.579752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.135223ms","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-25T14:13:42.579828Z","caller":"traceutil/trace.go:171","msg":"trace[2121328717] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:441; }","duration":"109.27785ms","start":"2026-07-25T14:13:42.470531Z","end":"2026-07-25T14:13:42.579809Z","steps":["trace[2121328717] 'agreement among raft nodes before linearized reading' (duration: 109.127687ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:42.5799Z","caller":"traceutil/trace.go:171","msg":"trace[765523919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:441; }","duration":"111.355597ms","start":"2026-07-25T14:13:42.468503Z","end":"2026-07-25T14:13:42.579859Z","steps":["trace[765523919] 'agreement among raft nodes before linearized reading' (duration: 111.111962ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:42.57982Z","caller":"traceutil/trace.go:171","msg":"trace[1297242183] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:441; }","duration":"108.780279ms","start":"2026-07-25T14:13:42.471019Z","end":"2026-07-25T14:13:42.579799Z","steps":["trace[1297242183] 'agreement among raft nodes before linearized reading' (duration: 108.615825ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:42.579747Z","caller":"traceutil/trace.go:171","msg":"trace[826534345] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"306.491893ms","start":"2026-07-25T14:13:42.273227Z","end":"2026-07-25T14:13:42.579719Z","steps":["trace[826534345] 'process raft request' (duration: 305.961111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:42.58009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:42.273215Z","time spent":"306.81419ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T14:13:42.931798Z","caller":"traceutil/trace.go:171","msg":"trace[830220333] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"346.702582ms","start":"2026-07-25T14:13:42.585063Z","end":"2026-07-25T14:13:42.931765Z","steps":["trace[830220333] 'process raft request' (duration: 341.751189ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:42.931964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:42.584993Z","time spent":"346.883036ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T14:13:42.932656Z","caller":"traceutil/trace.go:171","msg":"trace[751314805] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"347.286016ms","start":"2026-07-25T14:13:42.585351Z","end":"2026-07-25T14:13:42.932637Z","steps":["trace[751314805] 'process raft request' (duration: 346.752174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:42.932745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:42.585333Z","time spent":"347.364598ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T14:13:42.932911Z","caller":"traceutil/trace.go:171","msg":"trace[106263413] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"347.329577ms","start":"2026-07-25T14:13:42.58556Z","end":"2026-07-25T14:13:42.932889Z","steps":["trace[106263413] 'process raft request' (duration: 346.784265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:42.933073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:42.585549Z","time spent":"347.436219ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T14:13:50.233912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211123283s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T14:13:50.234022Z","caller":"traceutil/trace.go:171","msg":"trace[283589301] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:505; }","duration":"1.178579595s","start":"2026-07-25T14:13:49.055427Z","end":"2026-07-25T14:13:50.234007Z","steps":["trace[283589301] 'read index received' (duration: 18.54µs)","trace[283589301] 'applied index is now lower than readState.Index' (duration: 1.178559245s)"],"step_count":2} {"level":"info","ts":"2026-07-25T14:13:50.23417Z","caller":"traceutil/trace.go:171","msg":"trace[1317117097] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"1.26974912s","start":"2026-07-25T14:13:48.96436Z","end":"2026-07-25T14:13:50.234109Z","steps":["trace[1317117097] 'process raft request' (duration: 58.337881ms)","trace[1317117097] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-cp-lock; req_size:416; } (duration: 1.210340518s)"],"step_count":2} {"level":"warn","ts":"2026-07-25T14:13:50.234318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:48.964357Z","time spent":"1.269886203s","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T14:13:50.234438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.179006725s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T14:13:50.23449Z","caller":"traceutil/trace.go:171","msg":"trace[81723971] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:491; }","duration":"1.179085526s","start":"2026-07-25T14:13:49.055393Z","end":"2026-07-25T14:13:50.234478Z","steps":["trace[81723971] 'agreement among raft nodes before linearized reading' (duration: 1.178658457s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:50.234531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:49.055378Z","time spent":"1.179144597s","remote":"127.0.0.1:38934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-07-25T14:13:52.838863Z","caller":"traceutil/trace.go:171","msg":"trace[1165726626] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"210.466102ms","start":"2026-07-25T14:13:52.62838Z","end":"2026-07-25T14:13:52.838846Z","steps":["trace[1165726626] 'process raft request' (duration: 210.37552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:53.207749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.782896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-25T14:13:53.207818Z","caller":"traceutil/trace.go:171","msg":"trace[380400465] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:573; }","duration":"175.95189ms","start":"2026-07-25T14:13:53.031851Z","end":"2026-07-25T14:13:53.207803Z","steps":["trace[380400465] 'range keys from in-memory index tree' (duration: 175.558581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:53.20802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.557076ms","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-25T14:13:53.20823Z","caller":"traceutil/trace.go:171","msg":"trace[905817716] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:573; }","duration":"195.942563ms","start":"2026-07-25T14:13:53.012189Z","end":"2026-07-25T14:13:53.208131Z","steps":["trace[905817716] 'range keys from in-memory index tree' (duration: 195.369182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:56.759231Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227753896943848,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-25T14:13:56.780523Z","caller":"traceutil/trace.go:171","msg":"trace[903206702] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"521.534743ms","start":"2026-07-25T14:13:56.258966Z","end":"2026-07-25T14:13:56.780501Z","steps":["trace[903206702] 'read index received' (duration: 518.997982ms)","trace[903206702] 'applied index is now lower than readState.Index' (duration: 2.535901ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T14:13:56.780589Z","caller":"traceutil/trace.go:171","msg":"trace[331977156] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"550.902057ms","start":"2026-07-25T14:13:56.229656Z","end":"2026-07-25T14:13:56.780558Z","steps":["trace[331977156] 'process raft request' (duration: 549.138731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:56.780966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:56.229634Z","time spent":"551.031939ms","remote":"127.0.0.1:38934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036253,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T14:13:56.780701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.38119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T14:13:56.781118Z","caller":"traceutil/trace.go:171","msg":"trace[628685446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"377.843858ms","start":"2026-07-25T14:13:56.403253Z","end":"2026-07-25T14:13:56.781096Z","steps":["trace[628685446] 'agreement among raft nodes before linearized reading' (duration: 377.383949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:56.781172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:56.40323Z","time spent":"377.92922ms","remote":"127.0.0.1:38818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T14:13:56.781428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.426401ms","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-25T14:13:56.781539Z","caller":"traceutil/trace.go:171","msg":"trace[1091749743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:593; }","duration":"522.556003ms","start":"2026-07-25T14:13:56.258938Z","end":"2026-07-25T14:13:56.781494Z","steps":["trace[1091749743] 'agreement among raft nodes before linearized reading' (duration: 521.654405ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:56.781605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:56.258923Z","time spent":"522.668825ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-25T14:13:56.953377Z","caller":"traceutil/trace.go:171","msg":"trace[60672516] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"114.236139ms","start":"2026-07-25T14:13:56.839111Z","end":"2026-07-25T14:13:56.953347Z","steps":["trace[60672516] 'process raft request' (duration: 114.092277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:57.559413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.950223ms","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-25T14:13:57.559443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.927454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-25T14:13:57.559497Z","caller":"traceutil/trace.go:171","msg":"trace[1717303190] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"332.052566ms","start":"2026-07-25T14:13:57.227426Z","end":"2026-07-25T14:13:57.559478Z","steps":["trace[1717303190] 'range keys from in-memory index tree' (duration: 331.833942ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:57.55952Z","caller":"traceutil/trace.go:171","msg":"trace[440819711] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:599; }","duration":"333.030636ms","start":"2026-07-25T14:13:57.226468Z","end":"2026-07-25T14:13:57.559499Z","steps":["trace[440819711] 'range keys from in-memory index tree' (duration: 332.792521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:57.55954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:57.227422Z","time spent":"332.109157ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-25T14:13:57.559563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:57.226457Z","time spent":"333.097318ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-25T14:13:57.745679Z","caller":"traceutil/trace.go:171","msg":"trace[1954414182] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"218.211904ms","start":"2026-07-25T14:13:57.527441Z","end":"2026-07-25T14:13:57.745653Z","steps":["trace[1954414182] 'read index received' (duration: 217.55087ms)","trace[1954414182] 'applied index is now lower than readState.Index' (duration: 660.144µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T14:13:57.745868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.406758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6tncp\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-25T14:13:57.745904Z","caller":"traceutil/trace.go:171","msg":"trace[929976204] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6tncp; range_end:; response_count:1; response_revision:600; }","duration":"218.49067ms","start":"2026-07-25T14:13:57.527405Z","end":"2026-07-25T14:13:57.745895Z","steps":["trace[929976204] 'agreement among raft nodes before linearized reading' (duration: 218.344337ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:57.745955Z","caller":"traceutil/trace.go:171","msg":"trace[603776819] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"379.871975ms","start":"2026-07-25T14:13:57.366038Z","end":"2026-07-25T14:13:57.74591Z","steps":["trace[603776819] 'process raft request' (duration: 379.089809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:57.746213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:57.366005Z","time spent":"380.09405ms","remote":"127.0.0.1:38934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T14:13:58.173027Z","caller":"traceutil/trace.go:171","msg":"trace[2124021220] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"609.119321ms","start":"2026-07-25T14:13:57.563883Z","end":"2026-07-25T14:13:58.173002Z","steps":["trace[2124021220] 'process raft request' (duration: 608.910447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:58.173199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:57.563862Z","time spent":"609.240593ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T14:13:58.173217Z","caller":"traceutil/trace.go:171","msg":"trace[1791052389] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"609.03873ms","start":"2026-07-25T14:13:57.564145Z","end":"2026-07-25T14:13:58.173184Z","steps":["trace[1791052389] 'process raft request' (duration: 608.819516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:58.17342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:57.564124Z","time spent":"609.191743ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T14:13:58.903626Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227753896943869,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T14:13:59.245883Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.073152275s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-25T14:13:59.353078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.865356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T14:13:59.353256Z","caller":"traceutil/trace.go:171","msg":"trace[1068970235] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:622; }","duration":"950.08331ms","start":"2026-07-25T14:13:58.403155Z","end":"2026-07-25T14:13:59.353238Z","steps":["trace[1068970235] 'read index received' (duration: 842.887307ms)","trace[1068970235] 'applied index is now lower than readState.Index' (duration: 107.195043ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T14:13:59.353351Z","caller":"traceutil/trace.go:171","msg":"trace[1790730273] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"1.59391668s","start":"2026-07-25T14:13:57.759413Z","end":"2026-07-25T14:13:59.35333Z","steps":["trace[1790730273] 'process raft request' (duration: 1.486703327s)","trace[1790730273] 'compare' (duration: 106.612831ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T14:13:59.353417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:57.759388Z","time spent":"1.593997572s","remote":"127.0.0.1:39024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T14:13:59.353643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.018731ms","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-25T14:13:59.353731Z","caller":"traceutil/trace.go:171","msg":"trace[138648095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:604; }","duration":"521.160804ms","start":"2026-07-25T14:13:58.832554Z","end":"2026-07-25T14:13:59.353714Z","steps":["trace[138648095] 'agreement among raft nodes before linearized reading' (duration: 520.899987ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:13:59.353657Z","caller":"traceutil/trace.go:171","msg":"trace[235570818] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"1.546702515s","start":"2026-07-25T14:13:57.806919Z","end":"2026-07-25T14:13:59.353621Z","steps":["trace[235570818] 'process raft request' (duration: 1.546248055s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:59.353734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.609598ms","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-25T14:13:59.353777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:58.832534Z","time spent":"521.234545ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-25T14:13:59.35379Z","caller":"traceutil/trace.go:171","msg":"trace[1674560225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:604; }","duration":"391.70601ms","start":"2026-07-25T14:13:58.96207Z","end":"2026-07-25T14:13:59.353776Z","steps":["trace[1674560225] 'agreement among raft nodes before linearized reading' (duration: 391.517136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:59.35382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:58.962054Z","time spent":"391.760571ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-25T14:13:59.35387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:57.806894Z","time spent":"1.546883638s","remote":"127.0.0.1:39362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T14:13:59.354121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.952688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T14:13:59.354176Z","caller":"traceutil/trace.go:171","msg":"trace[2128004730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"951.04603ms","start":"2026-07-25T14:13:58.403115Z","end":"2026-07-25T14:13:59.354161Z","steps":["trace[2128004730] 'agreement among raft nodes before linearized reading' (duration: 950.235103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:13:59.35421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:58.403094Z","time spent":"951.109001ms","remote":"127.0.0.1:38818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T14:14:01.030285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.220552196s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T14:14:01.030468Z","caller":"traceutil/trace.go:171","msg":"trace[170504708] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:624; }","duration":"1.666888055s","start":"2026-07-25T14:13:59.363552Z","end":"2026-07-25T14:14:01.03044Z","steps":["trace[170504708] 'read index received' (duration: 446.063355ms)","trace[170504708] 'applied index is now lower than readState.Index' (duration: 1.22082201s)"],"step_count":2} {"level":"info","ts":"2026-07-25T14:14:01.030512Z","caller":"traceutil/trace.go:171","msg":"trace[1007873244] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"1.672070507s","start":"2026-07-25T14:13:59.358415Z","end":"2026-07-25T14:14:01.030485Z","steps":["trace[1007873244] 'process raft request' (duration: 451.234836ms)","trace[1007873244] 'compare' (duration: 1.219757441s)"],"step_count":2} {"level":"info","ts":"2026-07-25T14:14:01.030557Z","caller":"traceutil/trace.go:171","msg":"trace[475021] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"1.67167553s","start":"2026-07-25T14:13:59.358873Z","end":"2026-07-25T14:14:01.030548Z","steps":["trace[475021] 'process raft request' (duration: 1.671504657s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:14:01.030594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:59.358403Z","time spent":"1.672146309s","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T14:14:01.030613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:59.358847Z","time spent":"1.671733051s","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T14:14:01.030614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.62759477s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T14:14:01.030667Z","caller":"traceutil/trace.go:171","msg":"trace[395267072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"1.627656841s","start":"2026-07-25T14:13:59.402997Z","end":"2026-07-25T14:14:01.030654Z","steps":["trace[395267072] 'agreement among raft nodes before linearized reading' (duration: 1.62756373s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:14:01.030696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:59.402974Z","time spent":"1.627715763s","remote":"127.0.0.1:38818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T14:14:01.030748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.063877ms","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-25T14:14:01.03078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.948855ms","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-25T14:14:01.030763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.667180231s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T14:14:01.030814Z","caller":"traceutil/trace.go:171","msg":"trace[1043332999] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:606; }","duration":"854.008146ms","start":"2026-07-25T14:14:00.176795Z","end":"2026-07-25T14:14:01.030803Z","steps":["trace[1043332999] 'agreement among raft nodes before linearized reading' (duration: 853.893314ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:14:01.030816Z","caller":"traceutil/trace.go:171","msg":"trace[331667750] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:606; }","duration":"853.184039ms","start":"2026-07-25T14:14:00.177611Z","end":"2026-07-25T14:14:01.030795Z","steps":["trace[331667750] 'agreement among raft nodes before linearized reading' (duration: 853.009026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:14:01.030844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:14:00.176779Z","time spent":"854.058187ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-25T14:14:01.030844Z","caller":"traceutil/trace.go:171","msg":"trace[11386911] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:606; }","duration":"1.667285783s","start":"2026-07-25T14:13:59.363535Z","end":"2026-07-25T14:14:01.030821Z","steps":["trace[11386911] 'agreement among raft nodes before linearized reading' (duration: 1.66714498s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:14:01.030889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:13:59.363527Z","time spent":"1.667353454s","remote":"127.0.0.1:39076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-25T14:14:01.030888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:14:00.177588Z","time spent":"853.277341ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-25T14:14:01.216235Z","caller":"traceutil/trace.go:171","msg":"trace[637509562] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"180.715585ms","start":"2026-07-25T14:14:01.035481Z","end":"2026-07-25T14:14:01.216196Z","steps":["trace[637509562] 'process raft request' (duration: 134.954848ms)","trace[637509562] 'compare' (duration: 45.505042ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T14:14:01.216362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.558433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T14:14:01.216601Z","caller":"traceutil/trace.go:171","msg":"trace[1975874617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"177.849481ms","start":"2026-07-25T14:14:01.03874Z","end":"2026-07-25T14:14:01.21659Z","steps":["trace[1975874617] 'agreement among raft nodes before linearized reading' (duration: 177.528463ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:14:01.216358Z","caller":"traceutil/trace.go:171","msg":"trace[1363904440] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:626; }","duration":"177.281768ms","start":"2026-07-25T14:14:01.038772Z","end":"2026-07-25T14:14:01.216054Z","steps":["trace[1363904440] 'read index received' (duration: 15.897459ms)","trace[1363904440] 'applied index is now lower than readState.Index' (duration: 161.383439ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T14:14:03.129346Z","caller":"traceutil/trace.go:171","msg":"trace[618865163] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"353.428931ms","start":"2026-07-25T14:14:02.7759Z","end":"2026-07-25T14:14:03.129329Z","steps":["trace[618865163] 'process raft request' (duration: 353.340599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:14:03.129461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:14:02.775875Z","time spent":"353.537883ms","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T14:14:03.136647Z","caller":"traceutil/trace.go:171","msg":"trace[1013608577] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"135.16358ms","start":"2026-07-25T14:14:03.001452Z","end":"2026-07-25T14:14:03.136615Z","steps":["trace[1013608577] 'read index received' (duration: 127.8793ms)","trace[1013608577] 'applied index is now lower than readState.Index' (duration: 7.28299ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T14:14:03.136941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.469167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-28ggz\" ","response":"range_response_count:1 size:4888"} {"level":"info","ts":"2026-07-25T14:14:03.13699Z","caller":"traceutil/trace.go:171","msg":"trace[1867908909] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-28ggz; range_end:; response_count:1; response_revision:638; }","duration":"135.558158ms","start":"2026-07-25T14:14:03.001419Z","end":"2026-07-25T14:14:03.136977Z","steps":["trace[1867908909] 'agreement among raft nodes before linearized reading' (duration: 135.349124ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:14:03.136718Z","caller":"traceutil/trace.go:171","msg":"trace[948924705] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"161.273843ms","start":"2026-07-25T14:14:02.975413Z","end":"2026-07-25T14:14:03.136687Z","steps":["trace[948924705] 'process raft request' (duration: 161.047639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:14:03.140992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.81628ms","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-25T14:14:03.141031Z","caller":"traceutil/trace.go:171","msg":"trace[1484552387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"106.887381ms","start":"2026-07-25T14:14:03.034133Z","end":"2026-07-25T14:14:03.14102Z","steps":["trace[1484552387] 'agreement among raft nodes before linearized reading' (duration: 106.789278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:14:03.140977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.84562ms","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-25T14:14:03.141119Z","caller":"traceutil/trace.go:171","msg":"trace[1808697515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"106.973723ms","start":"2026-07-25T14:14:03.034084Z","end":"2026-07-25T14:14:03.141058Z","steps":["trace[1808697515] 'agreement among raft nodes before linearized reading' (duration: 106.746418ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:14:13.822165Z","caller":"traceutil/trace.go:171","msg":"trace[1312908463] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"100.589078ms","start":"2026-07-25T14:14:13.721554Z","end":"2026-07-25T14:14:13.822143Z","steps":["trace[1312908463] 'process raft request' (duration: 100.529817ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:14:13.822457Z","caller":"traceutil/trace.go:171","msg":"trace[1126009114] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"103.031201ms","start":"2026-07-25T14:14:13.719352Z","end":"2026-07-25T14:14:13.822383Z","steps":["trace[1126009114] 'process raft request' (duration: 102.606564ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:14:15.001791Z","caller":"traceutil/trace.go:171","msg":"trace[462199693] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"125.42509ms","start":"2026-07-25T14:14:14.876336Z","end":"2026-07-25T14:14:15.001761Z","steps":["trace[462199693] 'process raft request' (duration: 108.597005ms)","trace[462199693] 'compare' (duration: 16.718353ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T14:14:16.061268Z","caller":"traceutil/trace.go:171","msg":"trace[1375612162] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"719.909764ms","start":"2026-07-25T14:14:15.341334Z","end":"2026-07-25T14:14:16.061244Z","steps":["trace[1375612162] 'process raft request' (duration: 718.935407ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:14:16.061407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:14:15.341314Z","time spent":"720.021656ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T14:14:21.57124Z","caller":"traceutil/trace.go:171","msg":"trace[929082014] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"133.743984ms","start":"2026-07-25T14:14:21.437475Z","end":"2026-07-25T14:14:21.571219Z","steps":["trace[929082014] 'process raft request' (duration: 133.615841ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:14:21.571876Z","caller":"traceutil/trace.go:171","msg":"trace[34204564] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"133.946237ms","start":"2026-07-25T14:14:21.43792Z","end":"2026-07-25T14:14:21.571866Z","steps":["trace[34204564] 'process raft request' (duration: 133.883506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:14:23.386741Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227753896944311,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T14:14:23.887005Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227753896944311,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T14:14:24.1435Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.436791586s","expected-duration":"1s"} {"level":"info","ts":"2026-07-25T14:14:24.143987Z","caller":"traceutil/trace.go:171","msg":"trace[1668142763] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:815; }","duration":"1.258127072s","start":"2026-07-25T14:14:22.885838Z","end":"2026-07-25T14:14:24.143965Z","steps":["trace[1668142763] 'read index received' (duration: 1.257805747s)","trace[1668142763] 'applied index is now lower than readState.Index' (duration: 320.415µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T14:14:24.144254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.258369217s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-25T14:14:24.144407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.29044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T14:14:24.144044Z","caller":"traceutil/trace.go:171","msg":"trace[810406850] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"1.437427676s","start":"2026-07-25T14:14:22.706586Z","end":"2026-07-25T14:14:24.144014Z","steps":["trace[810406850] 'process raft request' (duration: 1.437143461s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:14:24.144445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.302831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-25T14:14:24.144553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.397288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-25T14:14:24.144601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.504235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-25T14:14:24.144627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.131528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-25T14:14:24.144649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.834767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T14:14:24.144688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.638677ms","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-25T14:14:24.144722Z","caller":"traceutil/trace.go:171","msg":"trace[1013707958] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:789; }","duration":"1.258557599s","start":"2026-07-25T14:14:22.885784Z","end":"2026-07-25T14:14:24.144342Z","steps":["trace[1013707958] 'agreement among raft nodes before linearized reading' (duration: 1.258309735s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:14:24.144704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:14:22.706559Z","time spent":"1.437967425s","remote":"127.0.0.1:39076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T14:14:24.144749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:14:22.885764Z","time spent":"1.258979917s","remote":"127.0.0.1:39110","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":"info","ts":"2026-07-25T14:14:24.145161Z","caller":"traceutil/trace.go:171","msg":"trace[1474764223] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:789; }","duration":"320.373422ms","start":"2026-07-25T14:14:23.824111Z","end":"2026-07-25T14:14:24.144485Z","steps":["trace[1474764223] 'agreement among raft nodes before linearized reading' (duration: 320.318051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:14:24.145252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:14:23.824099Z","time spent":"321.119943ms","remote":"127.0.0.1:39362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-07-25T14:14:24.14533Z","caller":"traceutil/trace.go:171","msg":"trace[438834444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:789; }","duration":"568.445188ms","start":"2026-07-25T14:14:23.576121Z","end":"2026-07-25T14:14:24.144566Z","steps":["trace[438834444] 'agreement among raft nodes before linearized reading' (duration: 568.365837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:14:24.145435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:14:23.576104Z","time spent":"569.307922ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-25T14:14:24.145517Z","caller":"traceutil/trace.go:171","msg":"trace[2052440934] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:789; }","duration":"569.550167ms","start":"2026-07-25T14:14:23.575061Z","end":"2026-07-25T14:14:24.144611Z","steps":["trace[2052440934] 'agreement among raft nodes before linearized reading' (duration: 569.507186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:14:24.1456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:14:23.575044Z","time spent":"570.539783ms","remote":"127.0.0.1:39110","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-25T14:14:24.14561Z","caller":"traceutil/trace.go:171","msg":"trace[604793327] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:789; }","duration":"609.176069ms","start":"2026-07-25T14:14:23.53546Z","end":"2026-07-25T14:14:24.144636Z","steps":["trace[604793327] 'agreement among raft nodes before linearized reading' (duration: 609.151709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:14:24.14567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:14:23.535444Z","time spent":"610.209626ms","remote":"127.0.0.1:38940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-07-25T14:14:24.145787Z","caller":"traceutil/trace.go:171","msg":"trace[558831278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:789; }","duration":"743.860717ms","start":"2026-07-25T14:14:23.400797Z","end":"2026-07-25T14:14:24.144657Z","steps":["trace[558831278] 'agreement among raft nodes before linearized reading' (duration: 743.841197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:14:24.145854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:14:23.400791Z","time spent":"745.052127ms","remote":"127.0.0.1:38818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T14:14:24.14596Z","caller":"traceutil/trace.go:171","msg":"trace[798343959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:789; }","duration":"748.674997ms","start":"2026-07-25T14:14:23.396021Z","end":"2026-07-25T14:14:24.144696Z","steps":["trace[798343959] 'agreement among raft nodes before linearized reading' (duration: 748.638026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:14:24.146014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:14:23.396003Z","time spent":"749.997509ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-25T14:14:24.144943Z","caller":"traceutil/trace.go:171","msg":"trace[1351209409] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:789; }","duration":"825.344081ms","start":"2026-07-25T14:14:23.319084Z","end":"2026-07-25T14:14:24.144428Z","steps":["trace[1351209409] 'agreement among raft nodes before linearized reading' (duration: 825.29817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:14:24.146242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:14:23.31907Z","time spent":"827.12486ms","remote":"127.0.0.1:38930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-07-25T14:14:24.278452Z","caller":"traceutil/trace.go:171","msg":"trace[2076670101] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"129.140312ms","start":"2026-07-25T14:14:24.149289Z","end":"2026-07-25T14:14:24.27843Z","steps":["trace[2076670101] 'process raft request' (duration: 128.509412ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:14:24.285545Z","caller":"traceutil/trace.go:171","msg":"trace[2049130459] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"135.495086ms","start":"2026-07-25T14:14:24.15004Z","end":"2026-07-25T14:14:24.285535Z","steps":["trace[2049130459] 'process raft request' (duration: 135.430765ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:14:24.285534Z","caller":"traceutil/trace.go:171","msg":"trace[1944470758] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"135.507977ms","start":"2026-07-25T14:14:24.150001Z","end":"2026-07-25T14:14:24.285509Z","steps":["trace[1944470758] 'process raft request' (duration: 135.372075ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:14:24.285758Z","caller":"traceutil/trace.go:171","msg":"trace[59624862] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:816; }","duration":"116.67981ms","start":"2026-07-25T14:14:24.169052Z","end":"2026-07-25T14:14:24.285732Z","steps":["trace[59624862] 'read index received' (duration: 108.75851ms)","trace[59624862] 'applied index is now lower than readState.Index' (duration: 7.919509ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T14:14:24.285828Z","caller":"traceutil/trace.go:171","msg":"trace[779337884] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"135.62788ms","start":"2026-07-25T14:14:24.150175Z","end":"2026-07-25T14:14:24.285803Z","steps":["trace[779337884] 'process raft request' (duration: 135.324614ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:14:24.285953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.878303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-25T14:14:24.285992Z","caller":"traceutil/trace.go:171","msg":"trace[1197156812] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:793; }","duration":"116.965565ms","start":"2026-07-25T14:14:24.169017Z","end":"2026-07-25T14:14:24.285982Z","steps":["trace[1197156812] 'agreement among raft nodes before linearized reading' (duration: 116.806632ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:14:28.985115Z","caller":"traceutil/trace.go:171","msg":"trace[746102357] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"115.609352ms","start":"2026-07-25T14:14:28.869477Z","end":"2026-07-25T14:14:28.985086Z","steps":["trace[746102357] 'process raft request' (duration: 113.532989ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:14:28.985175Z","caller":"traceutil/trace.go:171","msg":"trace[537723827] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"115.667633ms","start":"2026-07-25T14:14:28.869477Z","end":"2026-07-25T14:14:28.985144Z","steps":["trace[537723827] 'process raft request' (duration: 115.546321ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:14:28.985114Z","caller":"traceutil/trace.go:171","msg":"trace[405347976] linearizableReadLoop","detail":"{readStateIndex:878; appliedIndex:876; }","duration":"110.980039ms","start":"2026-07-25T14:14:28.874109Z","end":"2026-07-25T14:14:28.985089Z","steps":["trace[405347976] 'read index received' (duration: 108.905976ms)","trace[405347976] 'applied index is now lower than readState.Index' (duration: 2.073123ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T14:14:28.985321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.181903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/\" range_end:\"/registry/serviceaccounts/envoy-gateway-system0\" limit:10000 ","response":"range_response_count:3 size:1862"} {"level":"info","ts":"2026-07-25T14:14:28.985371Z","caller":"traceutil/trace.go:171","msg":"trace[492901000] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/; range_end:/registry/serviceaccounts/envoy-gateway-system0; response_count:3; response_revision:850; }","duration":"111.275654ms","start":"2026-07-25T14:14:28.87408Z","end":"2026-07-25T14:14:28.985356Z","steps":["trace[492901000] 'agreement among raft nodes before linearized reading' (duration: 111.099561ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:14:32.805339Z","caller":"traceutil/trace.go:171","msg":"trace[685315981] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"106.323893ms","start":"2026-07-25T14:14:32.698991Z","end":"2026-07-25T14:14:32.805315Z","steps":["trace[685315981] 'process raft request' (duration: 106.15143ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:14:41.17146Z","caller":"traceutil/trace.go:171","msg":"trace[1677524168] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"103.682767ms","start":"2026-07-25T14:14:41.067755Z","end":"2026-07-25T14:14:41.171438Z","steps":["trace[1677524168] 'process raft request' (duration: 103.542405ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:14:46.768801Z","caller":"traceutil/trace.go:171","msg":"trace[596447833] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1021; }","duration":"141.417675ms","start":"2026-07-25T14:14:46.627364Z","end":"2026-07-25T14:14:46.768781Z","steps":["trace[596447833] 'read index received' (duration: 29.943669ms)","trace[596447833] 'applied index is now lower than readState.Index' (duration: 111.473166ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T14:14:46.768903Z","caller":"traceutil/trace.go:171","msg":"trace[1268177440] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"176.367716ms","start":"2026-07-25T14:14:46.592476Z","end":"2026-07-25T14:14:46.768844Z","steps":["trace[1268177440] 'process raft request' (duration: 109.400937ms)","trace[1268177440] 'compare' (duration: 66.828457ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T14:14:46.768992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.694672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-25T14:14:46.768993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.625839ms","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-25T14:14:46.769025Z","caller":"traceutil/trace.go:171","msg":"trace[729725164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:991; }","duration":"137.739993ms","start":"2026-07-25T14:14:46.631274Z","end":"2026-07-25T14:14:46.769014Z","steps":["trace[729725164] 'agreement among raft nodes before linearized reading' (duration: 137.626181ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:14:46.769054Z","caller":"traceutil/trace.go:171","msg":"trace[672674464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:991; }","duration":"141.72072ms","start":"2026-07-25T14:14:46.627316Z","end":"2026-07-25T14:14:46.769037Z","steps":["trace[672674464] 'agreement among raft nodes before linearized reading' (duration: 141.574038ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:14:47.372238Z","caller":"traceutil/trace.go:171","msg":"trace[1217835511] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"179.148313ms","start":"2026-07-25T14:14:47.193061Z","end":"2026-07-25T14:14:47.372209Z","steps":["trace[1217835511] 'process raft request' (duration: 178.879259ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:14:58.928283Z","caller":"traceutil/trace.go:171","msg":"trace[2058936902] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"103.694158ms","start":"2026-07-25T14:14:58.824537Z","end":"2026-07-25T14:14:58.928232Z","steps":["trace[2058936902] 'process raft request' (duration: 103.488165ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:16:25.329575Z","caller":"traceutil/trace.go:171","msg":"trace[564150438] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"114.810819ms","start":"2026-07-25T14:16:25.214729Z","end":"2026-07-25T14:16:25.32954Z","steps":["trace[564150438] 'process raft request' (duration: 114.619177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:16:25.489308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.818846ms","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-25T14:16:25.489404Z","caller":"traceutil/trace.go:171","msg":"trace[800826655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1333; }","duration":"106.942847ms","start":"2026-07-25T14:16:25.382428Z","end":"2026-07-25T14:16:25.489371Z","steps":["trace[800826655] 'range keys from in-memory index tree' (duration: 106.629343ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:16:25.489808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.246322ms","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-25T14:16:25.49045Z","caller":"traceutil/trace.go:171","msg":"trace[326021380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1333; }","duration":"112.332673ms","start":"2026-07-25T14:16:25.378088Z","end":"2026-07-25T14:16:25.490421Z","steps":["trace[326021380] 'range keys from in-memory index tree' (duration: 111.044051ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:16:29.958476Z","caller":"traceutil/trace.go:171","msg":"trace[1180544529] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"121.44965ms","start":"2026-07-25T14:16:29.836974Z","end":"2026-07-25T14:16:29.958424Z","steps":["trace[1180544529] 'process raft request' (duration: 120.811954ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:16:33.533312Z","caller":"traceutil/trace.go:171","msg":"trace[852009778] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"160.761939ms","start":"2026-07-25T14:16:33.372513Z","end":"2026-07-25T14:16:33.533275Z","steps":["trace[852009778] 'process raft request' (duration: 160.526656ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:16:33.533223Z","caller":"traceutil/trace.go:171","msg":"trace[186998351] linearizableReadLoop","detail":"{readStateIndex:1432; appliedIndex:1431; }","duration":"136.807002ms","start":"2026-07-25T14:16:33.396395Z","end":"2026-07-25T14:16:33.533202Z","steps":["trace[186998351] 'read index received' (duration: 136.64163ms)","trace[186998351] 'applied index is now lower than readState.Index' (duration: 164.552µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T14:16:33.533699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.260296ms","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-25T14:16:33.533799Z","caller":"traceutil/trace.go:171","msg":"trace[974697410] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1375; }","duration":"137.415849ms","start":"2026-07-25T14:16:33.396361Z","end":"2026-07-25T14:16:33.533777Z","steps":["trace[974697410] 'agreement among raft nodes before linearized reading' (duration: 137.054545ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:16:40.041697Z","caller":"traceutil/trace.go:171","msg":"trace[1836632656] linearizableReadLoop","detail":"{readStateIndex:1461; appliedIndex:1459; }","duration":"342.104294ms","start":"2026-07-25T14:16:39.699572Z","end":"2026-07-25T14:16:40.041676Z","steps":["trace[1836632656] 'read index received' (duration: 341.939151ms)","trace[1836632656] 'applied index is now lower than readState.Index' (duration: 164.483µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T14:16:40.041738Z","caller":"traceutil/trace.go:171","msg":"trace[740526267] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"423.670109ms","start":"2026-07-25T14:16:39.618042Z","end":"2026-07-25T14:16:40.041712Z","steps":["trace[740526267] 'process raft request' (duration: 423.520927ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:16:40.041779Z","caller":"traceutil/trace.go:171","msg":"trace[1305749909] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"423.73083ms","start":"2026-07-25T14:16:39.618043Z","end":"2026-07-25T14:16:40.041774Z","steps":["trace[1305749909] 'process raft request' (duration: 423.611318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:16:40.041856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:16:39.618019Z","time spent":"423.77847ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T14:16:40.041865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:16:39.618019Z","time spent":"423.77965ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T14:16:40.042075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.518917ms","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-25T14:16:40.042094Z","caller":"traceutil/trace.go:171","msg":"trace[1249629780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1403; }","duration":"342.586459ms","start":"2026-07-25T14:16:39.699502Z","end":"2026-07-25T14:16:40.042088Z","steps":["trace[1249629780] 'agreement among raft nodes before linearized reading' (duration: 342.502748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:16:40.042109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:16:39.699468Z","time spent":"342.637479ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-25T14:16:40.042177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.759349ms","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-25T14:16:40.042187Z","caller":"traceutil/trace.go:171","msg":"trace[1909365565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1403; }","duration":"338.84471ms","start":"2026-07-25T14:16:39.703339Z","end":"2026-07-25T14:16:40.042184Z","steps":["trace[1909365565] 'agreement among raft nodes before linearized reading' (duration: 338.808689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:16:40.042199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:16:39.703309Z","time spent":"338.88701ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-25T14:16:40.517839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.978883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T14:16:40.518094Z","caller":"traceutil/trace.go:171","msg":"trace[769362007] linearizableReadLoop","detail":"{readStateIndex:1464; appliedIndex:1461; }","duration":"207.674415ms","start":"2026-07-25T14:16:40.310402Z","end":"2026-07-25T14:16:40.518076Z","steps":["trace[769362007] 'read index received' (duration: 158.635573ms)","trace[769362007] 'applied index is now lower than readState.Index' (duration: 49.037852ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T14:16:40.518336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.705314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T14:16:40.518348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.931918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T14:16:40.518479Z","caller":"traceutil/trace.go:171","msg":"trace[52803890] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"472.986248ms","start":"2026-07-25T14:16:40.04546Z","end":"2026-07-25T14:16:40.518446Z","steps":["trace[52803890] 'process raft request' (duration: 104.311737ms)","trace[52803890] 'compare' (duration: 367.6651ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T14:16:40.518525Z","caller":"traceutil/trace.go:171","msg":"trace[2116028534] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"470.899766ms","start":"2026-07-25T14:16:40.04754Z","end":"2026-07-25T14:16:40.51844Z","steps":["trace[2116028534] 'process raft request' (duration: 470.495082ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:16:40.518649Z","caller":"traceutil/trace.go:171","msg":"trace[916209029] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1406; }","duration":"208.058498ms","start":"2026-07-25T14:16:40.310333Z","end":"2026-07-25T14:16:40.518392Z","steps":["trace[916209029] 'agreement among raft nodes before linearized reading' (duration: 207.949947ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:16:40.518372Z","caller":"traceutil/trace.go:171","msg":"trace[1071966252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1406; }","duration":"113.792554ms","start":"2026-07-25T14:16:40.40457Z","end":"2026-07-25T14:16:40.518363Z","steps":["trace[1071966252] 'agreement among raft nodes before linearized reading' (duration: 113.736284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:16:40.518608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:16:40.045443Z","time spent":"473.115969ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T14:16:40.518525Z","caller":"traceutil/trace.go:171","msg":"trace[2104698709] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"472.556103ms","start":"2026-07-25T14:16:40.045927Z","end":"2026-07-25T14:16:40.518483Z","steps":["trace[2104698709] 'process raft request' (duration: 472.010608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:16:40.519211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:16:40.045916Z","time spent":"473.23196ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T14:16:40.519325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T14:16:40.047534Z","time spent":"471.195849ms","remote":"127.0.0.1:39110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T14:16:42.707559Z","caller":"traceutil/trace.go:171","msg":"trace[1698000697] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"173.5153ms","start":"2026-07-25T14:16:42.532768Z","end":"2026-07-25T14:16:42.706283Z","steps":["trace[1698000697] 'process raft request' (duration: 173.321318ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:16:42.718041Z","caller":"traceutil/trace.go:171","msg":"trace[1933065518] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"181.836185ms","start":"2026-07-25T14:16:42.536183Z","end":"2026-07-25T14:16:42.718019Z","steps":["trace[1933065518] 'process raft request' (duration: 181.675243ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:16:42.718232Z","caller":"traceutil/trace.go:171","msg":"trace[1494710591] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"176.890714ms","start":"2026-07-25T14:16:42.541268Z","end":"2026-07-25T14:16:42.718159Z","steps":["trace[1494710591] 'process raft request' (duration: 176.715593ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:17:04.580362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.80422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-proxy\" ","response":"range_response_count:1 size:1949"} {"level":"info","ts":"2026-07-25T14:17:04.58045Z","caller":"traceutil/trace.go:171","msg":"trace[1664727501] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1487; }","duration":"145.917225ms","start":"2026-07-25T14:17:04.434515Z","end":"2026-07-25T14:17:04.580432Z","steps":["trace[1664727501] 'range keys from in-memory index tree' (duration: 145.718428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:17:04.580435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.828009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-root-ca.crt\" ","response":"range_response_count:1 size:1746"} {"level":"info","ts":"2026-07-25T14:17:04.580563Z","caller":"traceutil/trace.go:171","msg":"trace[960304198] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-root-ca.crt; range_end:; response_count:1; response_revision:1487; }","duration":"112.987295ms","start":"2026-07-25T14:17:04.467548Z","end":"2026-07-25T14:17:04.580535Z","steps":["trace[960304198] 'range keys from in-memory index tree' (duration: 112.706076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:17:04.580636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.084424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T14:17:04.580781Z","caller":"traceutil/trace.go:171","msg":"trace[1332528816] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1487; }","duration":"125.766066ms","start":"2026-07-25T14:17:04.454995Z","end":"2026-07-25T14:17:04.580762Z","steps":["trace[1332528816] 'count revisions from in-memory index tree' (duration: 124.999852ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:17:15.042596Z","caller":"traceutil/trace.go:171","msg":"trace[677026787] transaction","detail":"{read_only:false; response_revision:1522; number_of_response:1; }","duration":"128.490516ms","start":"2026-07-25T14:17:14.914069Z","end":"2026-07-25T14:17:15.042559Z","steps":["trace[677026787] 'process raft request' (duration: 58.395701ms)","trace[677026787] 'compare' (duration: 69.909249ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T14:17:15.131429Z","caller":"traceutil/trace.go:171","msg":"trace[308099343] linearizableReadLoop","detail":"{readStateIndex:1590; appliedIndex:1587; }","duration":"161.02486ms","start":"2026-07-25T14:17:14.970377Z","end":"2026-07-25T14:17:15.131402Z","steps":["trace[308099343] 'read index received' (duration: 2.059723ms)","trace[308099343] 'applied index is now lower than readState.Index' (duration: 158.963617ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T14:17:15.131493Z","caller":"traceutil/trace.go:171","msg":"trace[194879422] transaction","detail":"{read_only:false; response_revision:1523; number_of_response:1; }","duration":"217.278976ms","start":"2026-07-25T14:17:14.914186Z","end":"2026-07-25T14:17:15.131465Z","steps":["trace[194879422] 'process raft request' (duration: 216.973587ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T14:17:15.131561Z","caller":"traceutil/trace.go:171","msg":"trace[1042480739] transaction","detail":"{read_only:false; response_revision:1524; number_of_response:1; }","duration":"213.332615ms","start":"2026-07-25T14:17:14.918189Z","end":"2026-07-25T14:17:15.131522Z","steps":["trace[1042480739] 'process raft request' (duration: 213.145139ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:17:15.131885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.447544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:6 size:2185"} {"level":"info","ts":"2026-07-25T14:17:15.132097Z","caller":"traceutil/trace.go:171","msg":"trace[1393172022] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:6; response_revision:1524; }","duration":"161.583038ms","start":"2026-07-25T14:17:14.970364Z","end":"2026-07-25T14:17:15.131947Z","steps":["trace[1393172022] 'agreement among raft nodes before linearized reading' (duration: 161.142224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T14:17:16.696445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.862961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6tncp\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-07-25T14:17:16.696546Z","caller":"traceutil/trace.go:171","msg":"trace[1118673651] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6tncp; range_end:; response_count:1; response_revision:1527; }","duration":"171.982935ms","start":"2026-07-25T14:17:16.524542Z","end":"2026-07-25T14:17:16.696524Z","steps":["trace[1118673651] 'range keys from in-memory index tree' (duration: 171.608764ms)"],"step_count":1}