{"level":"warn","ts":"2026-02-07T02:28:00.871126Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-07T02:28:00.871216Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49: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.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49: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-02-07T02:28:00.871408Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-07T02:28:00.871574Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-02-07T02:28:00.871781Z","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-02-07T02:28:00.872452Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-02-07T02:28:00.877108Z","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":2,"max-cpu-available":2,"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.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49: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-02-07T02:28:00.90042Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"14.846286ms"} {"level":"info","ts":"2026-02-07T02:28:00.93248Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-02-07T02:28:00.932652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-02-07T02:28:00.932729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-02-07T02:28:00.932747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-07T02:28:00.932814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-02-07T02:28:00.932954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-02-07T02:28:01.141455Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-07T02:28:01.17886Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-07T02:28:01.256037Z","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-02-07T02:28:01.29768Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-07T02:28:01.297887Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-07T02:28:01.298847Z","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-02-07T02:28:01.298936Z","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-02-07T02:28:01.299005Z","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-02-07T02:28:01.309733Z","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-02-07T02:28:01.30998Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-07T02:28:01.310012Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-07T02:28:01.31049Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-02-07T02:28:01.312313Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-02-07T02:28:01.317149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-02-07T02:28:01.317287Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-02-07T02:28:01.933364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-02-07T02:28:01.933472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-02-07T02:28:01.933518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-02-07T02:28:01.933556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-02-07T02:28:01.933576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-02-07T02:28:01.933594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-02-07T02:28:01.933626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-02-07T02:28:01.97301Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-02-07T02:28:01.973023Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T02:28:01.973298Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T02:28:01.974187Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T02:28:01.977238Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-07T02:28:01.977577Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-07T02:28:01.977683Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-07T02:28:01.979253Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-02-07T02:28:01.994194Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T02:28:01.994347Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T02:28:01.994369Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-07T02:28:02.259814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.193457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-07T02:28:02.259884Z","caller":"traceutil/trace.go:171","msg":"trace[1901852408] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"169.323631ms","start":"2026-02-07T02:28:02.09055Z","end":"2026-02-07T02:28:02.259873Z","steps":["trace[1901852408] 'count revisions from in-memory index tree' (duration: 169.130446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:02.260695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.965264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-07T02:28:02.260725Z","caller":"traceutil/trace.go:171","msg":"trace[952907284] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"155.011136ms","start":"2026-02-07T02:28:02.105707Z","end":"2026-02-07T02:28:02.260718Z","steps":["trace[952907284] 'count revisions from in-memory index tree' (duration: 154.936034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:02.261144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.472787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-07T02:28:02.261304Z","caller":"traceutil/trace.go:171","msg":"trace[983052156] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"155.520937ms","start":"2026-02-07T02:28:02.105644Z","end":"2026-02-07T02:28:02.261165Z","steps":["trace[983052156] 'range keys from in-memory index tree' (duration: 155.373904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:02.261549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.923807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-07T02:28:02.261576Z","caller":"traceutil/trace.go:171","msg":"trace[2069315949] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"140.996899ms","start":"2026-02-07T02:28:02.120573Z","end":"2026-02-07T02:28:02.26157Z","steps":["trace[2069315949] 'range keys from in-memory index tree' (duration: 140.879916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:02.26205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.433251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-07T02:28:02.262648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.00914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-07T02:28:02.263206Z","caller":"traceutil/trace.go:171","msg":"trace[248505658] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"102.597494ms","start":"2026-02-07T02:28:02.160599Z","end":"2026-02-07T02:28:02.263196Z","steps":["trace[248505658] 'range keys from in-memory index tree' (duration: 101.98137ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:02.262082Z","caller":"traceutil/trace.go:171","msg":"trace[1674995416] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"139.464012ms","start":"2026-02-07T02:28:02.122608Z","end":"2026-02-07T02:28:02.262072Z","steps":["trace[1674995416] 'count revisions from in-memory index tree' (duration: 139.407791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:02.263354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.808845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-07T02:28:02.265481Z","caller":"traceutil/trace.go:171","msg":"trace[1699427943] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"103.669439ms","start":"2026-02-07T02:28:02.161476Z","end":"2026-02-07T02:28:02.265145Z","steps":["trace[1699427943] 'count revisions from in-memory index tree' (duration: 101.709632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:02.263765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.5971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-07T02:28:02.265561Z","caller":"traceutil/trace.go:171","msg":"trace[1706319668] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"124.402203ms","start":"2026-02-07T02:28:02.14115Z","end":"2026-02-07T02:28:02.265553Z","steps":["trace[1706319668] 'count revisions from in-memory index tree' (duration: 122.57232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:02.263789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.92894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-07T02:28:02.265713Z","caller":"traceutil/trace.go:171","msg":"trace[703948946] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"134.860746ms","start":"2026-02-07T02:28:02.130847Z","end":"2026-02-07T02:28:02.265708Z","steps":["trace[703948946] 'count revisions from in-memory index tree' (duration: 132.90971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:02.263814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.012263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-07T02:28:02.265898Z","caller":"traceutil/trace.go:171","msg":"trace[1567228772] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"135.114532ms","start":"2026-02-07T02:28:02.130777Z","end":"2026-02-07T02:28:02.265892Z","steps":["trace[1567228772] 'range keys from in-memory index tree' (duration: 132.89677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:02.263836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.18183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-07T02:28:02.266234Z","caller":"traceutil/trace.go:171","msg":"trace[1503338083] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"115.588096ms","start":"2026-02-07T02:28:02.15064Z","end":"2026-02-07T02:28:02.266228Z","steps":["trace[1503338083] 'count revisions from in-memory index tree' (duration: 113.1645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:02.263859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.978249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-07T02:28:02.267079Z","caller":"traceutil/trace.go:171","msg":"trace[1564926804] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"126.211474ms","start":"2026-02-07T02:28:02.140856Z","end":"2026-02-07T02:28:02.267067Z","steps":["trace[1564926804] 'range keys from in-memory index tree' (duration: 122.657092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:02.263882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.265902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-07T02:28:02.267301Z","caller":"traceutil/trace.go:171","msg":"trace[1883511217] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"116.695031ms","start":"2026-02-07T02:28:02.150599Z","end":"2026-02-07T02:28:02.267295Z","steps":["trace[1883511217] 'range keys from in-memory index tree' (duration: 113.201651ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:03.781395Z","caller":"traceutil/trace.go:171","msg":"trace[201120701] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"128.014367ms","start":"2026-02-07T02:28:03.653355Z","end":"2026-02-07T02:28:03.781369Z","steps":["trace[201120701] 'process raft request' (duration: 75.643115ms)","trace[201120701] 'compare' (duration: 52.235368ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:28:03.784016Z","caller":"traceutil/trace.go:171","msg":"trace[11717690] linearizableReadLoop","detail":"{readStateIndex:28; appliedIndex:26; }","duration":"125.421886ms","start":"2026-02-07T02:28:03.658579Z","end":"2026-02-07T02:28:03.784001Z","steps":["trace[11717690] 'read index received' (duration: 70.427853ms)","trace[11717690] 'applied index is now lower than readState.Index' (duration: 54.992913ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:28:03.784226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.619081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-07T02:28:03.784267Z","caller":"traceutil/trace.go:171","msg":"trace[1447510671] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:29; }","duration":"125.697482ms","start":"2026-02-07T02:28:03.658558Z","end":"2026-02-07T02:28:03.784256Z","steps":["trace[1447510671] 'agreement among raft nodes before linearized reading' (duration: 125.501147ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:03.784459Z","caller":"traceutil/trace.go:171","msg":"trace[68370823] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"127.370971ms","start":"2026-02-07T02:28:03.657079Z","end":"2026-02-07T02:28:03.78445Z","steps":["trace[68370823] 'process raft request' (duration: 126.707076ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:03.784568Z","caller":"traceutil/trace.go:171","msg":"trace[153973804] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"125.940058ms","start":"2026-02-07T02:28:03.65862Z","end":"2026-02-07T02:28:03.78456Z","steps":["trace[153973804] 'process raft request' (duration: 125.222171ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:03.784665Z","caller":"traceutil/trace.go:171","msg":"trace[1719373473] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"125.993749ms","start":"2026-02-07T02:28:03.658656Z","end":"2026-02-07T02:28:03.78465Z","steps":["trace[1719373473] 'process raft request' (duration: 125.210661ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:03.784854Z","caller":"traceutil/trace.go:171","msg":"trace[487875476] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"126.136143ms","start":"2026-02-07T02:28:03.658708Z","end":"2026-02-07T02:28:03.784844Z","steps":["trace[487875476] 'process raft request' (duration: 125.187931ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:03.785715Z","caller":"traceutil/trace.go:171","msg":"trace[786724246] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"126.604313ms","start":"2026-02-07T02:28:03.659095Z","end":"2026-02-07T02:28:03.7857Z","steps":["trace[786724246] 'process raft request' (duration: 124.841863ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:03.785934Z","caller":"traceutil/trace.go:171","msg":"trace[246093041] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"126.771157ms","start":"2026-02-07T02:28:03.659153Z","end":"2026-02-07T02:28:03.785924Z","steps":["trace[246093041] 'process raft request' (duration: 124.816232ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:14.23788Z","caller":"traceutil/trace.go:171","msg":"trace[886901115] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"112.986535ms","start":"2026-02-07T02:28:14.124858Z","end":"2026-02-07T02:28:14.237844Z","steps":["trace[886901115] 'process raft request' (duration: 108.129423ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:19.399034Z","caller":"traceutil/trace.go:171","msg":"trace[249812307] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"153.188324ms","start":"2026-02-07T02:28:19.245774Z","end":"2026-02-07T02:28:19.398962Z","steps":["trace[249812307] 'process raft request' (duration: 112.894423ms)","trace[249812307] 'compare' (duration: 39.695087ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:28:19.854383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.196158ms","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-02-07T02:28:19.854484Z","caller":"traceutil/trace.go:171","msg":"trace[91774713] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:454; }","duration":"187.389202ms","start":"2026-02-07T02:28:19.667072Z","end":"2026-02-07T02:28:19.854462Z","steps":["trace[91774713] 'range keys from in-memory index tree' (duration: 187.043424ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:20.578122Z","caller":"traceutil/trace.go:171","msg":"trace[276119604] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"718.10793ms","start":"2026-02-07T02:28:19.859986Z","end":"2026-02-07T02:28:20.578094Z","steps":["trace[276119604] 'process raft request' (duration: 717.914666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:20.580515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:19.859956Z","time spent":"718.827697ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:28:20.934868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.942891ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:28:20.934963Z","caller":"traceutil/trace.go:171","msg":"trace[1149006870] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:455; }","duration":"124.071014ms","start":"2026-02-07T02:28:20.810879Z","end":"2026-02-07T02:28:20.93495Z","steps":["trace[1149006870] 'range keys from in-memory index tree' (duration: 123.92486ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:20.938912Z","caller":"traceutil/trace.go:171","msg":"trace[143694512] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"1.075938717s","start":"2026-02-07T02:28:19.862965Z","end":"2026-02-07T02:28:20.938903Z","steps":["trace[143694512] 'process raft request' (duration: 1.075559409s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:20.939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:19.86295Z","time spent":"1.075991478s","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:28:20.941767Z","caller":"traceutil/trace.go:171","msg":"trace[1412005127] linearizableReadLoop","detail":"{readStateIndex:470; appliedIndex:469; }","duration":"827.100373ms","start":"2026-02-07T02:28:20.114644Z","end":"2026-02-07T02:28:20.941744Z","steps":["trace[1412005127] 'read index received' (duration: 469.567574ms)","trace[1412005127] 'applied index is now lower than readState.Index' (duration: 357.530539ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:28:20.942187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.435752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-07T02:28:20.942212Z","caller":"traceutil/trace.go:171","msg":"trace[1377277273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:456; }","duration":"827.597256ms","start":"2026-02-07T02:28:20.114607Z","end":"2026-02-07T02:28:20.942205Z","steps":["trace[1377277273] 'agreement among raft nodes before linearized reading' (duration: 827.437342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:20.942241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.185928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-07T02:28:20.94226Z","caller":"traceutil/trace.go:171","msg":"trace[119857918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:456; }","duration":"662.31865ms","start":"2026-02-07T02:28:20.279935Z","end":"2026-02-07T02:28:20.942253Z","steps":["trace[119857918] 'agreement among raft nodes before linearized reading' (duration: 662.176257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:20.942279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:20.279913Z","time spent":"662.359832ms","remote":"127.0.0.1:60576","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-02-07T02:28:20.942316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:20.114578Z","time spent":"827.735489ms","remote":"127.0.0.1:60576","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-02-07T02:28:34.757017Z","caller":"traceutil/trace.go:171","msg":"trace[1785098971] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"105.611874ms","start":"2026-02-07T02:28:34.651374Z","end":"2026-02-07T02:28:34.756986Z","steps":["trace[1785098971] 'process raft request' (duration: 105.43792ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:37.146735Z","caller":"traceutil/trace.go:171","msg":"trace[1215812645] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:544; }","duration":"353.705342ms","start":"2026-02-07T02:28:36.793001Z","end":"2026-02-07T02:28:37.146706Z","steps":["trace[1215812645] 'read index received' (duration: 353.451526ms)","trace[1215812645] 'applied index is now lower than readState.Index' (duration: 252.246µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:28:37.146887Z","caller":"traceutil/trace.go:171","msg":"trace[313275071] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"354.521131ms","start":"2026-02-07T02:28:36.792352Z","end":"2026-02-07T02:28:37.146874Z","steps":["trace[313275071] 'process raft request' (duration: 354.173673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:37.147029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:36.79234Z","time spent":"354.582662ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:28:37.147238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.230214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:28:37.147277Z","caller":"traceutil/trace.go:171","msg":"trace[2015924369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:527; }","duration":"354.283195ms","start":"2026-02-07T02:28:36.792981Z","end":"2026-02-07T02:28:37.147264Z","steps":["trace[2015924369] 'agreement among raft nodes before linearized reading' (duration: 354.221003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:37.147312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:36.792974Z","time spent":"354.330306ms","remote":"127.0.0.1:60264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T02:28:37.148154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.207282ms","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-02-07T02:28:37.148204Z","caller":"traceutil/trace.go:171","msg":"trace[735388837] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:527; }","duration":"143.299114ms","start":"2026-02-07T02:28:37.004889Z","end":"2026-02-07T02:28:37.148188Z","steps":["trace[735388837] 'agreement among raft nodes before linearized reading' (duration: 142.72644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:37.673157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.560641ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:28:37.674052Z","caller":"traceutil/trace.go:171","msg":"trace[1943282801] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"515.394433ms","start":"2026-02-07T02:28:37.158642Z","end":"2026-02-07T02:28:37.674036Z","steps":["trace[1943282801] 'process raft request' (duration: 515.23259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:37.674144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:37.1586Z","time spent":"515.513136ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:28:37.674399Z","caller":"traceutil/trace.go:171","msg":"trace[1842432926] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"515.933195ms","start":"2026-02-07T02:28:37.158452Z","end":"2026-02-07T02:28:37.674385Z","steps":["trace[1842432926] 'process raft request' (duration: 515.319651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:37.674465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:37.158441Z","time spent":"515.997198ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:28:37.674397Z","caller":"traceutil/trace.go:171","msg":"trace[1478557341] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"520.639625ms","start":"2026-02-07T02:28:37.153718Z","end":"2026-02-07T02:28:37.674358Z","steps":["trace[1478557341] 'process raft request' (duration: 112.849052ms)","trace[1478557341] 'compare' (duration: 405.342256ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:28:37.676995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:37.1537Z","time spent":"520.923533ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:28:38.06551Z","caller":"traceutil/trace.go:171","msg":"trace[1373063404] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"503.511995ms","start":"2026-02-07T02:28:37.561967Z","end":"2026-02-07T02:28:38.065479Z","steps":["trace[1373063404] 'process raft request' (duration: 503.28752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:38.065705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:37.56193Z","time spent":"503.670689ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:28:40.684612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.324325ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-07T02:28:43.352933Z","caller":"traceutil/trace.go:171","msg":"trace[1336159999] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"174.165733ms","start":"2026-02-07T02:28:43.178717Z","end":"2026-02-07T02:28:43.352882Z","steps":["trace[1336159999] 'process raft request' (duration: 173.997499ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:44.911769Z","caller":"traceutil/trace.go:171","msg":"trace[648191351] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"288.607613ms","start":"2026-02-07T02:28:44.623139Z","end":"2026-02-07T02:28:44.911747Z","steps":["trace[648191351] 'process raft request' (duration: 286.107705ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:45.975692Z","caller":"traceutil/trace.go:171","msg":"trace[1816575632] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:577; }","duration":"246.438009ms","start":"2026-02-07T02:28:45.729093Z","end":"2026-02-07T02:28:45.975531Z","steps":["trace[1816575632] 'read index received' (duration: 246.098221ms)","trace[1816575632] 'applied index is now lower than readState.Index' (duration: 338.708µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:28:45.975918Z","caller":"traceutil/trace.go:171","msg":"trace[822226043] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"257.267542ms","start":"2026-02-07T02:28:45.718629Z","end":"2026-02-07T02:28:45.975897Z","steps":["trace[822226043] 'process raft request' (duration: 256.691929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:45.976062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.541061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-07T02:28:45.97614Z","caller":"traceutil/trace.go:171","msg":"trace[240884576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:558; }","duration":"246.647913ms","start":"2026-02-07T02:28:45.729472Z","end":"2026-02-07T02:28:45.97612Z","steps":["trace[240884576] 'agreement among raft nodes before linearized reading' (duration: 246.52296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:45.97635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.260277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-07T02:28:45.97637Z","caller":"traceutil/trace.go:171","msg":"trace[1688663051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:558; }","duration":"247.35875ms","start":"2026-02-07T02:28:45.729005Z","end":"2026-02-07T02:28:45.976364Z","steps":["trace[1688663051] 'agreement among raft nodes before linearized reading' (duration: 247.257768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:45.976434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.822691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:28:45.976449Z","caller":"traceutil/trace.go:171","msg":"trace[312626791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"184.837762ms","start":"2026-02-07T02:28:45.791606Z","end":"2026-02-07T02:28:45.976444Z","steps":["trace[312626791] 'agreement among raft nodes before linearized reading' (duration: 184.807111ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:46.2736Z","caller":"traceutil/trace.go:171","msg":"trace[51590570] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"287.260671ms","start":"2026-02-07T02:28:45.98632Z","end":"2026-02-07T02:28:46.27358Z","steps":["trace[51590570] 'process raft request' (duration: 287.2236ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:46.273868Z","caller":"traceutil/trace.go:171","msg":"trace[1864632878] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"289.089543ms","start":"2026-02-07T02:28:45.984769Z","end":"2026-02-07T02:28:46.273858Z","steps":["trace[1864632878] 'process raft request' (duration: 258.559441ms)","trace[1864632878] 'compare' (duration: 29.758584ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:28:54.118667Z","caller":"traceutil/trace.go:171","msg":"trace[1077734980] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"144.30962ms","start":"2026-02-07T02:28:53.974341Z","end":"2026-02-07T02:28:54.11865Z","steps":["trace[1077734980] 'process raft request' (duration: 143.92557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:54.413801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.105472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:28:54.414028Z","caller":"traceutil/trace.go:171","msg":"trace[1036003570] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"291.815296ms","start":"2026-02-07T02:28:54.122199Z","end":"2026-02-07T02:28:54.414014Z","steps":["trace[1036003570] 'process raft request' (duration: 78.416837ms)","trace[1036003570] 'compare' (duration: 212.834336ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:28:54.550705Z","caller":"traceutil/trace.go:171","msg":"trace[677703261] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"125.756116ms","start":"2026-02-07T02:28:54.424941Z","end":"2026-02-07T02:28:54.550697Z","steps":["trace[677703261] 'process raft request' (duration: 120.39364ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:54.550666Z","caller":"traceutil/trace.go:171","msg":"trace[1193423419] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"124.287001ms","start":"2026-02-07T02:28:54.426361Z","end":"2026-02-07T02:28:54.550648Z","steps":["trace[1193423419] 'process raft request' (duration: 124.115227ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:57.776586Z","caller":"traceutil/trace.go:171","msg":"trace[1829022385] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"239.403808ms","start":"2026-02-07T02:28:57.53716Z","end":"2026-02-07T02:28:57.776564Z","steps":["trace[1829022385] 'read index received' (duration: 239.203024ms)","trace[1829022385] 'applied index is now lower than readState.Index' (duration: 199.844µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:28:57.776817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.637784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-07T02:28:57.776851Z","caller":"traceutil/trace.go:171","msg":"trace[926349198] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:676; }","duration":"239.719056ms","start":"2026-02-07T02:28:57.537121Z","end":"2026-02-07T02:28:57.77684Z","steps":["trace[926349198] 'agreement among raft nodes before linearized reading' (duration: 239.547182ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:57.777577Z","caller":"traceutil/trace.go:171","msg":"trace[279885166] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"741.961161ms","start":"2026-02-07T02:28:57.035581Z","end":"2026-02-07T02:28:57.777542Z","steps":["trace[279885166] 'process raft request' (duration: 740.837534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:57.777736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:57.03557Z","time spent":"742.070514ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:28:58.678983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.780076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-07T02:28:58.67907Z","caller":"traceutil/trace.go:171","msg":"trace[1320759120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:691; }","duration":"102.91554ms","start":"2026-02-07T02:28:58.576127Z","end":"2026-02-07T02:28:58.679043Z","steps":["trace[1320759120] 'agreement among raft nodes before linearized reading' (duration: 79.109003ms)","trace[1320759120] 'range keys from in-memory index tree' (duration: 23.662304ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:28:58.679149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.867994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-rzsm2\" ","response":"range_response_count:1 size:4696"} {"level":"info","ts":"2026-02-07T02:28:58.679197Z","caller":"traceutil/trace.go:171","msg":"trace[632262196] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-rzsm2; range_end:; response_count:1; response_revision:691; }","duration":"118.956946ms","start":"2026-02-07T02:28:58.560228Z","end":"2026-02-07T02:28:58.679185Z","steps":["trace[632262196] 'agreement among raft nodes before linearized reading' (duration: 95.016896ms)","trace[632262196] 'range keys from in-memory index tree' (duration: 23.805647ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:28:58.871323Z","caller":"traceutil/trace.go:171","msg":"trace[459890928] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"187.199525ms","start":"2026-02-07T02:28:58.684102Z","end":"2026-02-07T02:28:58.871302Z","steps":["trace[459890928] 'process raft request' (duration: 160.134861ms)","trace[459890928] 'compare' (duration: 26.422089ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:28:58.871554Z","caller":"traceutil/trace.go:171","msg":"trace[972402844] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"183.57947ms","start":"2026-02-07T02:28:58.687965Z","end":"2026-02-07T02:28:58.871545Z","steps":["trace[972402844] 'process raft request' (duration: 183.002056ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:59.024819Z","caller":"traceutil/trace.go:171","msg":"trace[1643716037] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"141.758911ms","start":"2026-02-07T02:28:58.88304Z","end":"2026-02-07T02:28:59.024799Z","steps":["trace[1643716037] 'process raft request' (duration: 140.891781ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:59.024957Z","caller":"traceutil/trace.go:171","msg":"trace[1356279988] linearizableReadLoop","detail":"{readStateIndex:719; appliedIndex:717; }","duration":"141.849873ms","start":"2026-02-07T02:28:58.883102Z","end":"2026-02-07T02:28:59.024952Z","steps":["trace[1356279988] 'read index received' (duration: 33.008234ms)","trace[1356279988] 'applied index is now lower than readState.Index' (duration: 108.840829ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:28:59.02514Z","caller":"traceutil/trace.go:171","msg":"trace[299434791] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"137.03901ms","start":"2026-02-07T02:28:58.888096Z","end":"2026-02-07T02:28:59.025135Z","steps":["trace[299434791] 'process raft request' (duration: 136.642001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:59.025289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.129742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-gqjpn\" ","response":"range_response_count:1 size:4732"} {"level":"warn","ts":"2026-02-07T02:28:59.02532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.203352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:28:59.025341Z","caller":"traceutil/trace.go:171","msg":"trace[1710766432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:697; }","duration":"142.250682ms","start":"2026-02-07T02:28:58.883085Z","end":"2026-02-07T02:28:59.025335Z","steps":["trace[1710766432] 'agreement among raft nodes before linearized reading' (duration: 142.205631ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:59.025342Z","caller":"traceutil/trace.go:171","msg":"trace[728714262] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-gqjpn; range_end:; response_count:1; response_revision:697; }","duration":"137.198374ms","start":"2026-02-07T02:28:58.888129Z","end":"2026-02-07T02:28:59.025328Z","steps":["trace[728714262] 'agreement among raft nodes before linearized reading' (duration: 137.067921ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:59.172666Z","caller":"traceutil/trace.go:171","msg":"trace[1506236559] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"140.236795ms","start":"2026-02-07T02:28:59.032412Z","end":"2026-02-07T02:28:59.172649Z","steps":["trace[1506236559] 'process raft request' (duration: 138.266098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:59.482215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.290967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:28:59.482339Z","caller":"traceutil/trace.go:171","msg":"trace[335915526] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"441.579263ms","start":"2026-02-07T02:28:59.040748Z","end":"2026-02-07T02:28:59.482327Z","steps":["trace[335915526] 'process raft request' (duration: 295.097353ms)","trace[335915526] 'compare' (duration: 146.063241ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:28:59.482387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:59.040729Z","time spent":"441.642955ms","remote":"127.0.0.1:60480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4829,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:28:59.646452Z","caller":"traceutil/trace.go:171","msg":"trace[47188989] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"124.427915ms","start":"2026-02-07T02:28:59.522002Z","end":"2026-02-07T02:28:59.64643Z","steps":["trace[47188989] 'process raft request' (duration: 91.582105ms)","trace[47188989] 'compare' (duration: 32.762928ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:28:59.703177Z","caller":"traceutil/trace.go:171","msg":"trace[1632677839] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"136.99951ms","start":"2026-02-07T02:28:59.566162Z","end":"2026-02-07T02:28:59.703162Z","steps":["trace[1632677839] 'process raft request' (duration: 136.914318ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:00.019782Z","caller":"traceutil/trace.go:171","msg":"trace[1809191291] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"343.397354ms","start":"2026-02-07T02:28:59.676368Z","end":"2026-02-07T02:29:00.019766Z","steps":["trace[1809191291] 'process raft request' (duration: 343.373183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:00.019883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:59.676352Z","time spent":"343.474686ms","remote":"127.0.0.1:60792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3866,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:29:00.020105Z","caller":"traceutil/trace.go:171","msg":"trace[1086972332] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"366.639848ms","start":"2026-02-07T02:28:59.653436Z","end":"2026-02-07T02:29:00.020076Z","steps":["trace[1086972332] 'process raft request' (duration: 366.200508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:00.02014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:59.653404Z","time spent":"366.71334ms","remote":"127.0.0.1:60584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1613,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:29:00.020447Z","caller":"traceutil/trace.go:171","msg":"trace[1292725288] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"366.750031ms","start":"2026-02-07T02:28:59.653495Z","end":"2026-02-07T02:29:00.020245Z","steps":["trace[1292725288] 'process raft request' (duration: 366.215828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:00.020482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:59.653478Z","time spent":"366.989377ms","remote":"127.0.0.1:60448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1018,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:29:00.497374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.509503ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:29:00.497545Z","caller":"traceutil/trace.go:171","msg":"trace[1501171207] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:730; }","duration":"784.996959ms","start":"2026-02-07T02:28:59.712527Z","end":"2026-02-07T02:29:00.497524Z","steps":["trace[1501171207] 'read index received' (duration: 307.736128ms)","trace[1501171207] 'applied index is now lower than readState.Index' (duration: 477.260011ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:29:00.497593Z","caller":"traceutil/trace.go:171","msg":"trace[1578946317] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"787.897757ms","start":"2026-02-07T02:28:59.709689Z","end":"2026-02-07T02:29:00.497587Z","steps":["trace[1578946317] 'process raft request' (duration: 518.130926ms)","trace[1578946317] 'compare' (duration: 269.418782ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:29:00.49763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:59.709674Z","time spent":"787.935608ms","remote":"127.0.0.1:60764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4191,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:29:00.497789Z","caller":"traceutil/trace.go:171","msg":"trace[1955006128] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"785.354407ms","start":"2026-02-07T02:28:59.712428Z","end":"2026-02-07T02:29:00.497783Z","steps":["trace[1955006128] 'process raft request' (duration: 785.04346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:00.49782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:59.712421Z","time spent":"785.385099ms","remote":"127.0.0.1:60370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T02:29:00.497911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.368458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-rzsm2\" ","response":"range_response_count:1 size:4811"} {"level":"info","ts":"2026-02-07T02:29:00.497962Z","caller":"traceutil/trace.go:171","msg":"trace[107716803] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-rzsm2; range_end:; response_count:1; response_revision:709; }","duration":"785.427139ms","start":"2026-02-07T02:28:59.712523Z","end":"2026-02-07T02:29:00.49795Z","steps":["trace[107716803] 'agreement among raft nodes before linearized reading' (duration: 785.281596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:00.497984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:59.712515Z","time spent":"785.46527ms","remote":"127.0.0.1:60480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4835,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-rzsm2\" "} {"level":"warn","ts":"2026-02-07T02:29:01.196029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.550522ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-07T02:29:01.19611Z","caller":"traceutil/trace.go:171","msg":"trace[1653316878] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:732; }","duration":"698.534044ms","start":"2026-02-07T02:29:00.497565Z","end":"2026-02-07T02:29:01.196099Z","steps":["trace[1653316878] 'read index received' (duration: 67.966202ms)","trace[1653316878] 'applied index is now lower than readState.Index' (duration: 630.566802ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:29:01.196389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.690313ms","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-02-07T02:29:01.196408Z","caller":"traceutil/trace.go:171","msg":"trace[1752073065] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:709; }","duration":"752.750534ms","start":"2026-02-07T02:29:00.44365Z","end":"2026-02-07T02:29:01.196401Z","steps":["trace[1752073065] 'agreement among raft nodes before linearized reading' (duration: 752.632411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:01.196426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:00.443631Z","time spent":"752.791235ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T02:29:01.196385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.107471ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:29:01.196546Z","caller":"traceutil/trace.go:171","msg":"trace[1533880706] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:709; }","duration":"385.279955ms","start":"2026-02-07T02:29:00.811236Z","end":"2026-02-07T02:29:01.196516Z","steps":["trace[1533880706] 'range keys from in-memory index tree' (duration: 385.076181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:01.196564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.374291823s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-07T02:29:01.19658Z","caller":"traceutil/trace.go:171","msg":"trace[60139408] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:709; }","duration":"1.374338044s","start":"2026-02-07T02:28:59.822238Z","end":"2026-02-07T02:29:01.196576Z","steps":["trace[60139408] 'agreement among raft nodes before linearized reading' (duration: 1.374294263s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:01.196592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:59.82222Z","time spent":"1.374369375s","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T02:29:01.196686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.414758621s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-07T02:29:01.196709Z","caller":"traceutil/trace.go:171","msg":"trace[1426511165] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:709; }","duration":"1.414808743s","start":"2026-02-07T02:28:59.781896Z","end":"2026-02-07T02:29:01.196705Z","steps":["trace[1426511165] 'agreement among raft nodes before linearized reading' (duration: 1.414761182s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:01.196721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:59.781875Z","time spent":"1.414842663s","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-07T02:29:01.198664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.635804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-07T02:29:01.198731Z","caller":"traceutil/trace.go:171","msg":"trace[99487301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:711; }","duration":"487.713975ms","start":"2026-02-07T02:29:00.71097Z","end":"2026-02-07T02:29:01.198684Z","steps":["trace[99487301] 'agreement among raft nodes before linearized reading' (duration: 487.562422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:01.198777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:00.710945Z","time spent":"487.805367ms","remote":"127.0.0.1:60576","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-02-07T02:29:01.198945Z","caller":"traceutil/trace.go:171","msg":"trace[1322823667] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"698.672687ms","start":"2026-02-07T02:29:00.500266Z","end":"2026-02-07T02:29:01.198938Z","steps":["trace[1322823667] 'process raft request' (duration: 698.084174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:01.199008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:00.500252Z","time spent":"698.706608ms","remote":"127.0.0.1:60480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4808,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:29:01.199152Z","caller":"traceutil/trace.go:171","msg":"trace[1344527185] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"681.661448ms","start":"2026-02-07T02:29:00.517483Z","end":"2026-02-07T02:29:01.199144Z","steps":["trace[1344527185] 'process raft request' (duration: 681.009513ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:01.199186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:00.517477Z","time spent":"681.690949ms","remote":"127.0.0.1:60764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4164,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:29:01.199306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.109779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-07T02:29:01.199322Z","caller":"traceutil/trace.go:171","msg":"trace[230653058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:711; }","duration":"314.17451ms","start":"2026-02-07T02:29:00.885142Z","end":"2026-02-07T02:29:01.199316Z","steps":["trace[230653058] 'agreement among raft nodes before linearized reading' (duration: 314.125969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:01.199335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:00.885118Z","time spent":"314.2135ms","remote":"127.0.0.1:60576","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-02-07T02:29:01.199406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.396217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:29:01.199423Z","caller":"traceutil/trace.go:171","msg":"trace[675984917] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:711; }","duration":"408.453338ms","start":"2026-02-07T02:29:00.79096Z","end":"2026-02-07T02:29:01.199414Z","steps":["trace[675984917] 'agreement among raft nodes before linearized reading' (duration: 408.427408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:01.19945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:00.790937Z","time spent":"408.50921ms","remote":"127.0.0.1:60272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-07T02:29:03.330378Z","caller":"traceutil/trace.go:171","msg":"trace[881097618] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"103.2797ms","start":"2026-02-07T02:29:03.227044Z","end":"2026-02-07T02:29:03.330324Z","steps":["trace[881097618] 'process raft request' (duration: 103.050834ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:03.579509Z","caller":"traceutil/trace.go:171","msg":"trace[1064752609] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"241.559699ms","start":"2026-02-07T02:29:03.337921Z","end":"2026-02-07T02:29:03.57948Z","steps":["trace[1064752609] 'process raft request' (duration: 181.552513ms)","trace[1064752609] 'compare' (duration: 59.897384ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:29:03.964605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.68228ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:29:03.964868Z","caller":"traceutil/trace.go:171","msg":"trace[1759268947] linearizableReadLoop","detail":"{readStateIndex:752; appliedIndex:749; }","duration":"168.545727ms","start":"2026-02-07T02:29:03.796298Z","end":"2026-02-07T02:29:03.964844Z","steps":["trace[1759268947] 'read index received' (duration: 24.23µs)","trace[1759268947] 'applied index is now lower than readState.Index' (duration: 168.520267ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:29:03.965072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.764542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:29:03.965115Z","caller":"traceutil/trace.go:171","msg":"trace[2080417987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:728; }","duration":"168.833744ms","start":"2026-02-07T02:29:03.796267Z","end":"2026-02-07T02:29:03.965101Z","steps":["trace[2080417987] 'agreement among raft nodes before linearized reading' (duration: 168.64105ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:03.965638Z","caller":"traceutil/trace.go:171","msg":"trace[742487918] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"626.331112ms","start":"2026-02-07T02:29:03.33929Z","end":"2026-02-07T02:29:03.965621Z","steps":["trace[742487918] 'process raft request' (duration: 430.508135ms)","trace[742487918] 'compare' (duration: 194.305641ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:29:03.96582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:03.339227Z","time spent":"626.532106ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:29:03.966056Z","caller":"traceutil/trace.go:171","msg":"trace[1100817701] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"626.66601ms","start":"2026-02-07T02:29:03.339376Z","end":"2026-02-07T02:29:03.966042Z","steps":["trace[1100817701] 'process raft request' (duration: 625.348669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:03.966117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:03.339371Z","time spent":"626.711881ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:29:03.973928Z","caller":"traceutil/trace.go:171","msg":"trace[215017699] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"625.80461ms","start":"2026-02-07T02:29:03.340438Z","end":"2026-02-07T02:29:03.966242Z","steps":["trace[215017699] 'process raft request' (duration: 624.351215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:03.974037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:03.340431Z","time spent":"633.541331ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:29:07.591403Z","caller":"traceutil/trace.go:171","msg":"trace[965797449] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"245.723987ms","start":"2026-02-07T02:29:07.345665Z","end":"2026-02-07T02:29:07.591389Z","steps":["trace[965797449] 'process raft request' (duration: 245.367108ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:16.116651Z","caller":"traceutil/trace.go:171","msg":"trace[897516505] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:792; }","duration":"496.269985ms","start":"2026-02-07T02:29:15.620354Z","end":"2026-02-07T02:29:16.116624Z","steps":["trace[897516505] 'read index received' (duration: 496.130782ms)","trace[897516505] 'applied index is now lower than readState.Index' (duration: 138.673µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:29:16.116927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.356396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:29:16.116986Z","caller":"traceutil/trace.go:171","msg":"trace[508227747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"315.447408ms","start":"2026-02-07T02:29:15.801524Z","end":"2026-02-07T02:29:16.116972Z","steps":["trace[508227747] 'agreement among raft nodes before linearized reading' (duration: 315.334856ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:16.117014Z","caller":"traceutil/trace.go:171","msg":"trace[1923460902] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"497.819391ms","start":"2026-02-07T02:29:15.619188Z","end":"2026-02-07T02:29:16.117008Z","steps":["trace[1923460902] 'process raft request' (duration: 497.34444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:16.117014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:15.801513Z","time spent":"315.49557ms","remote":"127.0.0.1:60272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T02:29:16.117079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:15.619177Z","time spent":"497.851881ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:29:16.117169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.812588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-07T02:29:16.117186Z","caller":"traceutil/trace.go:171","msg":"trace[1488081742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:765; }","duration":"496.839819ms","start":"2026-02-07T02:29:15.62034Z","end":"2026-02-07T02:29:16.11718Z","steps":["trace[1488081742] 'agreement among raft nodes before linearized reading' (duration: 496.797898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:16.117198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:15.620335Z","time spent":"496.859539ms","remote":"127.0.0.1:60576","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-02-07T02:29:16.885584Z","caller":"traceutil/trace.go:171","msg":"trace[2032069848] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"735.752085ms","start":"2026-02-07T02:29:16.149815Z","end":"2026-02-07T02:29:16.885568Z","steps":["trace[2032069848] 'process raft request' (duration: 735.719984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:16.885744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:16.149809Z","time spent":"735.828526ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:29:16.885977Z","caller":"traceutil/trace.go:171","msg":"trace[988222342] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"742.797599ms","start":"2026-02-07T02:29:16.143171Z","end":"2026-02-07T02:29:16.885968Z","steps":["trace[988222342] 'process raft request' (duration: 742.218016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:16.886015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:16.143166Z","time spent":"742.83084ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:29:16.886093Z","caller":"traceutil/trace.go:171","msg":"trace[1352044635] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"738.300275ms","start":"2026-02-07T02:29:16.147787Z","end":"2026-02-07T02:29:16.886087Z","steps":["trace[1352044635] 'process raft request' (duration: 737.721851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:16.886148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:16.147781Z","time spent":"738.324595ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:29:18.655189Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276677654686590,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-07T02:29:18.684922Z","caller":"traceutil/trace.go:171","msg":"trace[513100576] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:797; }","duration":"530.479977ms","start":"2026-02-07T02:29:18.154367Z","end":"2026-02-07T02:29:18.684847Z","steps":["trace[513100576] 'read index received' (duration: 530.230531ms)","trace[513100576] 'applied index is now lower than readState.Index' (duration: 248.286µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:29:18.685151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.760574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-07T02:29:18.685182Z","caller":"traceutil/trace.go:171","msg":"trace[1607152090] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:770; }","duration":"530.829726ms","start":"2026-02-07T02:29:18.154343Z","end":"2026-02-07T02:29:18.685173Z","steps":["trace[1607152090] 'agreement among raft nodes before linearized reading' (duration: 530.645931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:18.685215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:18.154331Z","time spent":"530.876186ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-07T02:29:18.686237Z","caller":"traceutil/trace.go:171","msg":"trace[99643797] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"561.906283ms","start":"2026-02-07T02:29:18.124306Z","end":"2026-02-07T02:29:18.686213Z","steps":["trace[99643797] 'process raft request' (duration: 560.390988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:18.68638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:18.124289Z","time spent":"562.018845ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:29:23.064237Z","caller":"traceutil/trace.go:171","msg":"trace[1944048726] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"117.407ms","start":"2026-02-07T02:29:22.9468Z","end":"2026-02-07T02:29:23.064207Z","steps":["trace[1944048726] 'process raft request' (duration: 117.254297ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:23.068864Z","caller":"traceutil/trace.go:171","msg":"trace[932187668] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"121.565528ms","start":"2026-02-07T02:29:22.947278Z","end":"2026-02-07T02:29:23.068843Z","steps":["trace[932187668] 'process raft request' (duration: 121.456356ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:25.053244Z","caller":"traceutil/trace.go:171","msg":"trace[1995558802] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:814; }","duration":"250.235781ms","start":"2026-02-07T02:29:24.802982Z","end":"2026-02-07T02:29:25.053217Z","steps":["trace[1995558802] 'read index received' (duration: 249.992586ms)","trace[1995558802] 'applied index is now lower than readState.Index' (duration: 242.175µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:29:25.053604Z","caller":"traceutil/trace.go:171","msg":"trace[1328687055] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"333.058332ms","start":"2026-02-07T02:29:24.720534Z","end":"2026-02-07T02:29:25.053592Z","steps":["trace[1328687055] 'process raft request' (duration: 332.513559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:25.053701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:24.720522Z","time spent":"333.117424ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:29:25.053948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.962078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-07T02:29:25.053987Z","caller":"traceutil/trace.go:171","msg":"trace[8424313] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:786; }","duration":"251.0222ms","start":"2026-02-07T02:29:24.802954Z","end":"2026-02-07T02:29:25.053976Z","steps":["trace[8424313] 'agreement among raft nodes before linearized reading' (duration: 250.914917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:25.054137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.06874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:29:25.054171Z","caller":"traceutil/trace.go:171","msg":"trace[1571067855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:786; }","duration":"251.125512ms","start":"2026-02-07T02:29:24.803036Z","end":"2026-02-07T02:29:25.054161Z","steps":["trace[1571067855] 'agreement among raft nodes before linearized reading' (duration: 251.083251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:25.055352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.035881ms","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-02-07T02:29:25.055427Z","caller":"traceutil/trace.go:171","msg":"trace[724800079] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:786; }","duration":"111.139934ms","start":"2026-02-07T02:29:24.944277Z","end":"2026-02-07T02:29:25.055417Z","steps":["trace[724800079] 'agreement among raft nodes before linearized reading' (duration: 111.000691ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:25.055639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.33246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T02:29:25.055676Z","caller":"traceutil/trace.go:171","msg":"trace[1155258396] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:786; }","duration":"202.374041ms","start":"2026-02-07T02:29:24.853294Z","end":"2026-02-07T02:29:25.055668Z","steps":["trace[1155258396] 'agreement among raft nodes before linearized reading' (duration: 202.312199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:25.588618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.933962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:29:25.588887Z","caller":"traceutil/trace.go:171","msg":"trace[62144457] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"247.893906ms","start":"2026-02-07T02:29:25.340977Z","end":"2026-02-07T02:29:25.588871Z","steps":["trace[62144457] 'process raft request' (duration: 247.853435ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:25.589273Z","caller":"traceutil/trace.go:171","msg":"trace[1768785262] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"527.638331ms","start":"2026-02-07T02:29:25.061623Z","end":"2026-02-07T02:29:25.589261Z","steps":["trace[1768785262] 'process raft request' (duration: 119.983141ms)","trace[1768785262] 'compare' (duration: 406.690127ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:29:25.58934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:25.061614Z","time spent":"527.691922ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:29:25.589515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:25.07754Z","time spent":"511.974164ms","remote":"127.0.0.1:60314","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-07T02:29:25.589637Z","caller":"traceutil/trace.go:171","msg":"trace[1463486841] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"511.886422ms","start":"2026-02-07T02:29:25.077742Z","end":"2026-02-07T02:29:25.589628Z","steps":["trace[1463486841] 'process raft request' (duration: 511.036631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:25.589694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:25.077735Z","time spent":"511.932163ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:29:25.589797Z","caller":"traceutil/trace.go:171","msg":"trace[899378097] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:815; }","duration":"511.568224ms","start":"2026-02-07T02:29:25.07822Z","end":"2026-02-07T02:29:25.589788Z","steps":["trace[899378097] 'read index received' (duration: 103.393342ms)","trace[899378097] 'applied index is now lower than readState.Index' (duration: 408.173862ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:29:25.590149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.920052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-07T02:29:25.590197Z","caller":"traceutil/trace.go:171","msg":"trace[1835499056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:789; }","duration":"512.414364ms","start":"2026-02-07T02:29:25.077772Z","end":"2026-02-07T02:29:25.590186Z","steps":["trace[1835499056] 'agreement among raft nodes before linearized reading' (duration: 512.26968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:25.590229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:25.077767Z","time spent":"512.453634ms","remote":"127.0.0.1:60576","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-02-07T02:29:25.590393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.132077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-07T02:29:25.590421Z","caller":"traceutil/trace.go:171","msg":"trace[1425995564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:789; }","duration":"512.169058ms","start":"2026-02-07T02:29:25.078241Z","end":"2026-02-07T02:29:25.59041Z","steps":["trace[1425995564] 'agreement among raft nodes before linearized reading' (duration: 512.096586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:25.590445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:25.078238Z","time spent":"512.201299ms","remote":"127.0.0.1:60576","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-02-07T02:29:25.99446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.296346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:29:25.994581Z","caller":"traceutil/trace.go:171","msg":"trace[1368873879] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:819; }","duration":"204.618353ms","start":"2026-02-07T02:29:25.789954Z","end":"2026-02-07T02:29:25.994572Z","steps":["trace[1368873879] 'read index received' (duration: 99.181474ms)","trace[1368873879] 'applied index is now lower than readState.Index' (duration: 105.436299ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:29:25.994649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.695286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:29:25.994661Z","caller":"traceutil/trace.go:171","msg":"trace[1386358637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:792; }","duration":"204.723336ms","start":"2026-02-07T02:29:25.789934Z","end":"2026-02-07T02:29:25.994657Z","steps":["trace[1386358637] 'agreement among raft nodes before linearized reading' (duration: 204.663585ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:25.994773Z","caller":"traceutil/trace.go:171","msg":"trace[478308173] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"396.20516ms","start":"2026-02-07T02:29:25.598562Z","end":"2026-02-07T02:29:25.994767Z","steps":["trace[478308173] 'process raft request' (duration: 290.566976ms)","trace[478308173] 'compare' (duration: 104.640151ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:29:25.994817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:25.598542Z","time spent":"396.246972ms","remote":"127.0.0.1:60314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:29:25.994943Z","caller":"traceutil/trace.go:171","msg":"trace[356956754] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"390.426195ms","start":"2026-02-07T02:29:25.604512Z","end":"2026-02-07T02:29:25.994938Z","steps":["trace[356956754] 'process raft request' (duration: 390.016696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:25.994971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:25.604441Z","time spent":"390.516728ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:29:25.995813Z","caller":"traceutil/trace.go:171","msg":"trace[227144443] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"391.221164ms","start":"2026-02-07T02:29:25.604585Z","end":"2026-02-07T02:29:25.995806Z","steps":["trace[227144443] 'process raft request' (duration: 389.971525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:25.99586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:25.604581Z","time spent":"391.253765ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:29:26.593192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.948183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:29:26.593245Z","caller":"traceutil/trace.go:171","msg":"trace[82845178] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:792; }","duration":"217.040115ms","start":"2026-02-07T02:29:26.376193Z","end":"2026-02-07T02:29:26.593233Z","steps":["trace[82845178] 'count revisions from in-memory index tree' (duration: 216.889341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:26.593264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.112059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T02:29:26.593344Z","caller":"traceutil/trace.go:171","msg":"trace[436238028] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:792; }","duration":"498.221041ms","start":"2026-02-07T02:29:26.09509Z","end":"2026-02-07T02:29:26.593311Z","steps":["trace[436238028] 'count revisions from in-memory index tree' (duration: 498.028487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:26.593375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:26.095078Z","time spent":"498.289903ms","remote":"127.0.0.1:60508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":40,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-02-07T02:29:27.730366Z","caller":"traceutil/trace.go:171","msg":"trace[66607424] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"129.720799ms","start":"2026-02-07T02:29:27.600627Z","end":"2026-02-07T02:29:27.730348Z","steps":["trace[66607424] 'process raft request' (duration: 129.520354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:30.966638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.632952ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-02-07T02:29:30.966851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.853951ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:29:30.966924Z","caller":"traceutil/trace.go:171","msg":"trace[201637116] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:803; }","duration":"155.931113ms","start":"2026-02-07T02:29:30.81098Z","end":"2026-02-07T02:29:30.966911Z","steps":["trace[201637116] 'range keys from in-memory index tree' (duration: 155.841851ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:31.676634Z","caller":"traceutil/trace.go:171","msg":"trace[1632742736] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"587.86039ms","start":"2026-02-07T02:29:31.088752Z","end":"2026-02-07T02:29:31.676612Z","steps":["trace[1632742736] 'process raft request' (duration: 587.725757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:31.676901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:31.088741Z","time spent":"587.955773ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:29:31.945599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.945435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T02:29:31.945677Z","caller":"traceutil/trace.go:171","msg":"trace[1723513011] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:804; }","duration":"151.057258ms","start":"2026-02-07T02:29:31.794603Z","end":"2026-02-07T02:29:31.94566Z","steps":["trace[1723513011] 'count revisions from in-memory index tree' (duration: 150.905674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:31.946026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.847024ms","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-02-07T02:29:31.946056Z","caller":"traceutil/trace.go:171","msg":"trace[292150187] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:804; }","duration":"223.904245ms","start":"2026-02-07T02:29:31.722143Z","end":"2026-02-07T02:29:31.946047Z","steps":["trace[292150187] 'range keys from in-memory index tree' (duration: 223.67422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:31.946242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.824339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-07T02:29:31.946269Z","caller":"traceutil/trace.go:171","msg":"trace[1243504192] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:804; }","duration":"207.879549ms","start":"2026-02-07T02:29:31.738382Z","end":"2026-02-07T02:29:31.946261Z","steps":["trace[1243504192] 'range keys from in-memory index tree' (duration: 207.747597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:31.946472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.885872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:29:31.946498Z","caller":"traceutil/trace.go:171","msg":"trace[1212395664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"155.938173ms","start":"2026-02-07T02:29:31.790552Z","end":"2026-02-07T02:29:31.946491Z","steps":["trace[1212395664] 'range keys from in-memory index tree' (duration: 155.833991ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:32.25106Z","caller":"traceutil/trace.go:171","msg":"trace[243369772] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:836; }","duration":"210.815799ms","start":"2026-02-07T02:29:32.040223Z","end":"2026-02-07T02:29:32.251039Z","steps":["trace[243369772] 'read index received' (duration: 210.646794ms)","trace[243369772] 'applied index is now lower than readState.Index' (duration: 168.205µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:29:32.251634Z","caller":"traceutil/trace.go:171","msg":"trace[1458460743] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"298.997975ms","start":"2026-02-07T02:29:31.952624Z","end":"2026-02-07T02:29:32.251621Z","steps":["trace[1458460743] 'process raft request' (duration: 298.303299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:32.251998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.756271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T02:29:32.252092Z","caller":"traceutil/trace.go:171","msg":"trace[1875014365] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:806; }","duration":"211.893224ms","start":"2026-02-07T02:29:32.040185Z","end":"2026-02-07T02:29:32.252078Z","steps":["trace[1875014365] 'agreement among raft nodes before linearized reading' (duration: 211.760461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:32.252354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.709123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-07T02:29:32.25243Z","caller":"traceutil/trace.go:171","msg":"trace[1000004971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:806; }","duration":"148.817996ms","start":"2026-02-07T02:29:32.103601Z","end":"2026-02-07T02:29:32.252419Z","steps":["trace[1000004971] 'agreement among raft nodes before linearized reading' (duration: 148.671923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:32.252796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.582911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-07T02:29:32.253588Z","caller":"traceutil/trace.go:171","msg":"trace[1811810402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:806; }","duration":"149.392779ms","start":"2026-02-07T02:29:32.104185Z","end":"2026-02-07T02:29:32.253578Z","steps":["trace[1811810402] 'agreement among raft nodes before linearized reading' (duration: 148.485898ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:43.851891Z","caller":"traceutil/trace.go:171","msg":"trace[225698023] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"102.86724ms","start":"2026-02-07T02:29:43.749003Z","end":"2026-02-07T02:29:43.851871Z","steps":["trace[225698023] 'process raft request' (duration: 102.391578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:44.53198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.140397ms","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-02-07T02:29:44.532033Z","caller":"traceutil/trace.go:171","msg":"trace[1602984951] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:889; }","duration":"179.27239ms","start":"2026-02-07T02:29:44.352749Z","end":"2026-02-07T02:29:44.532021Z","steps":["trace[1602984951] 'range keys from in-memory index tree' (duration: 178.965292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:44.532146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.733749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-07T02:29:44.53222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.830461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-07T02:29:44.532239Z","caller":"traceutil/trace.go:171","msg":"trace[155973368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:889; }","duration":"158.870673ms","start":"2026-02-07T02:29:44.373364Z","end":"2026-02-07T02:29:44.532234Z","steps":["trace[155973368] 'range keys from in-memory index tree' (duration: 158.692089ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:44.532244Z","caller":"traceutil/trace.go:171","msg":"trace[1438561318] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:889; }","duration":"573.888254ms","start":"2026-02-07T02:29:43.958327Z","end":"2026-02-07T02:29:44.532216Z","steps":["trace[1438561318] 'count revisions from in-memory index tree' (duration: 573.646287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:44.532286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:43.958314Z","time spent":"573.960656ms","remote":"127.0.0.1:60736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-07T02:29:44.532331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.952541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-07T02:29:44.532348Z","caller":"traceutil/trace.go:171","msg":"trace[796451224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:889; }","duration":"151.993902ms","start":"2026-02-07T02:29:44.38035Z","end":"2026-02-07T02:29:44.532344Z","steps":["trace[796451224] 'range keys from in-memory index tree' (duration: 151.878529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:44.532677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.450509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-07T02:29:44.5327Z","caller":"traceutil/trace.go:171","msg":"trace[153267415] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:889; }","duration":"475.53162ms","start":"2026-02-07T02:29:44.057161Z","end":"2026-02-07T02:29:44.532693Z","steps":["trace[153267415] 'range keys from in-memory index tree' (duration: 475.251484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:44.532723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:44.057128Z","time spent":"475.585512ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-07T02:29:46.218689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.966966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:29:46.218744Z","caller":"traceutil/trace.go:171","msg":"trace[1518631890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:894; }","duration":"431.080549ms","start":"2026-02-07T02:29:45.787652Z","end":"2026-02-07T02:29:46.218732Z","steps":["trace[1518631890] 'range keys from in-memory index tree' (duration: 430.830073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:46.218769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:45.787573Z","time spent":"431.191201ms","remote":"127.0.0.1:60272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T02:29:46.219125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.464651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-07T02:29:46.219173Z","caller":"traceutil/trace.go:171","msg":"trace[1968299739] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:894; }","duration":"362.550884ms","start":"2026-02-07T02:29:45.856611Z","end":"2026-02-07T02:29:46.219162Z","steps":["trace[1968299739] 'range keys from in-memory index tree' (duration: 362.260217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:46.219196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:45.856593Z","time spent":"362.598634ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T02:29:46.842099Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276677654686906,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-07T02:29:47.010552Z","caller":"traceutil/trace.go:171","msg":"trace[1479235819] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"788.341337ms","start":"2026-02-07T02:29:46.222187Z","end":"2026-02-07T02:29:47.010529Z","steps":["trace[1479235819] 'process raft request' (duration: 788.194073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:47.010716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:46.222172Z","time spent":"788.451659ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:29:47.548904Z","caller":"traceutil/trace.go:171","msg":"trace[1340889142] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:931; }","duration":"1.20684638s","start":"2026-02-07T02:29:46.342025Z","end":"2026-02-07T02:29:47.548872Z","steps":["trace[1340889142] 'read index received' (duration: 668.793366ms)","trace[1340889142] 'applied index is now lower than readState.Index' (duration: 538.052174ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:29:47.548979Z","caller":"traceutil/trace.go:171","msg":"trace[1600415979] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"1.209045242s","start":"2026-02-07T02:29:46.339928Z","end":"2026-02-07T02:29:47.548974Z","steps":["trace[1600415979] 'process raft request' (duration: 1.208805986s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:47.549041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:46.339912Z","time spent":"1.209079302s","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:29:47.549137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.629995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-07T02:29:47.5492Z","caller":"traceutil/trace.go:171","msg":"trace[508502731] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:896; }","duration":"987.721726ms","start":"2026-02-07T02:29:46.561463Z","end":"2026-02-07T02:29:47.549185Z","steps":["trace[508502731] 'agreement among raft nodes before linearized reading' (duration: 987.542012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:47.549237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:46.56145Z","time spent":"987.778558ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-07T02:29:47.549301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.349444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-07T02:29:47.549321Z","caller":"traceutil/trace.go:171","msg":"trace[1806099542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:896; }","duration":"974.405255ms","start":"2026-02-07T02:29:46.57491Z","end":"2026-02-07T02:29:47.549316Z","steps":["trace[1806099542] 'agreement among raft nodes before linearized reading' (duration: 974.320514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:47.549339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:46.574893Z","time spent":"974.440217ms","remote":"127.0.0.1:60576","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-02-07T02:29:47.549386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207359673s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:29:47.549403Z","caller":"traceutil/trace.go:171","msg":"trace[2062634604] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:896; }","duration":"1.207390863s","start":"2026-02-07T02:29:46.342005Z","end":"2026-02-07T02:29:47.549396Z","steps":["trace[2062634604] 'agreement among raft nodes before linearized reading' (duration: 1.207367803s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:47.549415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:46.341999Z","time spent":"1.207413044s","remote":"127.0.0.1:60624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-02-07T02:29:47.549515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.262657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-07T02:29:47.549546Z","caller":"traceutil/trace.go:171","msg":"trace[1265721399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:896; }","duration":"987.299468ms","start":"2026-02-07T02:29:46.562238Z","end":"2026-02-07T02:29:47.549537Z","steps":["trace[1265721399] 'agreement among raft nodes before linearized reading' (duration: 987.218626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:47.549571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:46.562234Z","time spent":"987.331808ms","remote":"127.0.0.1:60576","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-02-07T02:29:47.549602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.40393ms","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-02-07T02:29:47.549619Z","caller":"traceutil/trace.go:171","msg":"trace[495993848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:896; }","duration":"987.429271ms","start":"2026-02-07T02:29:46.562185Z","end":"2026-02-07T02:29:47.549614Z","steps":["trace[495993848] 'agreement among raft nodes before linearized reading' (duration: 987.38043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:47.549631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:46.562181Z","time spent":"987.448152ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T02:29:50.926429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.570004ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:29:50.926529Z","caller":"traceutil/trace.go:171","msg":"trace[386681821] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:902; }","duration":"115.682026ms","start":"2026-02-07T02:29:50.810828Z","end":"2026-02-07T02:29:50.92651Z","steps":["trace[386681821] 'range keys from in-memory index tree' (duration: 115.555252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:50.926946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.25496805s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:29:50.927075Z","caller":"traceutil/trace.go:171","msg":"trace[191815867] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:938; }","duration":"1.140786596s","start":"2026-02-07T02:29:49.786282Z","end":"2026-02-07T02:29:50.927069Z","steps":["trace[191815867] 'read index received' (duration: 35.871µs)","trace[191815867] 'applied index is now lower than readState.Index' (duration: 1.140750175s)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:29:50.927122Z","caller":"traceutil/trace.go:171","msg":"trace[1657053741] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"1.283235112s","start":"2026-02-07T02:29:49.643883Z","end":"2026-02-07T02:29:50.927118Z","steps":["trace[1657053741] 'process raft request' (duration: 1.283097779s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:50.92715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:49.643876Z","time spent":"1.283258004s","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:29:50.927139Z","caller":"traceutil/trace.go:171","msg":"trace[1881617572] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"1.284787758s","start":"2026-02-07T02:29:49.642329Z","end":"2026-02-07T02:29:50.927117Z","steps":["trace[1881617572] 'process raft request' (duration: 29.609653ms)","trace[1881617572] 'compare' (duration: 1.254625342s)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:29:50.927244Z","caller":"traceutil/trace.go:171","msg":"trace[618783727] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"1.283302324s","start":"2026-02-07T02:29:49.643938Z","end":"2026-02-07T02:29:50.92724Z","steps":["trace[618783727] 'process raft request' (duration: 1.283094769s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:50.927264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:49.642313Z","time spent":"1.284888001s","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:29:50.927272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:49.643934Z","time spent":"1.283325165s","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:29:50.928728Z","caller":"traceutil/trace.go:171","msg":"trace[448362716] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"939.89708ms","start":"2026-02-07T02:29:49.988824Z","end":"2026-02-07T02:29:50.928721Z","steps":["trace[448362716] 'process raft request' (duration: 938.227871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:50.928766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:49.988808Z","time spent":"939.939092ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:29:51.024848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.238545132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:29:51.024911Z","caller":"traceutil/trace.go:171","msg":"trace[706202984] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:906; }","duration":"1.238656374s","start":"2026-02-07T02:29:49.786242Z","end":"2026-02-07T02:29:51.024899Z","steps":["trace[706202984] 'agreement among raft nodes before linearized reading' (duration: 1.141042721s)","trace[706202984] 'range keys from in-memory index tree' (duration: 97.524831ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:29:51.024937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:49.786225Z","time spent":"1.238707145s","remote":"127.0.0.1:60272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T02:29:51.02497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104698666s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T02:29:51.025015Z","caller":"traceutil/trace.go:171","msg":"trace[535753980] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:906; }","duration":"1.104777088s","start":"2026-02-07T02:29:49.920227Z","end":"2026-02-07T02:29:51.025004Z","steps":["trace[535753980] 'agreement among raft nodes before linearized reading' (duration: 1.104683986s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:51.025035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:49.920214Z","time spent":"1.104816619s","remote":"127.0.0.1:60678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-02-07T02:29:51.159255Z","caller":"traceutil/trace.go:171","msg":"trace[679930564] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:943; }","duration":"127.866182ms","start":"2026-02-07T02:29:51.031374Z","end":"2026-02-07T02:29:51.159241Z","steps":["trace[679930564] 'read index received' (duration: 127.714309ms)","trace[679930564] 'applied index is now lower than readState.Index' (duration: 151.303µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:29:51.159334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.947324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:29:51.159349Z","caller":"traceutil/trace.go:171","msg":"trace[805970203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:907; }","duration":"127.974815ms","start":"2026-02-07T02:29:51.031369Z","end":"2026-02-07T02:29:51.159344Z","steps":["trace[805970203] 'agreement among raft nodes before linearized reading' (duration: 127.926364ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:51.159464Z","caller":"traceutil/trace.go:171","msg":"trace[1679547126] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"131.787744ms","start":"2026-02-07T02:29:51.027669Z","end":"2026-02-07T02:29:51.159457Z","steps":["trace[1679547126] 'process raft request' (duration: 131.424546ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:58.295086Z","caller":"traceutil/trace.go:171","msg":"trace[471174705] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"481.902756ms","start":"2026-02-07T02:29:57.813168Z","end":"2026-02-07T02:29:58.295071Z","steps":["trace[471174705] 'read index received' (duration: 481.774713ms)","trace[471174705] 'applied index is now lower than readState.Index' (duration: 127.473µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:29:58.295178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.994378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:29:58.295193Z","caller":"traceutil/trace.go:171","msg":"trace[165403087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:924; }","duration":"482.053549ms","start":"2026-02-07T02:29:57.813135Z","end":"2026-02-07T02:29:58.295188Z","steps":["trace[165403087] 'agreement among raft nodes before linearized reading' (duration: 481.993658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:58.295211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:57.813123Z","time spent":"482.084691ms","remote":"127.0.0.1:60272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-07T02:29:58.29544Z","caller":"traceutil/trace.go:171","msg":"trace[1109577897] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"505.661024ms","start":"2026-02-07T02:29:57.789746Z","end":"2026-02-07T02:29:58.295407Z","steps":["trace[1109577897] 'process raft request' (duration: 505.238223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:58.295564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:57.789729Z","time spent":"505.769277ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:29:58.43791Z","caller":"traceutil/trace.go:171","msg":"trace[475297742] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"141.286107ms","start":"2026-02-07T02:29:58.296609Z","end":"2026-02-07T02:29:58.437895Z","steps":["trace[475297742] 'read index received' (duration: 141.177884ms)","trace[475297742] 'applied index is now lower than readState.Index' (duration: 107.553µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:29:58.437986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.361889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:29:58.438002Z","caller":"traceutil/trace.go:171","msg":"trace[2069063072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:925; }","duration":"141.41061ms","start":"2026-02-07T02:29:58.296587Z","end":"2026-02-07T02:29:58.437997Z","steps":["trace[2069063072] 'agreement among raft nodes before linearized reading' (duration: 141.368379ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:58.438123Z","caller":"traceutil/trace.go:171","msg":"trace[377194166] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"502.286834ms","start":"2026-02-07T02:29:57.935817Z","end":"2026-02-07T02:29:58.438104Z","steps":["trace[377194166] 'process raft request' (duration: 501.969746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:58.438909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:57.935802Z","time spent":"502.372997ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:29:59.328629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.155779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:29:59.328722Z","caller":"traceutil/trace.go:171","msg":"trace[751798177] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"115.915982ms","start":"2026-02-07T02:29:59.212791Z","end":"2026-02-07T02:29:59.328707Z","steps":["trace[751798177] 'read index received' (duration: 7.605289ms)","trace[751798177] 'applied index is now lower than readState.Index' (duration: 108.309463ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:29:59.328842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.050365ms","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-02-07T02:29:59.328843Z","caller":"traceutil/trace.go:171","msg":"trace[4757046] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"263.753084ms","start":"2026-02-07T02:29:59.065069Z","end":"2026-02-07T02:29:59.328822Z","steps":["trace[4757046] 'process raft request' (duration: 155.331388ms)","trace[4757046] 'compare' (duration: 108.028836ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:29:59.328869Z","caller":"traceutil/trace.go:171","msg":"trace[733669466] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:928; }","duration":"116.111916ms","start":"2026-02-07T02:29:59.212748Z","end":"2026-02-07T02:29:59.32886Z","steps":["trace[733669466] 'agreement among raft nodes before linearized reading' (duration: 116.000354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:01.288365Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276677654687003,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-07T02:30:01.288732Z","caller":"traceutil/trace.go:171","msg":"trace[1566339140] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"988.035691ms","start":"2026-02-07T02:30:00.300682Z","end":"2026-02-07T02:30:01.288718Z","steps":["trace[1566339140] 'process raft request' (duration: 987.948079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:01.28881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:00.300673Z","time spent":"988.084172ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:30:02.466438Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.177898909s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-07T02:30:02.788227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000372302s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-07T02:30:02.788305Z","caller":"traceutil/trace.go:171","msg":"trace[145453195] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000491025s","start":"2026-02-07T02:30:00.787797Z","end":"2026-02-07T02:30:02.788288Z","steps":["trace[145453195] 'agreement among raft nodes before linearized reading' (duration: 2.000395412s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:02.788353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:00.787781Z","time spent":"2.000563426s","remote":"127.0.0.1:60272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T02:30:04.791381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000147127s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-07T02:30:04.7915Z","caller":"traceutil/trace.go:171","msg":"trace[545033489] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000350952s","start":"2026-02-07T02:30:02.791113Z","end":"2026-02-07T02:30:04.791464Z","steps":["trace[545033489] 'agreement among raft nodes before linearized reading' (duration: 2.000162957s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:04.791568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:02.791101Z","time spent":"2.000436763s","remote":"127.0.0.1:60264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/07 02:30:04 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-07T02:30:05.370813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.904159574s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-07T02:30:05.373377Z","caller":"traceutil/trace.go:171","msg":"trace[834678627] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:968; }","duration":"4.585533656s","start":"2026-02-07T02:30:00.787829Z","end":"2026-02-07T02:30:05.373362Z","steps":["trace[834678627] 'read index received' (duration: 501.281201ms)","trace[834678627] 'applied index is now lower than readState.Index' (duration: 4.084251235s)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:30:05.373508Z","caller":"traceutil/trace.go:171","msg":"trace[725165159] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"4.337685366s","start":"2026-02-07T02:30:01.035811Z","end":"2026-02-07T02:30:05.373496Z","steps":["trace[725165159] 'process raft request' (duration: 4.335100805s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:05.37364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:01.035793Z","time spent":"4.337766007s","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:30:06.075312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.003695155s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-07T02:30:06.075411Z","caller":"traceutil/trace.go:171","msg":"trace[537133138] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"5.003860679s","start":"2026-02-07T02:30:01.071533Z","end":"2026-02-07T02:30:06.075394Z","steps":["trace[537133138] 'agreement among raft nodes before linearized reading' (duration: 5.003720386s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:06.075466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:01.071515Z","time spent":"5.00394089s","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/02/07 02:30:06 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-07T02:30:06.207478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000189238s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-07T02:30:06.20756Z","caller":"traceutil/trace.go:171","msg":"trace[1518433590] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.0002908s","start":"2026-02-07T02:30:04.207249Z","end":"2026-02-07T02:30:06.20754Z","steps":["trace[1518433590] 'agreement among raft nodes before linearized reading' (duration: 2.000182737s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:06.207608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:04.2072Z","time spent":"2.000399593s","remote":"127.0.0.1:60272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T02:30:06.390075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999981928s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-07T02:30:06.390176Z","caller":"traceutil/trace.go:171","msg":"trace[1454996392] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"5.000123801s","start":"2026-02-07T02:30:01.39002Z","end":"2026-02-07T02:30:06.390144Z","steps":["trace[1454996392] 'agreement among raft nodes before linearized reading' (duration: 5.000011329s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:06.390221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:01.390003Z","time spent":"5.000209753s","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T02:30:06.420255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.705337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:30:06.420343Z","caller":"traceutil/trace.go:171","msg":"trace[725029214] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:970; }","duration":"1.046921463s","start":"2026-02-07T02:30:05.373406Z","end":"2026-02-07T02:30:06.420328Z","steps":["trace[725029214] 'read index received' (duration: 141.063523ms)","trace[725029214] 'applied index is now lower than readState.Index' (duration: 905.85683ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:30:06.420433Z","caller":"traceutil/trace.go:171","msg":"trace[1610349123] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"1.549334181s","start":"2026-02-07T02:30:04.871089Z","end":"2026-02-07T02:30:06.420423Z","steps":["trace[1610349123] 'process raft request' (duration: 643.400768ms)","trace[1610349123] 'compare' (duration: 905.235056ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:06.420478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:04.871073Z","time spent":"1.549383542s","remote":"127.0.0.1:60370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T02:30:06.420751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.357733112s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-07T02:30:06.42078Z","caller":"traceutil/trace.go:171","msg":"trace[1252788392] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:932; }","duration":"1.357801084s","start":"2026-02-07T02:30:05.06297Z","end":"2026-02-07T02:30:06.420771Z","steps":["trace[1252788392] 'agreement among raft nodes before linearized reading' (duration: 1.357672101s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:06.420862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:05.062952Z","time spent":"1.357894725s","remote":"127.0.0.1:60314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.49\" "} {"level":"warn","ts":"2026-02-07T02:30:06.421058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.548529552s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:30:06.421092Z","caller":"traceutil/trace.go:171","msg":"trace[293493260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"1.548574874s","start":"2026-02-07T02:30:04.872504Z","end":"2026-02-07T02:30:06.421079Z","steps":["trace[293493260] 'agreement among raft nodes before linearized reading' (duration: 1.548528703s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:06.421117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:04.872498Z","time spent":"1.548613374s","remote":"127.0.0.1:60264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T02:30:06.421285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.127406076s","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-02-07T02:30:06.421312Z","caller":"traceutil/trace.go:171","msg":"trace[50518287] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:932; }","duration":"3.127476698s","start":"2026-02-07T02:30:03.293826Z","end":"2026-02-07T02:30:06.421303Z","steps":["trace[50518287] 'agreement among raft nodes before linearized reading' (duration: 3.127400066s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:06.421338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:03.293804Z","time spent":"3.127525868s","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T02:30:06.421559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.086704994s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-07T02:30:06.421588Z","caller":"traceutil/trace.go:171","msg":"trace[1894829609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:932; }","duration":"5.086759314s","start":"2026-02-07T02:30:01.33482Z","end":"2026-02-07T02:30:06.42158Z","steps":["trace[1894829609] 'agreement among raft nodes before linearized reading' (duration: 5.086677502s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:06.421614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:01.334807Z","time spent":"5.086801586s","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T02:30:06.421771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.340209297s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-07T02:30:06.42182Z","caller":"traceutil/trace.go:171","msg":"trace[520954569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:932; }","duration":"5.340282499s","start":"2026-02-07T02:30:01.081529Z","end":"2026-02-07T02:30:06.421812Z","steps":["trace[520954569] 'agreement among raft nodes before linearized reading' (duration: 5.340187816s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:06.421846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:01.081493Z","time spent":"5.340345539s","remote":"127.0.0.1:60576","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":"warn","ts":"2026-02-07T02:30:06.422006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.65232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:30:06.42206Z","caller":"traceutil/trace.go:171","msg":"trace[1097801620] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:932; }","duration":"171.732732ms","start":"2026-02-07T02:30:06.250305Z","end":"2026-02-07T02:30:06.422038Z","steps":["trace[1097801620] 'agreement among raft nodes before linearized reading' (duration: 171.67313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:07.163906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.130892ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-02-07T02:30:07.163995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:06.45918Z","time spent":"704.811509ms","remote":"127.0.0.1:60314","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-07T02:30:07.190246Z","caller":"traceutil/trace.go:171","msg":"trace[733459517] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:971; }","duration":"552.702949ms","start":"2026-02-07T02:30:06.637523Z","end":"2026-02-07T02:30:07.190226Z","steps":["trace[733459517] 'read index received' (duration: 288.251309ms)","trace[733459517] 'applied index is now lower than readState.Index' (duration: 264.45081ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:07.190474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.933224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:30:07.190532Z","caller":"traceutil/trace.go:171","msg":"trace[1474947041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:936; }","duration":"553.019187ms","start":"2026-02-07T02:30:06.637503Z","end":"2026-02-07T02:30:07.190523Z","steps":["trace[1474947041] 'agreement among raft nodes before linearized reading' (duration: 552.897564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:07.190561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:06.637497Z","time spent":"553.056948ms","remote":"127.0.0.1:60272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-07T02:30:07.191264Z","caller":"traceutil/trace.go:171","msg":"trace[864747781] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"553.970519ms","start":"2026-02-07T02:30:06.637279Z","end":"2026-02-07T02:30:07.191249Z","steps":["trace[864747781] 'process raft request' (duration: 552.831922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:07.191352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:06.637276Z","time spent":"554.02559ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:30:07.191596Z","caller":"traceutil/trace.go:171","msg":"trace[627842917] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"554.231225ms","start":"2026-02-07T02:30:06.637329Z","end":"2026-02-07T02:30:07.19156Z","steps":["trace[627842917] 'process raft request' (duration: 552.829403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:07.191748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:06.637326Z","time spent":"554.337097ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:30:07.192058Z","caller":"traceutil/trace.go:171","msg":"trace[1498907074] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"541.307671ms","start":"2026-02-07T02:30:06.650737Z","end":"2026-02-07T02:30:07.192044Z","steps":["trace[1498907074] 'process raft request' (duration: 539.447698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:07.192247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:06.650723Z","time spent":"541.473705ms","remote":"127.0.0.1:60370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T02:30:07.192301Z","caller":"traceutil/trace.go:171","msg":"trace[1869414199] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"555.069024ms","start":"2026-02-07T02:30:06.637218Z","end":"2026-02-07T02:30:07.192287Z","steps":["trace[1869414199] 'process raft request' (duration: 552.773641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:07.192367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:06.6372Z","time spent":"555.133636ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:30:07.543505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.794111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:30:07.543575Z","caller":"traceutil/trace.go:171","msg":"trace[963440222] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:977; }","duration":"339.686226ms","start":"2026-02-07T02:30:07.203877Z","end":"2026-02-07T02:30:07.543563Z","steps":["trace[963440222] 'read index received' (duration: 76.804343ms)","trace[963440222] 'applied index is now lower than readState.Index' (duration: 262.881123ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:07.543648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.767938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-07T02:30:07.543669Z","caller":"traceutil/trace.go:171","msg":"trace[478717486] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:938; }","duration":"339.800199ms","start":"2026-02-07T02:30:07.203863Z","end":"2026-02-07T02:30:07.543663Z","steps":["trace[478717486] 'agreement among raft nodes before linearized reading' (duration: 339.736678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:07.543692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:07.203857Z","time spent":"339.82901ms","remote":"127.0.0.1:60584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-02-07T02:30:07.544217Z","caller":"traceutil/trace.go:171","msg":"trace[831086734] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"341.272964ms","start":"2026-02-07T02:30:07.202935Z","end":"2026-02-07T02:30:07.544207Z","steps":["trace[831086734] 'process raft request' (duration: 77.721145ms)","trace[831086734] 'compare' (duration: 262.72013ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:07.544268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:07.202928Z","time spent":"341.315385ms","remote":"127.0.0.1:60370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:30:14.652513Z","caller":"traceutil/trace.go:171","msg":"trace[1955012835] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"217.967428ms","start":"2026-02-07T02:30:14.434513Z","end":"2026-02-07T02:30:14.65248Z","steps":["trace[1955012835] 'process raft request' (duration: 217.753893ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:30:15.353002Z","caller":"traceutil/trace.go:171","msg":"trace[527869854] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"119.233949ms","start":"2026-02-07T02:30:15.23374Z","end":"2026-02-07T02:30:15.352974Z","steps":["trace[527869854] 'read index received' (duration: 84.37667ms)","trace[527869854] 'applied index is now lower than readState.Index' (duration: 34.856169ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:30:15.353116Z","caller":"traceutil/trace.go:171","msg":"trace[1136612978] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"202.528636ms","start":"2026-02-07T02:30:15.150577Z","end":"2026-02-07T02:30:15.353106Z","steps":["trace[1136612978] 'process raft request' (duration: 167.626246ms)","trace[1136612978] 'compare' (duration: 34.643514ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:15.353668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.004224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-07T02:30:15.353698Z","caller":"traceutil/trace.go:171","msg":"trace[917144895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:977; }","duration":"116.074716ms","start":"2026-02-07T02:30:15.237614Z","end":"2026-02-07T02:30:15.353689Z","steps":["trace[917144895] 'agreement among raft nodes before linearized reading' (duration: 115.875181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:15.353899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.161152ms","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-02-07T02:30:15.353914Z","caller":"traceutil/trace.go:171","msg":"trace[1003588370] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:977; }","duration":"120.229153ms","start":"2026-02-07T02:30:15.233681Z","end":"2026-02-07T02:30:15.35391Z","steps":["trace[1003588370] 'agreement among raft nodes before linearized reading' (duration: 120.178102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:15.755755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.084671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-07T02:30:15.755839Z","caller":"traceutil/trace.go:171","msg":"trace[2127136155] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:978; }","duration":"396.196743ms","start":"2026-02-07T02:30:15.359622Z","end":"2026-02-07T02:30:15.755818Z","steps":["trace[2127136155] 'agreement among raft nodes before linearized reading' (duration: 95.437621ms)","trace[2127136155] 'range keys from in-memory index tree' (duration: 300.613948ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:15.755872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:15.359615Z","time spent":"396.251654ms","remote":"127.0.0.1:60448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-07T02:30:15.756896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.413448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:30:15.757004Z","caller":"traceutil/trace.go:171","msg":"trace[173670224] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"395.411324ms","start":"2026-02-07T02:30:15.361585Z","end":"2026-02-07T02:30:15.756996Z","steps":["trace[173670224] 'process raft request' (duration: 395.360073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:15.757046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:15.361579Z","time spent":"395.447636ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:30:15.757237Z","caller":"traceutil/trace.go:171","msg":"trace[358682961] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"397.556166ms","start":"2026-02-07T02:30:15.359673Z","end":"2026-02-07T02:30:15.757229Z","steps":["trace[358682961] 'process raft request' (duration: 158.737808ms)","trace[358682961] 'compare' (duration: 237.21244ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:15.757265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:15.359669Z","time spent":"397.580796ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:30:15.898978Z","caller":"traceutil/trace.go:171","msg":"trace[1122734679] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1022; }","duration":"139.932926ms","start":"2026-02-07T02:30:15.759026Z","end":"2026-02-07T02:30:15.898958Z","steps":["trace[1122734679] 'read index received' (duration: 97.873219ms)","trace[1122734679] 'applied index is now lower than readState.Index' (duration: 42.059147ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:30:15.899212Z","caller":"traceutil/trace.go:171","msg":"trace[553428152] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"160.52616ms","start":"2026-02-07T02:30:15.738677Z","end":"2026-02-07T02:30:15.899204Z","steps":["trace[553428152] 'process raft request' (duration: 118.300088ms)","trace[553428152] 'compare' (duration: 41.904364ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:15.899399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.357317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-07T02:30:15.899427Z","caller":"traceutil/trace.go:171","msg":"trace[974113491] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:981; }","duration":"140.426238ms","start":"2026-02-07T02:30:15.758994Z","end":"2026-02-07T02:30:15.89942Z","steps":["trace[974113491] 'agreement among raft nodes before linearized reading' (duration: 140.322256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:15.899542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.582648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:30:15.899564Z","caller":"traceutil/trace.go:171","msg":"trace[570290718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:981; }","duration":"110.640848ms","start":"2026-02-07T02:30:15.788919Z","end":"2026-02-07T02:30:15.899559Z","steps":["trace[570290718] 'agreement among raft nodes before linearized reading' (duration: 110.606728ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:30:22.509831Z","caller":"traceutil/trace.go:171","msg":"trace[978838082] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"356.993152ms","start":"2026-02-07T02:30:22.152817Z","end":"2026-02-07T02:30:22.50981Z","steps":["trace[978838082] 'process raft request' (duration: 356.849319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:22.509966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:22.152797Z","time spent":"357.099225ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:30:22.88396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.350889ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-07T02:30:22.88406Z","caller":"traceutil/trace.go:171","msg":"trace[437012340] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1055; }","duration":"204.331687ms","start":"2026-02-07T02:30:22.679715Z","end":"2026-02-07T02:30:22.884047Z","steps":["trace[437012340] 'read index received' (duration: 86.962µs)","trace[437012340] 'applied index is now lower than readState.Index' (duration: 204.243435ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:22.884243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.655786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-07T02:30:22.884263Z","caller":"traceutil/trace.go:171","msg":"trace[395711230] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1013; }","duration":"204.740317ms","start":"2026-02-07T02:30:22.679516Z","end":"2026-02-07T02:30:22.884256Z","steps":["trace[395711230] 'agreement among raft nodes before linearized reading' (duration: 204.565103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:24.563807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.633226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:30:24.564704Z","caller":"traceutil/trace.go:171","msg":"trace[1220914843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"777.559189ms","start":"2026-02-07T02:30:23.787125Z","end":"2026-02-07T02:30:24.564684Z","steps":["trace[1220914843] 'range keys from in-memory index tree' (duration: 776.578035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:24.564751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:23.78711Z","time spent":"777.62747ms","remote":"127.0.0.1:60272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T02:30:24.565047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.139191ms","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-02-07T02:30:24.5651Z","caller":"traceutil/trace.go:171","msg":"trace[1084895207] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1015; }","duration":"772.226774ms","start":"2026-02-07T02:30:23.792843Z","end":"2026-02-07T02:30:24.565069Z","steps":["trace[1084895207] 'range keys from in-memory index tree' (duration: 771.903726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:24.565129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:23.792816Z","time spent":"772.306395ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T02:30:24.565651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.920502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-07T02:30:24.565702Z","caller":"traceutil/trace.go:171","msg":"trace[509858048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1015; }","duration":"610.016014ms","start":"2026-02-07T02:30:23.955676Z","end":"2026-02-07T02:30:24.565692Z","steps":["trace[509858048] 'range keys from in-memory index tree' (duration: 609.81786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:24.56573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:23.955652Z","time spent":"610.071206ms","remote":"127.0.0.1:60576","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-02-07T02:30:25.304371Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276677654687194,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-07T02:30:25.569965Z","caller":"traceutil/trace.go:171","msg":"trace[310180230] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"995.253181ms","start":"2026-02-07T02:30:24.574693Z","end":"2026-02-07T02:30:25.569946Z","steps":["trace[310180230] 'process raft request' (duration: 995.134208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:25.570088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:24.574664Z","time spent":"995.364753ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:30:26.000069Z","caller":"traceutil/trace.go:171","msg":"trace[878167584] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1059; }","duration":"1.196139157s","start":"2026-02-07T02:30:24.803902Z","end":"2026-02-07T02:30:26.000041Z","steps":["trace[878167584] 'read index received' (duration: 766.038758ms)","trace[878167584] 'applied index is now lower than readState.Index' (duration: 430.099039ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:26.000444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.196508226s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:30:26.000488Z","caller":"traceutil/trace.go:171","msg":"trace[744998194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1018; }","duration":"1.196606748s","start":"2026-02-07T02:30:24.803872Z","end":"2026-02-07T02:30:26.000479Z","steps":["trace[744998194] 'agreement among raft nodes before linearized reading' (duration: 1.196489046s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:26.000517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:24.803857Z","time spent":"1.196654009s","remote":"127.0.0.1:60272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-07T02:30:26.000905Z","caller":"traceutil/trace.go:171","msg":"trace[1998055293] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"1.398518701s","start":"2026-02-07T02:30:24.602377Z","end":"2026-02-07T02:30:26.000896Z","steps":["trace[1998055293] 'process raft request' (duration: 1.397463335s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:26.000991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:24.602347Z","time spent":"1.398587252s","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:30:26.001104Z","caller":"traceutil/trace.go:171","msg":"trace[1589598727] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"1.39851316s","start":"2026-02-07T02:30:24.602583Z","end":"2026-02-07T02:30:26.001096Z","steps":["trace[1589598727] 'process raft request' (duration: 1.397425494s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:26.001143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:24.602576Z","time spent":"1.398547651s","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:30:26.007988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16048845s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-07T02:30:26.008086Z","caller":"traceutil/trace.go:171","msg":"trace[1474846801] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1018; }","duration":"1.160606493s","start":"2026-02-07T02:30:24.847457Z","end":"2026-02-07T02:30:26.008064Z","steps":["trace[1474846801] 'agreement among raft nodes before linearized reading' (duration: 1.160338246s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:26.008125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:24.84742Z","time spent":"1.160699945s","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":534,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-07T02:30:26.008268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.218293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-07T02:30:26.008285Z","caller":"traceutil/trace.go:171","msg":"trace[321316234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1018; }","duration":"485.269735ms","start":"2026-02-07T02:30:25.523012Z","end":"2026-02-07T02:30:26.008281Z","steps":["trace[321316234] 'agreement among raft nodes before linearized reading' (duration: 485.224454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:26.008298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:25.522991Z","time spent":"485.303886ms","remote":"127.0.0.1:60576","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-02-07T02:30:26.008361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.501035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-07T02:30:26.008376Z","caller":"traceutil/trace.go:171","msg":"trace[1212618343] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:1018; }","duration":"943.544547ms","start":"2026-02-07T02:30:25.064828Z","end":"2026-02-07T02:30:26.008373Z","steps":["trace[1212618343] 'agreement among raft nodes before linearized reading' (duration: 943.501676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:26.008392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:25.064811Z","time spent":"943.574987ms","remote":"127.0.0.1:60314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.49\" "} {"level":"warn","ts":"2026-02-07T02:30:26.008652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075508935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-07T02:30:26.00867Z","caller":"traceutil/trace.go:171","msg":"trace[1226503169] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1018; }","duration":"1.075562846s","start":"2026-02-07T02:30:24.933103Z","end":"2026-02-07T02:30:26.008666Z","steps":["trace[1226503169] 'agreement among raft nodes before linearized reading' (duration: 1.075510834s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:26.008683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:24.933086Z","time spent":"1.075593767s","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-07T02:30:29.497699Z","caller":"traceutil/trace.go:171","msg":"trace[427441569] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"483.586546ms","start":"2026-02-07T02:30:29.014084Z","end":"2026-02-07T02:30:29.49767Z","steps":["trace[427441569] 'process raft request' (duration: 483.371641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:29.498013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:29.014066Z","time spent":"483.812392ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:30:30.001614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.422835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:30:30.002104Z","caller":"traceutil/trace.go:171","msg":"trace[415607897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1037; }","duration":"211.956047ms","start":"2026-02-07T02:30:29.790128Z","end":"2026-02-07T02:30:30.002084Z","steps":["trace[415607897] 'range keys from in-memory index tree' (duration: 211.354323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:30.007169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.624222ms","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-02-07T02:30:30.007321Z","caller":"traceutil/trace.go:171","msg":"trace[879316647] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1037; }","duration":"404.925409ms","start":"2026-02-07T02:30:29.602377Z","end":"2026-02-07T02:30:30.007303Z","steps":["trace[879316647] 'range keys from in-memory index tree' (duration: 404.309995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:30.007356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:29.601573Z","time spent":"405.773798ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T02:30:54.7648Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276677654687511,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T02:30:55.266489Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276677654687511,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T02:30:55.767416Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276677654687511,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T02:30:56.267864Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276677654687511,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T02:30:56.511288Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.253692861s","expected-duration":"1s"} {"level":"info","ts":"2026-02-07T02:30:56.511547Z","caller":"traceutil/trace.go:171","msg":"trace[1394556731] linearizableReadLoop","detail":"{readStateIndex:1203; appliedIndex:1202; }","duration":"2.247301501s","start":"2026-02-07T02:30:54.264229Z","end":"2026-02-07T02:30:56.511531Z","steps":["trace[1394556731] 'read index received' (duration: 2.247147657s)","trace[1394556731] 'applied index is now lower than readState.Index' (duration: 153.014µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:30:56.512359Z","caller":"traceutil/trace.go:171","msg":"trace[536794706] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"2.254828847s","start":"2026-02-07T02:30:54.257519Z","end":"2026-02-07T02:30:56.512348Z","steps":["trace[536794706] 'process raft request' (duration: 2.253900776s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:56.512466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:54.257512Z","time spent":"2.254871448s","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:30:56.549272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.239544168s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-07T02:30:56.549333Z","caller":"traceutil/trace.go:171","msg":"trace[470849538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1154; }","duration":"2.239704651s","start":"2026-02-07T02:30:54.309614Z","end":"2026-02-07T02:30:56.549319Z","steps":["trace[470849538] 'agreement among raft nodes before linearized reading' (duration: 2.203328797s)","trace[470849538] 'range keys from in-memory index tree' (duration: 36.212991ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:56.549333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.112648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"warn","ts":"2026-02-07T02:30:56.549364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:54.309605Z","time spent":"2.239753043s","remote":"127.0.0.1:60576","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-02-07T02:30:56.549401Z","caller":"traceutil/trace.go:171","msg":"trace[228149037] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1154; }","duration":"553.226161ms","start":"2026-02-07T02:30:55.996153Z","end":"2026-02-07T02:30:56.549379Z","steps":["trace[228149037] 'agreement among raft nodes before linearized reading' (duration: 516.753524ms)","trace[228149037] 'range keys from in-memory index tree' (duration: 36.289693ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:56.549453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:55.996142Z","time spent":"553.303222ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":551,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-07T02:30:56.549546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.490035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:30:56.549613Z","caller":"traceutil/trace.go:171","msg":"trace[1691245884] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1154; }","duration":"762.577097ms","start":"2026-02-07T02:30:55.78702Z","end":"2026-02-07T02:30:56.549597Z","steps":["trace[1691245884] 'agreement among raft nodes before linearized reading' (duration: 725.891406ms)","trace[1691245884] 'range keys from in-memory index tree' (duration: 36.62112ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:56.54964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:55.787005Z","time spent":"762.629228ms","remote":"127.0.0.1:60272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T02:30:56.549855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.288566997s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-02-07T02:30:56.549873Z","caller":"traceutil/trace.go:171","msg":"trace[1809277670] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1154; }","duration":"1.288620889s","start":"2026-02-07T02:30:55.261246Z","end":"2026-02-07T02:30:56.549867Z","steps":["trace[1809277670] 'agreement among raft nodes before linearized reading' (duration: 1.251670992s)","trace[1809277670] 'range keys from in-memory index tree' (duration: 36.884506ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:56.549892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:55.261231Z","time spent":"1.28865753s","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-07T02:30:56.549947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.353086403s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T02:30:56.549962Z","caller":"traceutil/trace.go:171","msg":"trace[1267033210] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1154; }","duration":"1.353138245s","start":"2026-02-07T02:30:55.196818Z","end":"2026-02-07T02:30:56.549956Z","steps":["trace[1267033210] 'agreement among raft nodes before linearized reading' (duration: 1.316103885s)","trace[1267033210] 'count revisions from in-memory index tree' (duration: 37.01025ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:56.549976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:55.196782Z","time spent":"1.353189497s","remote":"127.0.0.1:60712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-02-07T02:30:56.550097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.483498445s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-07T02:30:56.550116Z","caller":"traceutil/trace.go:171","msg":"trace[1609580934] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:1154; }","duration":"1.483553207s","start":"2026-02-07T02:30:55.066556Z","end":"2026-02-07T02:30:56.550109Z","steps":["trace[1609580934] 'agreement among raft nodes before linearized reading' (duration: 1.446370913s)","trace[1609580934] 'range keys from in-memory index tree' (duration: 37.131213ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:56.550131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:55.06654Z","time spent":"1.483587447s","remote":"127.0.0.1:60314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.49\" "} {"level":"warn","ts":"2026-02-07T02:30:56.55024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.733457546s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T02:30:56.550259Z","caller":"traceutil/trace.go:171","msg":"trace[1325396113] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1154; }","duration":"1.733513346s","start":"2026-02-07T02:30:54.81674Z","end":"2026-02-07T02:30:56.550253Z","steps":["trace[1325396113] 'agreement among raft nodes before linearized reading' (duration: 1.696191749s)","trace[1325396113] 'count revisions from in-memory index tree' (duration: 37.294367ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:56.550276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:54.816718Z","time spent":"1.733553988s","remote":"127.0.0.1:60508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-07T02:30:56.550388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.240166483s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-07T02:30:56.550416Z","caller":"traceutil/trace.go:171","msg":"trace[1441559566] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1154; }","duration":"2.240208795s","start":"2026-02-07T02:30:54.310202Z","end":"2026-02-07T02:30:56.550411Z","steps":["trace[1441559566] 'agreement among raft nodes before linearized reading' (duration: 2.202736824s)","trace[1441559566] 'range keys from in-memory index tree' (duration: 37.413489ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:56.550431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:54.310196Z","time spent":"2.240231385s","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-07T02:30:56.551474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.287238378s","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-02-07T02:30:56.551507Z","caller":"traceutil/trace.go:171","msg":"trace[1750592851] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1154; }","duration":"2.287276109s","start":"2026-02-07T02:30:54.264219Z","end":"2026-02-07T02:30:56.551495Z","steps":["trace[1750592851] 'agreement among raft nodes before linearized reading' (duration: 2.248628111s)","trace[1750592851] 'range keys from in-memory index tree' (duration: 38.575426ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:56.551528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:54.264214Z","time spent":"2.287308989s","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T02:30:56.55164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.24771645s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-07T02:30:56.551663Z","caller":"traceutil/trace.go:171","msg":"trace[428705778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1154; }","duration":"2.247775812s","start":"2026-02-07T02:30:54.30388Z","end":"2026-02-07T02:30:56.551656Z","steps":["trace[428705778] 'agreement among raft nodes before linearized reading' (duration: 2.20898397s)","trace[428705778] 'range keys from in-memory index tree' (duration: 38.73361ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:56.551682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:54.303862Z","time spent":"2.247815502s","remote":"127.0.0.1:60576","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-02-07T02:30:56.552969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.766058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-02-07T02:30:56.553001Z","caller":"traceutil/trace.go:171","msg":"trace[1414147122] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1154; }","duration":"264.824549ms","start":"2026-02-07T02:30:56.288166Z","end":"2026-02-07T02:30:56.552991Z","steps":["trace[1414147122] 'agreement among raft nodes before linearized reading' (duration: 224.726357ms)","trace[1414147122] 'range keys from in-memory index tree' (duration: 40.037081ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:56.553259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.467964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-02-07T02:30:56.553289Z","caller":"traceutil/trace.go:171","msg":"trace[1726149396] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1154; }","duration":"328.539726ms","start":"2026-02-07T02:30:56.224743Z","end":"2026-02-07T02:30:56.553283Z","steps":["trace[1726149396] 'agreement among raft nodes before linearized reading' (duration: 288.154617ms)","trace[1726149396] 'range keys from in-memory index tree' (duration: 40.314878ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:56.553395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:56.224475Z","time spent":"328.901704ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-07T02:30:56.558164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.090617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-07T02:30:56.55951Z","caller":"traceutil/trace.go:171","msg":"trace[2116182053] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1154; }","duration":"459.460639ms","start":"2026-02-07T02:30:56.100034Z","end":"2026-02-07T02:30:56.559495Z","steps":["trace[2116182053] 'agreement among raft nodes before linearized reading' (duration: 412.868194ms)","trace[2116182053] 'range keys from in-memory index tree' (duration: 45.201812ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:56.559655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:56.100026Z","time spent":"459.516641ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-07T02:31:59.469933Z","caller":"traceutil/trace.go:171","msg":"trace[1087835390] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"177.004167ms","start":"2026-02-07T02:31:59.292895Z","end":"2026-02-07T02:31:59.469899Z","steps":["trace[1087835390] 'process raft request' (duration: 97.550701ms)","trace[1087835390] 'compare' (duration: 79.064067ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:32:01.700603Z","caller":"traceutil/trace.go:171","msg":"trace[1333936436] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"145.348154ms","start":"2026-02-07T02:32:01.555229Z","end":"2026-02-07T02:32:01.700577Z","steps":["trace[1333936436] 'process raft request' (duration: 60.133137ms)","trace[1333936436] 'compare' (duration: 85.126824ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:33:05.529692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.571072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T02:33:05.529903Z","caller":"traceutil/trace.go:171","msg":"trace[1082472663] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1684; }","duration":"118.832168ms","start":"2026-02-07T02:33:05.41105Z","end":"2026-02-07T02:33:05.529882Z","steps":["trace[1082472663] 'count revisions from in-memory index tree' (duration: 118.47099ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:33:47.417705Z","caller":"traceutil/trace.go:171","msg":"trace[1154165487] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"142.004074ms","start":"2026-02-07T02:33:47.275677Z","end":"2026-02-07T02:33:47.417681Z","steps":["trace[1154165487] 'process raft request' (duration: 141.84998ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:33:47.485603Z","caller":"traceutil/trace.go:171","msg":"trace[53053752] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"209.690988ms","start":"2026-02-07T02:33:47.275889Z","end":"2026-02-07T02:33:47.48558Z","steps":["trace[53053752] 'process raft request' (duration: 198.710619ms)","trace[53053752] 'compare' (duration: 10.660441ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:33:57.648988Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256276677654689493,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-07T02:33:57.875104Z","caller":"traceutil/trace.go:171","msg":"trace[1383151823] linearizableReadLoop","detail":"{readStateIndex:2011; appliedIndex:2010; }","duration":"726.718542ms","start":"2026-02-07T02:33:57.148366Z","end":"2026-02-07T02:33:57.875084Z","steps":["trace[1383151823] 'read index received' (duration: 726.538818ms)","trace[1383151823] 'applied index is now lower than readState.Index' (duration: 178.924µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:33:57.875205Z","caller":"traceutil/trace.go:171","msg":"trace[244786360] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"729.085857ms","start":"2026-02-07T02:33:57.146112Z","end":"2026-02-07T02:33:57.875197Z","steps":["trace[244786360] 'process raft request' (duration: 728.860132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:33:57.875286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:33:57.146089Z","time spent":"729.137938ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:33:57.875374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.685348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T02:33:57.875443Z","caller":"traceutil/trace.go:171","msg":"trace[1911678853] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1924; }","duration":"638.804711ms","start":"2026-02-07T02:33:57.236622Z","end":"2026-02-07T02:33:57.875426Z","steps":["trace[1911678853] 'agreement among raft nodes before linearized reading' (duration: 638.686608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:33:57.875485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:33:57.236596Z","time spent":"638.879203ms","remote":"127.0.0.1:60678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-07T02:33:57.875586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.024119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-07T02:33:57.875745Z","caller":"traceutil/trace.go:171","msg":"trace[81895766] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1924; }","duration":"251.181053ms","start":"2026-02-07T02:33:57.624555Z","end":"2026-02-07T02:33:57.875736Z","steps":["trace[81895766] 'agreement among raft nodes before linearized reading' (duration: 250.961978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:33:57.875801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.794381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-07T02:33:57.875839Z","caller":"traceutil/trace.go:171","msg":"trace[1346417890] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1924; }","duration":"255.831062ms","start":"2026-02-07T02:33:57.619997Z","end":"2026-02-07T02:33:57.875828Z","steps":["trace[1346417890] 'agreement among raft nodes before linearized reading' (duration: 255.707639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:33:57.875913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.537491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-07T02:33:57.875946Z","caller":"traceutil/trace.go:171","msg":"trace[757745071] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1924; }","duration":"727.608923ms","start":"2026-02-07T02:33:57.148327Z","end":"2026-02-07T02:33:57.875936Z","steps":["trace[757745071] 'agreement among raft nodes before linearized reading' (duration: 727.51424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:33:57.875966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:33:57.148311Z","time spent":"727.650093ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-07T02:33:57.876044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.082466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-07T02:33:57.876075Z","caller":"traceutil/trace.go:171","msg":"trace[469107255] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1924; }","duration":"374.145748ms","start":"2026-02-07T02:33:57.50192Z","end":"2026-02-07T02:33:57.876065Z","steps":["trace[469107255] 'agreement among raft nodes before linearized reading' (duration: 374.063186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:33:57.876104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:33:57.501903Z","time spent":"374.19396ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":551,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-07T02:33:57.87775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.944402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-02-07T02:33:57.894084Z","caller":"traceutil/trace.go:171","msg":"trace[569679847] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1924; }","duration":"460.315197ms","start":"2026-02-07T02:33:57.433754Z","end":"2026-02-07T02:33:57.89407Z","steps":["trace[569679847] 'agreement among raft nodes before linearized reading' (duration: 443.919452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:33:57.894476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:33:57.433734Z","time spent":"460.721228ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-07T02:34:34.327376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.976145ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:34:34.32747Z","caller":"traceutil/trace.go:171","msg":"trace[795709325] linearizableReadLoop","detail":"{readStateIndex:2178; appliedIndex:2177; }","duration":"167.222263ms","start":"2026-02-07T02:34:34.160234Z","end":"2026-02-07T02:34:34.327456Z","steps":["trace[795709325] 'read index received' (duration: 52.031394ms)","trace[795709325] 'applied index is now lower than readState.Index' (duration: 115.189879ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:34:34.327966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.707254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-07T02:34:34.328119Z","caller":"traceutil/trace.go:171","msg":"trace[1219051619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2084; }","duration":"167.873328ms","start":"2026-02-07T02:34:34.160228Z","end":"2026-02-07T02:34:34.328102Z","steps":["trace[1219051619] 'agreement among raft nodes before linearized reading' (duration: 167.53594ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:34:34.32847Z","caller":"traceutil/trace.go:171","msg":"trace[1746030100] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"202.788039ms","start":"2026-02-07T02:34:34.125665Z","end":"2026-02-07T02:34:34.328453Z","steps":["trace[1746030100] 'process raft request' (duration: 86.667039ms)","trace[1746030100] 'compare' (duration: 114.891352ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:34:34.329852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.279492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-cwznr\" ","response":"range_response_count:1 size:13268"} {"level":"info","ts":"2026-02-07T02:34:34.329909Z","caller":"traceutil/trace.go:171","msg":"trace[563062085] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-cwznr; range_end:; response_count:1; response_revision:2084; }","duration":"138.365644ms","start":"2026-02-07T02:34:34.19153Z","end":"2026-02-07T02:34:34.329896Z","steps":["trace[563062085] 'agreement among raft nodes before linearized reading' (duration: 138.19972ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:34:34.48264Z","caller":"traceutil/trace.go:171","msg":"trace[1748156226] linearizableReadLoop","detail":"{readStateIndex:2180; appliedIndex:2178; }","duration":"144.022437ms","start":"2026-02-07T02:34:34.338592Z","end":"2026-02-07T02:34:34.482614Z","steps":["trace[1748156226] 'read index received' (duration: 100.677048ms)","trace[1748156226] 'applied index is now lower than readState.Index' (duration: 43.344549ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:34:34.482905Z","caller":"traceutil/trace.go:171","msg":"trace[1740642289] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"144.790976ms","start":"2026-02-07T02:34:34.338099Z","end":"2026-02-07T02:34:34.48289Z","steps":["trace[1740642289] 'process raft request' (duration: 144.460457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:34:34.483366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.763385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-cwznr\" ","response":"range_response_count:1 size:13268"} {"level":"info","ts":"2026-02-07T02:34:34.483474Z","caller":"traceutil/trace.go:171","msg":"trace[1402629985] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-cwznr; range_end:; response_count:1; response_revision:2086; }","duration":"144.878628ms","start":"2026-02-07T02:34:34.33858Z","end":"2026-02-07T02:34:34.483459Z","steps":["trace[1402629985] 'agreement among raft nodes before linearized reading' (duration: 144.640633ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:34:34.482679Z","caller":"traceutil/trace.go:171","msg":"trace[100015358] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"147.722334ms","start":"2026-02-07T02:34:34.334924Z","end":"2026-02-07T02:34:34.482646Z","steps":["trace[100015358] 'process raft request' (duration: 104.355265ms)","trace[100015358] 'compare' (duration: 43.158334ms)"],"step_count":2}