{"level":"warn","ts":"2026-02-18T02:43:29.657371Z","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-18T02:43:29.657453Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.219:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.219:2380","--initial-cluster=instance=https://199.19.213.219:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.219:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.219: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-18T02:43:29.657561Z","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-18T02:43:29.657577Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.219:2380"]} {"level":"info","ts":"2026-02-18T02:43:29.657594Z","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-18T02:43:29.658238Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.219:2379"]} {"level":"info","ts":"2026-02-18T02:43:29.658342Z","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.19.213.219:2380"],"listen-peer-urls":["https://199.19.213.219:2380"],"advertise-client-urls":["https://199.19.213.219:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.219:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.219: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-18T02:43:29.704159Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"45.506226ms"} {"level":"info","ts":"2026-02-18T02:43:29.752335Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7e142186203fa167","cluster-id":"e7586aea7e662ee6"} {"level":"info","ts":"2026-02-18T02:43:29.752413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T02:43:29.752432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became follower at term 0"} {"level":"info","ts":"2026-02-18T02:43:29.752455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7e142186203fa167 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T02:43:29.752469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became follower at term 1"} {"level":"info","ts":"2026-02-18T02:43:29.7525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 switched to configuration voters=(9084923208263508327)"} {"level":"warn","ts":"2026-02-18T02:43:29.760782Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T02:43:29.765055Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T02:43:29.77049Z","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-18T02:43:29.775791Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7e142186203fa167","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T02:43:29.777504Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7e142186203fa167","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T02:43:29.777881Z","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-18T02:43:29.777975Z","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-18T02:43:29.778087Z","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-18T02:43:29.778387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 switched to configuration voters=(9084923208263508327)"} {"level":"info","ts":"2026-02-18T02:43:29.781598Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e7586aea7e662ee6","local-member-id":"7e142186203fa167","added-peer-id":"7e142186203fa167","added-peer-peer-urls":["https://199.19.213.219:2380"]} {"level":"info","ts":"2026-02-18T02:43:29.791832Z","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-18T02:43:29.792271Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7e142186203fa167","initial-advertise-peer-urls":["https://199.19.213.219:2380"],"listen-peer-urls":["https://199.19.213.219:2380"],"advertise-client-urls":["https://199.19.213.219:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.219:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T02:43:29.792269Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.219:2380"} {"level":"info","ts":"2026-02-18T02:43:29.79241Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.219:2380"} {"level":"info","ts":"2026-02-18T02:43:29.792795Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T02:43:30.153707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T02:43:30.154054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T02:43:30.154139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 received MsgPreVoteResp from 7e142186203fa167 at term 1"} {"level":"info","ts":"2026-02-18T02:43:30.154207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became candidate at term 2"} {"level":"info","ts":"2026-02-18T02:43:30.154271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 received MsgVoteResp from 7e142186203fa167 at term 2"} {"level":"info","ts":"2026-02-18T02:43:30.154348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became leader at term 2"} {"level":"info","ts":"2026-02-18T02:43:30.154439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7e142186203fa167 elected leader 7e142186203fa167 at term 2"} {"level":"info","ts":"2026-02-18T02:43:30.629258Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:43:31.020719Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7e142186203fa167","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.219:2379]}","request-path":"/0/members/7e142186203fa167/attributes","cluster-id":"e7586aea7e662ee6","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T02:43:31.020909Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T02:43:31.022004Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T02:43:31.022352Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T02:43:31.022404Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T02:43:31.025516Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.219:2379"} {"level":"info","ts":"2026-02-18T02:43:31.028245Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T02:43:31.103062Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e7586aea7e662ee6","local-member-id":"7e142186203fa167","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:43:31.103291Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:43:31.103465Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-18T02:43:31.253748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.707705ms","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-18T02:43:31.253807Z","caller":"traceutil/trace.go:171","msg":"trace[1001401446] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"121.796867ms","start":"2026-02-18T02:43:31.131999Z","end":"2026-02-18T02:43:31.253796Z","steps":["trace[1001401446] 'count revisions from in-memory index tree' (duration: 121.672814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:31.254091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.159345ms","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-18T02:43:31.25413Z","caller":"traceutil/trace.go:171","msg":"trace[532860529] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"122.215316ms","start":"2026-02-18T02:43:31.131907Z","end":"2026-02-18T02:43:31.254122Z","steps":["trace[532860529] 'range keys from in-memory index tree' (duration: 121.997951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:31.25431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.709252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T02:43:31.254332Z","caller":"traceutil/trace.go:171","msg":"trace[132599442] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"108.734493ms","start":"2026-02-18T02:43:31.145592Z","end":"2026-02-18T02:43:31.254326Z","steps":["trace[132599442] 'count revisions from in-memory index tree' (duration: 108.660901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:31.254487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.06801ms","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-18T02:43:31.254508Z","caller":"traceutil/trace.go:171","msg":"trace[31618192] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"109.123912ms","start":"2026-02-18T02:43:31.14538Z","end":"2026-02-18T02:43:31.254504Z","steps":["trace[31618192] 'range keys from in-memory index tree' (duration: 108.818255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:32.93558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.31573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-18T02:43:32.941649Z","caller":"traceutil/trace.go:171","msg":"trace[1464749650] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"191.805173ms","start":"2026-02-18T02:43:32.749826Z","end":"2026-02-18T02:43:32.941631Z","steps":["trace[1464749650] 'process raft request' (duration: 74.9696ms)","trace[1464749650] 'compare' (duration: 110.204998ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:43:32.941974Z","caller":"traceutil/trace.go:171","msg":"trace[624532204] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"192.073108ms","start":"2026-02-18T02:43:32.749895Z","end":"2026-02-18T02:43:32.941968Z","steps":["trace[624532204] 'process raft request' (duration: 185.770832ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:32.942106Z","caller":"traceutil/trace.go:171","msg":"trace[1533048774] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"192.071888ms","start":"2026-02-18T02:43:32.750029Z","end":"2026-02-18T02:43:32.942101Z","steps":["trace[1533048774] 'process raft request' (duration: 185.68583ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:32.942324Z","caller":"traceutil/trace.go:171","msg":"trace[132510440] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"176.539867ms","start":"2026-02-18T02:43:32.765779Z","end":"2026-02-18T02:43:32.942319Z","steps":["trace[132510440] 'process raft request' (duration: 176.510357ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:32.942418Z","caller":"traceutil/trace.go:171","msg":"trace[2146389899] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"192.338975ms","start":"2026-02-18T02:43:32.750074Z","end":"2026-02-18T02:43:32.942413Z","steps":["trace[2146389899] 'process raft request' (duration: 185.66767ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:32.942467Z","caller":"traceutil/trace.go:171","msg":"trace[1207983860] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"189.040677ms","start":"2026-02-18T02:43:32.753422Z","end":"2026-02-18T02:43:32.942463Z","steps":["trace[1207983860] 'process raft request' (duration: 188.815162ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:32.942554Z","caller":"traceutil/trace.go:171","msg":"trace[370167646] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"189.086609ms","start":"2026-02-18T02:43:32.753463Z","end":"2026-02-18T02:43:32.942549Z","steps":["trace[370167646] 'process raft request' (duration: 188.791202ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:32.942607Z","caller":"traceutil/trace.go:171","msg":"trace[839392463] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:9; }","duration":"180.330875ms","start":"2026-02-18T02:43:32.76227Z","end":"2026-02-18T02:43:32.942601Z","steps":["trace[839392463] 'read index received' (duration: 62.539811ms)","trace[839392463] 'applied index is now lower than readState.Index' (duration: 117.790324ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:43:32.942643Z","caller":"traceutil/trace.go:171","msg":"trace[1403400027] transaction","detail":"{read_only:false; number_of_response:0; response_revision:12; }","duration":"176.958417ms","start":"2026-02-18T02:43:32.765682Z","end":"2026-02-18T02:43:32.94264Z","steps":["trace[1403400027] 'process raft request' (duration: 176.596348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:32.942887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.945679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T02:43:32.942905Z","caller":"traceutil/trace.go:171","msg":"trace[1000008445] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:13; }","duration":"183.981021ms","start":"2026-02-18T02:43:32.758917Z","end":"2026-02-18T02:43:32.942898Z","steps":["trace[1000008445] 'agreement among raft nodes before linearized reading' (duration: 183.911289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:32.942989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.595738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-18T02:43:32.943004Z","caller":"traceutil/trace.go:171","msg":"trace[1780486948] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:13; }","duration":"157.641229ms","start":"2026-02-18T02:43:32.785357Z","end":"2026-02-18T02:43:32.942999Z","steps":["trace[1780486948] 'agreement among raft nodes before linearized reading' (duration: 157.615888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:32.943175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.939294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-18T02:43:32.943187Z","caller":"traceutil/trace.go:171","msg":"trace[572378439] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:13; }","duration":"147.973915ms","start":"2026-02-18T02:43:32.79521Z","end":"2026-02-18T02:43:32.943184Z","steps":["trace[572378439] 'agreement among raft nodes before linearized reading' (duration: 147.950865ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:33.140519Z","caller":"traceutil/trace.go:171","msg":"trace[110050032] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"175.925064ms","start":"2026-02-18T02:43:32.96456Z","end":"2026-02-18T02:43:33.140485Z","steps":["trace[110050032] 'process raft request' (duration: 141.999627ms)","trace[110050032] 'compare' (duration: 33.294772ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:43:33.142499Z","caller":"traceutil/trace.go:171","msg":"trace[1685197743] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"177.851478ms","start":"2026-02-18T02:43:32.964635Z","end":"2026-02-18T02:43:33.142486Z","steps":["trace[1685197743] 'process raft request' (duration: 175.783881ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:33.142905Z","caller":"traceutil/trace.go:171","msg":"trace[1944354240] linearizableReadLoop","detail":"{readStateIndex:25; appliedIndex:21; }","duration":"131.691126ms","start":"2026-02-18T02:43:33.0112Z","end":"2026-02-18T02:43:33.142891Z","steps":["trace[1944354240] 'read index received' (duration: 95.387763ms)","trace[1944354240] 'applied index is now lower than readState.Index' (duration: 36.302233ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:43:33.142984Z","caller":"traceutil/trace.go:171","msg":"trace[1308375669] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"177.702435ms","start":"2026-02-18T02:43:32.965274Z","end":"2026-02-18T02:43:33.142976Z","steps":["trace[1308375669] 'process raft request' (duration: 175.709159ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:33.143223Z","caller":"traceutil/trace.go:171","msg":"trace[1211895798] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"177.815718ms","start":"2026-02-18T02:43:32.965398Z","end":"2026-02-18T02:43:33.143214Z","steps":["trace[1211895798] 'process raft request' (duration: 177.366038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:33.143462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.235428ms","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-18T02:43:33.143511Z","caller":"traceutil/trace.go:171","msg":"trace[1636631311] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:21; }","duration":"178.311289ms","start":"2026-02-18T02:43:32.965187Z","end":"2026-02-18T02:43:33.143498Z","steps":["trace[1636631311] 'agreement among raft nodes before linearized reading' (duration: 178.168936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:33.143691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.519595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T02:43:33.143728Z","caller":"traceutil/trace.go:171","msg":"trace[475076197] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:21; }","duration":"178.627086ms","start":"2026-02-18T02:43:32.965091Z","end":"2026-02-18T02:43:33.143718Z","steps":["trace[475076197] 'agreement among raft nodes before linearized reading' (duration: 178.575765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:33.143939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.31099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T02:43:33.144099Z","caller":"traceutil/trace.go:171","msg":"trace[1874688208] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:21; }","duration":"188.498245ms","start":"2026-02-18T02:43:32.955573Z","end":"2026-02-18T02:43:33.144072Z","steps":["trace[1874688208] 'agreement among raft nodes before linearized reading' (duration: 188.24938ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:33.217185Z","caller":"traceutil/trace.go:171","msg":"trace[1639498936] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"200.934735ms","start":"2026-02-18T02:43:33.016221Z","end":"2026-02-18T02:43:33.217156Z","steps":["trace[1639498936] 'process raft request' (duration: 200.330741ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:33.218407Z","caller":"traceutil/trace.go:171","msg":"trace[1250809202] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"199.826858ms","start":"2026-02-18T02:43:33.018567Z","end":"2026-02-18T02:43:33.218394Z","steps":["trace[1250809202] 'process raft request' (duration: 198.16327ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:33.219133Z","caller":"traceutil/trace.go:171","msg":"trace[1882674413] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"198.956648ms","start":"2026-02-18T02:43:33.020164Z","end":"2026-02-18T02:43:33.21912Z","steps":["trace[1882674413] 'process raft request' (duration: 198.171799ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:33.22046Z","caller":"traceutil/trace.go:171","msg":"trace[693366078] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"199.225185ms","start":"2026-02-18T02:43:33.021221Z","end":"2026-02-18T02:43:33.220446Z","steps":["trace[693366078] 'process raft request' (duration: 198.982369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:33.221406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.504517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T02:43:33.221559Z","caller":"traceutil/trace.go:171","msg":"trace[1230180573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:25; }","duration":"179.686642ms","start":"2026-02-18T02:43:33.041864Z","end":"2026-02-18T02:43:33.22155Z","steps":["trace[1230180573] 'agreement among raft nodes before linearized reading' (duration: 179.156259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:33.221521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.794117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T02:43:33.221835Z","caller":"traceutil/trace.go:171","msg":"trace[1487803431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:25; }","duration":"181.276538ms","start":"2026-02-18T02:43:33.040526Z","end":"2026-02-18T02:43:33.221803Z","steps":["trace[1487803431] 'agreement among raft nodes before linearized reading' (duration: 179.879455ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:33.254356Z","caller":"traceutil/trace.go:171","msg":"trace[825165391] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"101.207209ms","start":"2026-02-18T02:43:33.153114Z","end":"2026-02-18T02:43:33.254321Z","steps":["trace[825165391] 'process raft request' (duration: 94.947524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:37.68049Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630436561678981754,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:43:38.181459Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630436561678981754,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:43:38.682524Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630436561678981754,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:43:39.182953Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630436561678981754,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:43:39.643482Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.77534566s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T02:43:39.643829Z","caller":"traceutil/trace.go:171","msg":"trace[1091089926] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"2.77577205s","start":"2026-02-18T02:43:36.86804Z","end":"2026-02-18T02:43:39.643812Z","steps":["trace[1091089926] 'process raft request' (duration: 2.775682837s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:39.643902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:36.868021Z","time spent":"2.775835692s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T02:43:39.647012Z","caller":"traceutil/trace.go:171","msg":"trace[1364243294] linearizableReadLoop","detail":"{readStateIndex:279; appliedIndex:278; }","duration":"2.472979961s","start":"2026-02-18T02:43:37.174022Z","end":"2026-02-18T02:43:39.647002Z","steps":["trace[1364243294] 'read index received' (duration: 2.470146765s)","trace[1364243294] 'applied index is now lower than readState.Index' (duration: 2.832776ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:43:39.647123Z","caller":"traceutil/trace.go:171","msg":"trace[1720861570] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"2.735448353s","start":"2026-02-18T02:43:36.911667Z","end":"2026-02-18T02:43:39.647115Z","steps":["trace[1720861570] 'process raft request' (duration: 2.735276239s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:39.647166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:36.911644Z","time spent":"2.735499755s","remote":"127.0.0.1:34542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":176,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T02:43:39.647308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.473281107s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-18T02:43:39.647335Z","caller":"traceutil/trace.go:171","msg":"trace[59248907] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:269; }","duration":"2.473330319s","start":"2026-02-18T02:43:37.173998Z","end":"2026-02-18T02:43:39.647328Z","steps":["trace[59248907] 'agreement among raft nodes before linearized reading' (duration: 2.473245837s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:39.647357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:37.173985Z","time spent":"2.47336807s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T02:43:39.647442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.410058271s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3444"} {"level":"info","ts":"2026-02-18T02:43:39.647464Z","caller":"traceutil/trace.go:171","msg":"trace[1352937990] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:269; }","duration":"2.410114822s","start":"2026-02-18T02:43:37.237343Z","end":"2026-02-18T02:43:39.647458Z","steps":["trace[1352937990] 'agreement among raft nodes before linearized reading' (duration: 2.410079621s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:39.647479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:37.237323Z","time spent":"2.410152753s","remote":"127.0.0.1:34530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3467,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-02-18T02:43:39.647653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.3135135s","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-18T02:43:39.647677Z","caller":"traceutil/trace.go:171","msg":"trace[1590676184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:269; }","duration":"2.313546751s","start":"2026-02-18T02:43:37.334125Z","end":"2026-02-18T02:43:39.647671Z","steps":["trace[1590676184] 'agreement among raft nodes before linearized reading' (duration: 2.31350752s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:39.647693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:37.334121Z","time spent":"2.313569041s","remote":"127.0.0.1:34612","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-18T02:43:39.647768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.313798017s","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-18T02:43:39.64779Z","caller":"traceutil/trace.go:171","msg":"trace[1989012327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:269; }","duration":"2.313847547s","start":"2026-02-18T02:43:37.333936Z","end":"2026-02-18T02:43:39.647784Z","steps":["trace[1989012327] 'agreement among raft nodes before linearized reading' (duration: 2.313817627s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:39.647807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:37.333923Z","time spent":"2.313878939s","remote":"127.0.0.1:34612","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":"info","ts":"2026-02-18T02:44:04.000773Z","caller":"traceutil/trace.go:171","msg":"trace[1840152737] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"192.181721ms","start":"2026-02-18T02:44:03.808578Z","end":"2026-02-18T02:44:04.00076Z","steps":["trace[1840152737] 'process raft request' (duration: 192.075298ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:44:04.020977Z","caller":"traceutil/trace.go:171","msg":"trace[769961733] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:484; }","duration":"161.597272ms","start":"2026-02-18T02:44:03.859369Z","end":"2026-02-18T02:44:04.020966Z","steps":["trace[769961733] 'read index received' (duration: 141.338532ms)","trace[769961733] 'applied index is now lower than readState.Index' (duration: 20.25824ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:44:04.021159Z","caller":"traceutil/trace.go:171","msg":"trace[390985291] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"211.218002ms","start":"2026-02-18T02:44:03.809935Z","end":"2026-02-18T02:44:04.021153Z","steps":["trace[390985291] 'process raft request' (duration: 210.919325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:04.021231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.985012ms","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-18T02:44:04.021251Z","caller":"traceutil/trace.go:171","msg":"trace[668175438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:472; }","duration":"113.034823ms","start":"2026-02-18T02:44:03.908211Z","end":"2026-02-18T02:44:04.021246Z","steps":["trace[668175438] 'agreement among raft nodes before linearized reading' (duration: 112.987152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:04.021498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.038752ms","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-18T02:44:04.021514Z","caller":"traceutil/trace.go:171","msg":"trace[934869321] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:472; }","duration":"162.162814ms","start":"2026-02-18T02:44:03.859346Z","end":"2026-02-18T02:44:04.021508Z","steps":["trace[934869321] 'agreement among raft nodes before linearized reading' (duration: 162.049222ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:44:04.205446Z","caller":"traceutil/trace.go:171","msg":"trace[1987276357] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"180.026808ms","start":"2026-02-18T02:44:04.025394Z","end":"2026-02-18T02:44:04.205421Z","steps":["trace[1987276357] 'process raft request' (duration: 179.980957ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:44:04.205648Z","caller":"traceutil/trace.go:171","msg":"trace[1251678298] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"180.412937ms","start":"2026-02-18T02:44:04.025214Z","end":"2026-02-18T02:44:04.205627Z","steps":["trace[1251678298] 'process raft request' (duration: 83.834035ms)","trace[1251678298] 'compare' (duration: 96.191083ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:44:07.074618Z","caller":"traceutil/trace.go:171","msg":"trace[991062443] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"280.840308ms","start":"2026-02-18T02:44:06.793761Z","end":"2026-02-18T02:44:07.074601Z","steps":["trace[991062443] 'process raft request' (duration: 280.693435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:07.744338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.173304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:44:07.744415Z","caller":"traceutil/trace.go:171","msg":"trace[657169453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:480; }","duration":"305.291696ms","start":"2026-02-18T02:44:07.439104Z","end":"2026-02-18T02:44:07.744395Z","steps":["trace[657169453] 'range keys from in-memory index tree' (duration: 305.103362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:07.744454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:07.439086Z","time spent":"305.360008ms","remote":"127.0.0.1:34332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T02:44:08.640796Z","caller":"traceutil/trace.go:171","msg":"trace[1492115683] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"539.464849ms","start":"2026-02-18T02:44:08.101313Z","end":"2026-02-18T02:44:08.640778Z","steps":["trace[1492115683] 'process raft request' (duration: 539.360746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:08.641056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:08.101301Z","time spent":"539.55016ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:44:08.989195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.000085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:44:08.989273Z","caller":"traceutil/trace.go:171","msg":"trace[875482881] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:497; }","duration":"771.961835ms","start":"2026-02-18T02:44:08.217298Z","end":"2026-02-18T02:44:08.98926Z","steps":["trace[875482881] 'read index received' (duration: 424.085721ms)","trace[875482881] 'applied index is now lower than readState.Index' (duration: 347.875524ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:44:08.989408Z","caller":"traceutil/trace.go:171","msg":"trace[1810386174] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"887.580137ms","start":"2026-02-18T02:44:08.101821Z","end":"2026-02-18T02:44:08.989401Z","steps":["trace[1810386174] 'process raft request' (duration: 776.323405ms)","trace[1810386174] 'compare' (duration: 110.930664ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:44:08.989447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:08.101811Z","time spent":"887.619598ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:44:08.989565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.267012ms","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-18T02:44:08.989591Z","caller":"traceutil/trace.go:171","msg":"trace[948444775] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:482; }","duration":"772.330564ms","start":"2026-02-18T02:44:08.217254Z","end":"2026-02-18T02:44:08.989585Z","steps":["trace[948444775] 'agreement among raft nodes before linearized reading' (duration: 772.284823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:08.989608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:08.217235Z","time spent":"772.367534ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T02:44:08.989693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.595643ms","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-18T02:44:08.989715Z","caller":"traceutil/trace.go:171","msg":"trace[1551859570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:482; }","duration":"770.638833ms","start":"2026-02-18T02:44:08.219072Z","end":"2026-02-18T02:44:08.989711Z","steps":["trace[1551859570] 'agreement among raft nodes before linearized reading' (duration: 770.604943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:08.989728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:08.219061Z","time spent":"770.664745ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T02:44:08.989826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.131286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:44:08.989848Z","caller":"traceutil/trace.go:171","msg":"trace[1368728500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:482; }","duration":"550.180537ms","start":"2026-02-18T02:44:08.439663Z","end":"2026-02-18T02:44:08.989844Z","steps":["trace[1368728500] 'agreement among raft nodes before linearized reading' (duration: 550.148156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:08.989862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:08.439648Z","time spent":"550.211418ms","remote":"127.0.0.1:34332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T02:44:09.26324Z","caller":"traceutil/trace.go:171","msg":"trace[323122414] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"267.297133ms","start":"2026-02-18T02:44:08.995914Z","end":"2026-02-18T02:44:09.263211Z","steps":["trace[323122414] 'process raft request' (duration: 267.238611ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:44:09.264014Z","caller":"traceutil/trace.go:171","msg":"trace[1511470445] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"268.564003ms","start":"2026-02-18T02:44:08.99543Z","end":"2026-02-18T02:44:09.263994Z","steps":["trace[1511470445] 'process raft request' (duration: 172.458112ms)","trace[1511470445] 'compare' (duration: 94.856651ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:44:09.430545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.361995ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T02:44:11.513113Z","caller":"traceutil/trace.go:171","msg":"trace[354152161] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"240.636413ms","start":"2026-02-18T02:44:11.272452Z","end":"2026-02-18T02:44:11.513089Z","steps":["trace[354152161] 'process raft request' (duration: 240.412438ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:44:11.524567Z","caller":"traceutil/trace.go:171","msg":"trace[947952651] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"247.242198ms","start":"2026-02-18T02:44:11.27731Z","end":"2026-02-18T02:44:11.524552Z","steps":["trace[947952651] 'process raft request' (duration: 246.909809ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:44:15.507098Z","caller":"traceutil/trace.go:171","msg":"trace[721820480] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:515; }","duration":"487.659712ms","start":"2026-02-18T02:44:15.019408Z","end":"2026-02-18T02:44:15.507068Z","steps":["trace[721820480] 'read index received' (duration: 487.358335ms)","trace[721820480] 'applied index is now lower than readState.Index' (duration: 300.646µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:44:15.507238Z","caller":"traceutil/trace.go:171","msg":"trace[787176183] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"723.72118ms","start":"2026-02-18T02:44:14.783506Z","end":"2026-02-18T02:44:15.507228Z","steps":["trace[787176183] 'process raft request' (duration: 723.363202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:15.507369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:14.783494Z","time spent":"723.771572ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:44:15.507824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.410888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-18T02:44:15.507863Z","caller":"traceutil/trace.go:171","msg":"trace[462726483] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:498; }","duration":"488.447559ms","start":"2026-02-18T02:44:15.019403Z","end":"2026-02-18T02:44:15.50785Z","steps":["trace[462726483] 'agreement among raft nodes before linearized reading' (duration: 488.374367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:15.507892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:15.01935Z","time spent":"488.535041ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T02:44:15.510706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.998325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wz2c6\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-18T02:44:15.510827Z","caller":"traceutil/trace.go:171","msg":"trace[1068707673] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wz2c6; range_end:; response_count:1; response_revision:498; }","duration":"178.125329ms","start":"2026-02-18T02:44:15.332649Z","end":"2026-02-18T02:44:15.510774Z","steps":["trace[1068707673] 'agreement among raft nodes before linearized reading' (duration: 174.753922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:16.952268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.195416796s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:44:16.952352Z","caller":"traceutil/trace.go:171","msg":"trace[1165214649] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:516; }","duration":"1.42650204s","start":"2026-02-18T02:44:15.525835Z","end":"2026-02-18T02:44:16.952337Z","steps":["trace[1165214649] 'read index received' (duration: 230.886069ms)","trace[1165214649] 'applied index is now lower than readState.Index' (duration: 1.195614941s)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:44:16.952505Z","caller":"traceutil/trace.go:171","msg":"trace[945235300] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"1.434554841s","start":"2026-02-18T02:44:15.517937Z","end":"2026-02-18T02:44:16.952492Z","steps":["trace[945235300] 'process raft request' (duration: 238.844228ms)","trace[945235300] 'compare' (duration: 1.19513041s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:44:16.95256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:15.517919Z","time spent":"1.434612002s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:44:16.952758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.426923169s","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-18T02:44:16.952787Z","caller":"traceutil/trace.go:171","msg":"trace[606147019] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:499; }","duration":"1.42701145s","start":"2026-02-18T02:44:15.525765Z","end":"2026-02-18T02:44:16.952777Z","steps":["trace[606147019] 'agreement among raft nodes before linearized reading' (duration: 1.426955289s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:16.952814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:15.525736Z","time spent":"1.427071882s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T02:44:17.06256Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.305870866s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T02:44:17.062948Z","caller":"traceutil/trace.go:171","msg":"trace[104974007] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"1.536089121s","start":"2026-02-18T02:44:15.526839Z","end":"2026-02-18T02:44:17.062928Z","steps":["trace[104974007] 'process raft request' (duration: 1.535909737s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:17.063073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:15.526825Z","time spent":"1.536182843s","remote":"127.0.0.1:34530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:44:17.063221Z","caller":"traceutil/trace.go:171","msg":"trace[1153285979] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:518; }","duration":"110.817649ms","start":"2026-02-18T02:44:16.952375Z","end":"2026-02-18T02:44:17.063193Z","steps":["trace[1153285979] 'read index received' (duration: 110.807019ms)","trace[1153285979] 'applied index is now lower than readState.Index' (duration: 8.84µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:44:17.063414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.529520872s","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-18T02:44:17.063439Z","caller":"traceutil/trace.go:171","msg":"trace[209096877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:500; }","duration":"1.529602454s","start":"2026-02-18T02:44:15.533829Z","end":"2026-02-18T02:44:17.063431Z","steps":["trace[209096877] 'agreement among raft nodes before linearized reading' (duration: 1.52943021s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:17.063472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:15.533822Z","time spent":"1.529642894s","remote":"127.0.0.1:34612","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-18T02:44:17.063771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.323554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:44:17.063797Z","caller":"traceutil/trace.go:171","msg":"trace[1011747765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"626.378696ms","start":"2026-02-18T02:44:16.437412Z","end":"2026-02-18T02:44:17.063791Z","steps":["trace[1011747765] 'agreement among raft nodes before linearized reading' (duration: 626.339525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:17.063813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:16.437396Z","time spent":"626.413556ms","remote":"127.0.0.1:34332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T02:44:18.618137Z","caller":"traceutil/trace.go:171","msg":"trace[983559755] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"102.944602ms","start":"2026-02-18T02:44:18.515123Z","end":"2026-02-18T02:44:18.618067Z","steps":["trace[983559755] 'process raft request' (duration: 102.627925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:19.261742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.503652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-18T02:44:19.26178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.415207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-02-18T02:44:19.261874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.057943ms","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-18T02:44:19.261905Z","caller":"traceutil/trace.go:171","msg":"trace[215201973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:512; }","duration":"278.561482ms","start":"2026-02-18T02:44:18.983281Z","end":"2026-02-18T02:44:19.261843Z","steps":["trace[215201973] 'range keys from in-memory index tree' (duration: 278.303685ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:44:19.261916Z","caller":"traceutil/trace.go:171","msg":"trace[463463771] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:512; }","duration":"181.160375ms","start":"2026-02-18T02:44:19.08075Z","end":"2026-02-18T02:44:19.261911Z","steps":["trace[463463771] 'range keys from in-memory index tree' (duration: 180.908129ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:44:19.261806Z","caller":"traceutil/trace.go:171","msg":"trace[1043739055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:512; }","duration":"177.591575ms","start":"2026-02-18T02:44:19.084199Z","end":"2026-02-18T02:44:19.26179Z","steps":["trace[1043739055] 'range keys from in-memory index tree' (duration: 177.39722ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:44:22.621919Z","caller":"traceutil/trace.go:171","msg":"trace[1846520480] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"128.909917ms","start":"2026-02-18T02:44:22.492999Z","end":"2026-02-18T02:44:22.621909Z","steps":["trace[1846520480] 'process raft request' (duration: 73.009706ms)","trace[1846520480] 'compare' (duration: 55.107793ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:44:22.621617Z","caller":"traceutil/trace.go:171","msg":"trace[1897259460] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"125.294826ms","start":"2026-02-18T02:44:22.496306Z","end":"2026-02-18T02:44:22.621601Z","steps":["trace[1897259460] 'read index received' (duration: 69.656711ms)","trace[1897259460] 'applied index is now lower than readState.Index' (duration: 55.637525ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:44:22.622347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.026282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:44:22.6227Z","caller":"traceutil/trace.go:171","msg":"trace[1087493303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"126.38598ms","start":"2026-02-18T02:44:22.49629Z","end":"2026-02-18T02:44:22.622676Z","steps":["trace[1087493303] 'agreement among raft nodes before linearized reading' (duration: 126.003792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:22.934864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.177861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-18T02:44:22.934925Z","caller":"traceutil/trace.go:171","msg":"trace[1670097941] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:574; }","duration":"175.273653ms","start":"2026-02-18T02:44:22.759635Z","end":"2026-02-18T02:44:22.934909Z","steps":["trace[1670097941] 'range keys from in-memory index tree' (duration: 174.965176ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:44:22.940291Z","caller":"traceutil/trace.go:171","msg":"trace[1760153009] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"134.168116ms","start":"2026-02-18T02:44:22.806105Z","end":"2026-02-18T02:44:22.940273Z","steps":["trace[1760153009] 'process raft request' (duration: 133.90499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:23.426391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.1681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:44:23.426464Z","caller":"traceutil/trace.go:171","msg":"trace[1251688091] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"119.753911ms","start":"2026-02-18T02:44:23.306697Z","end":"2026-02-18T02:44:23.426451Z","steps":["trace[1251688091] 'read index received' (duration: 19.430298ms)","trace[1251688091] 'applied index is now lower than readState.Index' (duration: 100.322623ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:44:23.426644Z","caller":"traceutil/trace.go:171","msg":"trace[1871731218] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"124.054847ms","start":"2026-02-18T02:44:23.30254Z","end":"2026-02-18T02:44:23.426594Z","steps":["trace[1871731218] 'process raft request' (duration: 23.609252ms)","trace[1871731218] 'compare' (duration: 100.072537ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:44:23.426714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.013977ms","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-18T02:44:23.426737Z","caller":"traceutil/trace.go:171","msg":"trace[196529849] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"120.054177ms","start":"2026-02-18T02:44:23.306675Z","end":"2026-02-18T02:44:23.426729Z","steps":["trace[196529849] 'agreement among raft nodes before linearized reading' (duration: 119.966195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:23.463964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.211416ms","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-18T02:44:23.464012Z","caller":"traceutil/trace.go:171","msg":"trace[245378556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:579; }","duration":"157.276677ms","start":"2026-02-18T02:44:23.306724Z","end":"2026-02-18T02:44:23.464Z","steps":["trace[245378556] 'agreement among raft nodes before linearized reading' (duration: 157.149015ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:44:23.928171Z","caller":"traceutil/trace.go:171","msg":"trace[1550977066] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"319.878753ms","start":"2026-02-18T02:44:23.608276Z","end":"2026-02-18T02:44:23.928155Z","steps":["trace[1550977066] 'process raft request' (duration: 319.795691ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:23.928261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:23.608267Z","time spent":"319.952925ms","remote":"127.0.0.1:34420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T02:44:24.011109Z","caller":"traceutil/trace.go:171","msg":"trace[1585670539] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"402.504667ms","start":"2026-02-18T02:44:23.608588Z","end":"2026-02-18T02:44:24.011093Z","steps":["trace[1585670539] 'process raft request' (duration: 402.153639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:24.011206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:23.608583Z","time spent":"402.572398ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:44:24.011496Z","caller":"traceutil/trace.go:171","msg":"trace[1780842942] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"391.651502ms","start":"2026-02-18T02:44:23.619837Z","end":"2026-02-18T02:44:24.011488Z","steps":["trace[1780842942] 'process raft request' (duration: 391.12753ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:44:24.011502Z","caller":"traceutil/trace.go:171","msg":"trace[381105160] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"402.19473ms","start":"2026-02-18T02:44:23.609292Z","end":"2026-02-18T02:44:24.011486Z","steps":["trace[381105160] 'read index received' (duration: 319.184377ms)","trace[381105160] 'applied index is now lower than readState.Index' (duration: 83.009543ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:44:24.011542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:23.61983Z","time spent":"391.682382ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:44:24.011617Z","caller":"traceutil/trace.go:171","msg":"trace[1105243287] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"398.972497ms","start":"2026-02-18T02:44:23.612638Z","end":"2026-02-18T02:44:24.01161Z","steps":["trace[1105243287] 'process raft request' (duration: 398.271661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:24.011647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:23.612631Z","time spent":"398.998967ms","remote":"127.0.0.1:34530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18235,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:44:24.011902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.604579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-18T02:44:24.011924Z","caller":"traceutil/trace.go:171","msg":"trace[1513478617] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:585; }","duration":"402.627409ms","start":"2026-02-18T02:44:23.60929Z","end":"2026-02-18T02:44:24.011917Z","steps":["trace[1513478617] 'agreement among raft nodes before linearized reading' (duration: 402.547438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:24.011945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:23.609286Z","time spent":"402.65541ms","remote":"127.0.0.1:34370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"info","ts":"2026-02-18T02:44:24.011993Z","caller":"traceutil/trace.go:171","msg":"trace[2052293324] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"289.949537ms","start":"2026-02-18T02:44:23.722038Z","end":"2026-02-18T02:44:24.011988Z","steps":["trace[2052293324] 'process raft request' (duration: 289.129659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:24.012071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.333404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-18T02:44:24.012086Z","caller":"traceutil/trace.go:171","msg":"trace[68085576] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:585; }","duration":"265.368264ms","start":"2026-02-18T02:44:23.746713Z","end":"2026-02-18T02:44:24.012081Z","steps":["trace[68085576] 'agreement among raft nodes before linearized reading' (duration: 265.322893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:24.012206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.407577ms","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-18T02:44:24.012223Z","caller":"traceutil/trace.go:171","msg":"trace[1841736326] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:585; }","duration":"152.453168ms","start":"2026-02-18T02:44:23.859765Z","end":"2026-02-18T02:44:24.012218Z","steps":["trace[1841736326] 'agreement among raft nodes before linearized reading' (duration: 152.423767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:29.971026Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630436561678982496,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:44:31.270103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229228042s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T02:44:31.270249Z","caller":"traceutil/trace.go:171","msg":"trace[1836305481] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"1.799778249s","start":"2026-02-18T02:44:29.470452Z","end":"2026-02-18T02:44:31.27023Z","steps":["trace[1836305481] 'read index received' (duration: 570.348961ms)","trace[1836305481] 'applied index is now lower than readState.Index' (duration: 1.229427638s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:44:31.271058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.800594528s","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-18T02:44:31.271082Z","caller":"traceutil/trace.go:171","msg":"trace[964456016] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:627; }","duration":"1.800650498s","start":"2026-02-18T02:44:29.470423Z","end":"2026-02-18T02:44:31.271073Z","steps":["trace[964456016] 'agreement among raft nodes before linearized reading' (duration: 1.800528526s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:31.271105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:29.470409Z","time spent":"1.800690859s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T02:44:31.271261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.732521012s","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-18T02:44:31.27128Z","caller":"traceutil/trace.go:171","msg":"trace[1856588908] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"1.732570785s","start":"2026-02-18T02:44:29.538702Z","end":"2026-02-18T02:44:31.271273Z","steps":["trace[1856588908] 'agreement among raft nodes before linearized reading' (duration: 1.732517043s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:31.271297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:29.53869Z","time spent":"1.732602035s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T02:44:31.271911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.939593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:44:31.271959Z","caller":"traceutil/trace.go:171","msg":"trace[1826110330] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"832.036195ms","start":"2026-02-18T02:44:30.439914Z","end":"2026-02-18T02:44:31.27195Z","steps":["trace[1826110330] 'agreement among raft nodes before linearized reading' (duration: 831.972293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:31.272036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:30.439895Z","time spent":"832.136227ms","remote":"127.0.0.1:34332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T02:44:31.272346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.194674024s","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-18T02:44:31.272368Z","caller":"traceutil/trace.go:171","msg":"trace[932633424] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:627; }","duration":"1.194721325s","start":"2026-02-18T02:44:30.077641Z","end":"2026-02-18T02:44:31.272362Z","steps":["trace[932633424] 'agreement among raft nodes before linearized reading' (duration: 1.194612983s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:31.272403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:30.077597Z","time spent":"1.194801217s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T02:44:31.272693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.228291101s","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-18T02:44:31.272716Z","caller":"traceutil/trace.go:171","msg":"trace[759727083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"1.228358103s","start":"2026-02-18T02:44:30.044351Z","end":"2026-02-18T02:44:31.272709Z","steps":["trace[759727083] 'agreement among raft nodes before linearized reading' (duration: 1.228274511s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:31.272734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:30.044336Z","time spent":"1.228392783s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T02:44:31.27296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.230181914s","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-18T02:44:31.273027Z","caller":"traceutil/trace.go:171","msg":"trace[2129435337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:627; }","duration":"1.230260556s","start":"2026-02-18T02:44:30.042759Z","end":"2026-02-18T02:44:31.273019Z","steps":["trace[2129435337] 'agreement among raft nodes before linearized reading' (duration: 1.230065581s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:31.273048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:30.042723Z","time spent":"1.230319447s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T02:44:32.575008Z","caller":"traceutil/trace.go:171","msg":"trace[994130336] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"107.908454ms","start":"2026-02-18T02:44:32.466726Z","end":"2026-02-18T02:44:32.574635Z","steps":["trace[994130336] 'process raft request' (duration: 107.72393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:39.942851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.677729ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-18T02:44:41.855124Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630436561678982630,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T02:44:42.142076Z","caller":"traceutil/trace.go:171","msg":"trace[24951149] linearizableReadLoop","detail":"{readStateIndex:720; appliedIndex:719; }","duration":"787.11871ms","start":"2026-02-18T02:44:41.354939Z","end":"2026-02-18T02:44:42.142057Z","steps":["trace[24951149] 'read index received' (duration: 786.994777ms)","trace[24951149] 'applied index is now lower than readState.Index' (duration: 123.323µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:44:42.142217Z","caller":"traceutil/trace.go:171","msg":"trace[587241017] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"794.24968ms","start":"2026-02-18T02:44:41.347957Z","end":"2026-02-18T02:44:42.142207Z","steps":["trace[587241017] 'process raft request' (duration: 793.982704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:42.142325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:41.347945Z","time spent":"794.318821ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:44:42.142446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.455587ms","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-18T02:44:42.143329Z","caller":"traceutil/trace.go:171","msg":"trace[574981213] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:695; }","duration":"788.348178ms","start":"2026-02-18T02:44:41.354972Z","end":"2026-02-18T02:44:42.14332Z","steps":["trace[574981213] 'agreement among raft nodes before linearized reading' (duration: 787.372316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:42.143373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:41.354969Z","time spent":"788.396169ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T02:44:42.142519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.5775ms","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-18T02:44:42.143501Z","caller":"traceutil/trace.go:171","msg":"trace[79745070] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:695; }","duration":"788.562993ms","start":"2026-02-18T02:44:41.354911Z","end":"2026-02-18T02:44:42.143474Z","steps":["trace[79745070] 'agreement among raft nodes before linearized reading' (duration: 787.56235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:42.143539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:41.3549Z","time spent":"788.631544ms","remote":"127.0.0.1:34612","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-18T02:44:42.142752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.273917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:44:42.143697Z","caller":"traceutil/trace.go:171","msg":"trace[1281315889] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:695; }","duration":"707.219769ms","start":"2026-02-18T02:44:41.43647Z","end":"2026-02-18T02:44:42.14369Z","steps":["trace[1281315889] 'agreement among raft nodes before linearized reading' (duration: 706.261857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:42.14373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:41.43646Z","time spent":"707.26389ms","remote":"127.0.0.1:34332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T02:44:42.142878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.691376ms","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-18T02:44:42.143889Z","caller":"traceutil/trace.go:171","msg":"trace[744583178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:695; }","duration":"754.713409ms","start":"2026-02-18T02:44:41.389168Z","end":"2026-02-18T02:44:42.143882Z","steps":["trace[744583178] 'agreement among raft nodes before linearized reading' (duration: 753.599963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:42.143924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:41.389163Z","time spent":"754.752941ms","remote":"127.0.0.1:34612","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-18T02:44:42.142934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.025786ms","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-18T02:44:42.144054Z","caller":"traceutil/trace.go:171","msg":"trace[614393925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:695; }","duration":"768.170233ms","start":"2026-02-18T02:44:41.375877Z","end":"2026-02-18T02:44:42.144048Z","steps":["trace[614393925] 'agreement among raft nodes before linearized reading' (duration: 767.015166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:42.145836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:41.375839Z","time spent":"769.988332ms","remote":"127.0.0.1:34612","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-18T02:44:42.656458Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630436561678982633,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T02:44:43.043019Z","caller":"traceutil/trace.go:171","msg":"trace[1697441485] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"887.787601ms","start":"2026-02-18T02:44:42.155215Z","end":"2026-02-18T02:44:43.043002Z","steps":["trace[1697441485] 'process raft request' (duration: 879.935234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:43.043117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:42.155183Z","time spent":"887.881993ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:44:43.092579Z","caller":"traceutil/trace.go:171","msg":"trace[840474022] linearizableReadLoop","detail":"{readStateIndex:724; appliedIndex:720; }","duration":"937.171404ms","start":"2026-02-18T02:44:42.155374Z","end":"2026-02-18T02:44:43.092545Z","steps":["trace[840474022] 'read index received' (duration: 879.78425ms)","trace[840474022] 'applied index is now lower than readState.Index' (duration: 57.385684ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:44:43.093837Z","caller":"traceutil/trace.go:171","msg":"trace[1593046840] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"938.134266ms","start":"2026-02-18T02:44:42.155686Z","end":"2026-02-18T02:44:43.09382Z","steps":["trace[1593046840] 'process raft request' (duration: 935.947046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:43.093943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:42.155676Z","time spent":"938.204237ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:44:43.094753Z","caller":"traceutil/trace.go:171","msg":"trace[191806521] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"936.350755ms","start":"2026-02-18T02:44:42.158386Z","end":"2026-02-18T02:44:43.094737Z","steps":["trace[191806521] 'process raft request' (duration: 933.350598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:43.094879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:42.158377Z","time spent":"936.413256ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:44:43.095456Z","caller":"traceutil/trace.go:171","msg":"trace[1089205319] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"934.990005ms","start":"2026-02-18T02:44:42.160451Z","end":"2026-02-18T02:44:43.095441Z","steps":["trace[1089205319] 'process raft request' (duration: 931.624299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:43.095529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:42.160438Z","time spent":"935.054766ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:44:43.095795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.419957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:44:43.095822Z","caller":"traceutil/trace.go:171","msg":"trace[86277476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:699; }","duration":"940.473768ms","start":"2026-02-18T02:44:42.155336Z","end":"2026-02-18T02:44:43.095809Z","steps":["trace[86277476] 'agreement among raft nodes before linearized reading' (duration: 940.399397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:43.095844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:42.155331Z","time spent":"940.509079ms","remote":"127.0.0.1:34346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T02:44:43.096471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.725351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:44:43.096492Z","caller":"traceutil/trace.go:171","msg":"trace[439559528] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:699; }","duration":"770.752691ms","start":"2026-02-18T02:44:42.325733Z","end":"2026-02-18T02:44:43.096485Z","steps":["trace[439559528] 'agreement among raft nodes before linearized reading' (duration: 770.71393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:43.096515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:42.325719Z","time spent":"770.786602ms","remote":"127.0.0.1:34618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:44:43.096816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.045011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:44:43.096837Z","caller":"traceutil/trace.go:171","msg":"trace[1937495022] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:699; }","duration":"294.084942ms","start":"2026-02-18T02:44:42.802745Z","end":"2026-02-18T02:44:43.09683Z","steps":["trace[1937495022] 'agreement among raft nodes before linearized reading' (duration: 293.910298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:43.097238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.610939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:44:43.097266Z","caller":"traceutil/trace.go:171","msg":"trace[882102699] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:699; }","duration":"359.66289ms","start":"2026-02-18T02:44:42.737594Z","end":"2026-02-18T02:44:43.097257Z","steps":["trace[882102699] 'agreement among raft nodes before linearized reading' (duration: 359.479336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:43.09729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:42.73758Z","time spent":"359.699001ms","remote":"127.0.0.1:34488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:44:43.098013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.581045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:44:43.098043Z","caller":"traceutil/trace.go:171","msg":"trace[421834159] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:699; }","duration":"675.686598ms","start":"2026-02-18T02:44:42.422348Z","end":"2026-02-18T02:44:43.098035Z","steps":["trace[421834159] 'agreement among raft nodes before linearized reading' (duration: 675.626886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:43.09807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:42.422328Z","time spent":"675.735308ms","remote":"127.0.0.1:34514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:44:44.414222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.099208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.219\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T02:44:44.414299Z","caller":"traceutil/trace.go:171","msg":"trace[1917982966] range","detail":"{range_begin:/registry/masterleases/199.19.213.219; range_end:; response_count:1; response_revision:700; }","duration":"108.217141ms","start":"2026-02-18T02:44:44.306049Z","end":"2026-02-18T02:44:44.414266Z","steps":["trace[1917982966] 'range keys from in-memory index tree' (duration: 107.954004ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:44:54.369101Z","caller":"traceutil/trace.go:171","msg":"trace[1740362731] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"150.802041ms","start":"2026-02-18T02:44:54.218267Z","end":"2026-02-18T02:44:54.369069Z","steps":["trace[1740362731] 'process raft request' (duration: 150.518875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:56.963826Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630436561678982755,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T02:44:57.301579Z","caller":"traceutil/trace.go:171","msg":"trace[2112311171] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"926.084674ms","start":"2026-02-18T02:44:56.375472Z","end":"2026-02-18T02:44:57.301556Z","steps":["trace[2112311171] 'process raft request' (duration: 925.90989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:57.301718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:56.375449Z","time spent":"926.192498ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:44:57.302158Z","caller":"traceutil/trace.go:171","msg":"trace[1895849808] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:768; }","duration":"838.206712ms","start":"2026-02-18T02:44:56.463382Z","end":"2026-02-18T02:44:57.301588Z","steps":["trace[1895849808] 'read index received' (duration: 838.191532ms)","trace[1895849808] 'applied index is now lower than readState.Index' (duration: 11.77µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:44:57.302262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.877639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:44:57.302568Z","caller":"traceutil/trace.go:171","msg":"trace[1718852693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:740; }","duration":"839.200666ms","start":"2026-02-18T02:44:56.463352Z","end":"2026-02-18T02:44:57.302552Z","steps":["trace[1718852693] 'agreement among raft nodes before linearized reading' (duration: 838.880759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:57.302768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:56.463324Z","time spent":"839.36581ms","remote":"127.0.0.1:34332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T02:44:57.303053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.329861ms","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-18T02:44:57.303097Z","caller":"traceutil/trace.go:171","msg":"trace[635511220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:740; }","duration":"205.403872ms","start":"2026-02-18T02:44:57.097681Z","end":"2026-02-18T02:44:57.303085Z","steps":["trace[635511220] 'agreement among raft nodes before linearized reading' (duration: 205.281279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:57.302571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.891614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:44:57.305547Z","caller":"traceutil/trace.go:171","msg":"trace[1286094248] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:740; }","duration":"447.891432ms","start":"2026-02-18T02:44:56.857646Z","end":"2026-02-18T02:44:57.305537Z","steps":["trace[1286094248] 'agreement among raft nodes before linearized reading' (duration: 444.893514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:57.30562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:56.857636Z","time spent":"447.973204ms","remote":"127.0.0.1:34530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":10,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-02-18T02:44:59.531356Z","caller":"traceutil/trace.go:171","msg":"trace[947397170] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:773; }","duration":"163.806804ms","start":"2026-02-18T02:44:59.367534Z","end":"2026-02-18T02:44:59.531341Z","steps":["trace[947397170] 'read index received' (duration: 163.546998ms)","trace[947397170] 'applied index is now lower than readState.Index' (duration: 259.316µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:44:59.531735Z","caller":"traceutil/trace.go:171","msg":"trace[1986267531] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"220.030943ms","start":"2026-02-18T02:44:59.311697Z","end":"2026-02-18T02:44:59.531728Z","steps":["trace[1986267531] 'process raft request' (duration: 219.428798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:59.531991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.462099ms","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-18T02:44:59.532066Z","caller":"traceutil/trace.go:171","msg":"trace[1390589663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:746; }","duration":"164.584722ms","start":"2026-02-18T02:44:59.367475Z","end":"2026-02-18T02:44:59.53206Z","steps":["trace[1390589663] 'agreement among raft nodes before linearized reading' (duration: 164.439769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:59.533223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.492523ms","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-18T02:44:59.533324Z","caller":"traceutil/trace.go:171","msg":"trace[522189685] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:746; }","duration":"154.633047ms","start":"2026-02-18T02:44:59.378685Z","end":"2026-02-18T02:44:59.533318Z","steps":["trace[522189685] 'agreement among raft nodes before linearized reading' (duration: 154.468613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:59.533597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.116117ms","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-18T02:44:59.533683Z","caller":"traceutil/trace.go:171","msg":"trace[48279302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:746; }","duration":"143.253431ms","start":"2026-02-18T02:44:59.390424Z","end":"2026-02-18T02:44:59.533677Z","steps":["trace[48279302] 'agreement among raft nodes before linearized reading' (duration: 143.081597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:59.533867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.384011ms","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-18T02:44:59.533947Z","caller":"traceutil/trace.go:171","msg":"trace[214612267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:746; }","duration":"154.487544ms","start":"2026-02-18T02:44:59.379455Z","end":"2026-02-18T02:44:59.533942Z","steps":["trace[214612267] 'agreement among raft nodes before linearized reading' (duration: 154.358801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:59.86944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.115452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:44:59.869612Z","caller":"traceutil/trace.go:171","msg":"trace[502194751] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:774; }","duration":"101.602551ms","start":"2026-02-18T02:44:59.767996Z","end":"2026-02-18T02:44:59.869599Z","steps":["trace[502194751] 'read index received' (duration: 41.392454ms)","trace[502194751] 'applied index is now lower than readState.Index' (duration: 60.209327ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:44:59.869795Z","caller":"traceutil/trace.go:171","msg":"trace[1791526103] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"331.883973ms","start":"2026-02-18T02:44:59.5379Z","end":"2026-02-18T02:44:59.869784Z","steps":["trace[1791526103] 'process raft request' (duration: 33.360762ms)","trace[1791526103] 'compare' (duration: 298.03481ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:44:59.869836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:59.537886Z","time spent":"331.927865ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:44:59.869982Z","caller":"traceutil/trace.go:171","msg":"trace[1751944550] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"329.213694ms","start":"2026-02-18T02:44:59.540762Z","end":"2026-02-18T02:44:59.869976Z","steps":["trace[1751944550] 'process raft request' (duration: 328.756213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:59.870015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:59.540704Z","time spent":"329.292525ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:44:59.87006Z","caller":"traceutil/trace.go:171","msg":"trace[1022343127] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"329.026349ms","start":"2026-02-18T02:44:59.541028Z","end":"2026-02-18T02:44:59.870055Z","steps":["trace[1022343127] 'process raft request' (duration: 328.528298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:59.870089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:59.541008Z","time spent":"329.06283ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:44:59.87015Z","caller":"traceutil/trace.go:171","msg":"trace[392012368] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"328.386695ms","start":"2026-02-18T02:44:59.541758Z","end":"2026-02-18T02:44:59.870145Z","steps":["trace[392012368] 'process raft request' (duration: 327.817283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:59.870184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:59.54175Z","time spent":"328.415826ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:44:59.870244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.247525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:44:59.87026Z","caller":"traceutil/trace.go:171","msg":"trace[1386460970] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:750; }","duration":"102.309807ms","start":"2026-02-18T02:44:59.767944Z","end":"2026-02-18T02:44:59.870254Z","steps":["trace[1386460970] 'agreement among raft nodes before linearized reading' (duration: 102.274876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:00.886436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.066755ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T02:45:00.886522Z","caller":"traceutil/trace.go:171","msg":"trace[2129093979] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"1.016875991s","start":"2026-02-18T02:44:59.869632Z","end":"2026-02-18T02:45:00.886508Z","steps":["trace[2129093979] 'read index received' (duration: 493.660292ms)","trace[2129093979] 'applied index is now lower than readState.Index' (duration: 523.214649ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:45:00.886965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054143581s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:45:00.886995Z","caller":"traceutil/trace.go:171","msg":"trace[332146092] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:750; }","duration":"1.054262284s","start":"2026-02-18T02:44:59.832726Z","end":"2026-02-18T02:45:00.886988Z","steps":["trace[332146092] 'agreement among raft nodes before linearized reading' (duration: 1.054194803s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:00.887019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:59.832712Z","time spent":"1.054300946s","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":13,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:45:00.887145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.432073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:45:00.887167Z","caller":"traceutil/trace.go:171","msg":"trace[1730347045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:750; }","duration":"448.488454ms","start":"2026-02-18T02:45:00.438674Z","end":"2026-02-18T02:45:00.887162Z","steps":["trace[1730347045] 'agreement among raft nodes before linearized reading' (duration: 448.456073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:00.887181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:00.438657Z","time spent":"448.521264ms","remote":"127.0.0.1:34332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T02:45:02.374461Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630436561678982782,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:45:02.875054Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630436561678982782,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:45:02.972204Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.430088401s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T02:45:02.972446Z","caller":"traceutil/trace.go:171","msg":"trace[1053475039] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:779; }","duration":"1.098004672s","start":"2026-02-18T02:45:01.874427Z","end":"2026-02-18T02:45:02.972432Z","steps":["trace[1053475039] 'read index received' (duration: 1.097865839s)","trace[1053475039] 'applied index is now lower than readState.Index' (duration: 138.323µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:45:02.972677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098204776s","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-18T02:45:02.972724Z","caller":"traceutil/trace.go:171","msg":"trace[991008793] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:751; }","duration":"1.098325699s","start":"2026-02-18T02:45:01.874387Z","end":"2026-02-18T02:45:02.972712Z","steps":["trace[991008793] 'agreement among raft nodes before linearized reading' (duration: 1.098130055s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:02.972738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097261895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-18T02:45:02.972747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:01.874368Z","time spent":"1.09837475s","remote":"127.0.0.1:34612","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-18T02:45:02.972758Z","caller":"traceutil/trace.go:171","msg":"trace[1754577236] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:751; }","duration":"1.097311357s","start":"2026-02-18T02:45:01.87544Z","end":"2026-02-18T02:45:02.972752Z","steps":["trace[1754577236] 'agreement among raft nodes before linearized reading' (duration: 1.097227775s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:02.972776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:01.87543Z","time spent":"1.097341417s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T02:45:02.972846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.64998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:45:02.972857Z","caller":"traceutil/trace.go:171","msg":"trace[1183650747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:751; }","duration":"535.691481ms","start":"2026-02-18T02:45:02.437162Z","end":"2026-02-18T02:45:02.972853Z","steps":["trace[1183650747] 'agreement among raft nodes before linearized reading' (duration: 535.670311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:02.972869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:02.437149Z","time spent":"535.717681ms","remote":"127.0.0.1:34346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T02:45:02.97291Z","caller":"traceutil/trace.go:171","msg":"trace[2065415825] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"1.430874638s","start":"2026-02-18T02:45:01.54203Z","end":"2026-02-18T02:45:02.972904Z","steps":["trace[2065415825] 'process raft request' (duration: 1.430301695s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:02.97296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:01.542018Z","time spent":"1.430905499s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:45:02.972981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.511572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:45:02.972996Z","caller":"traceutil/trace.go:171","msg":"trace[281541020] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:751; }","duration":"576.565683ms","start":"2026-02-18T02:45:02.396426Z","end":"2026-02-18T02:45:02.972992Z","steps":["trace[281541020] 'agreement among raft nodes before linearized reading' (duration: 576.537082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:02.973009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:02.396408Z","time spent":"576.596853ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:45:02.973075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.095296271s","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-18T02:45:02.973087Z","caller":"traceutil/trace.go:171","msg":"trace[294401529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:751; }","duration":"1.095319641s","start":"2026-02-18T02:45:01.877763Z","end":"2026-02-18T02:45:02.973082Z","steps":["trace[294401529] 'agreement among raft nodes before linearized reading' (duration: 1.09528268s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:02.973098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:01.877758Z","time spent":"1.095337591s","remote":"127.0.0.1:34612","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-18T02:45:02.973156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.095408753s","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-18T02:45:02.973167Z","caller":"traceutil/trace.go:171","msg":"trace[1719040691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:751; }","duration":"1.095434784s","start":"2026-02-18T02:45:01.877728Z","end":"2026-02-18T02:45:02.973163Z","steps":["trace[1719040691] 'agreement among raft nodes before linearized reading' (duration: 1.095402573s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:02.973178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:01.877719Z","time spent":"1.095456794s","remote":"127.0.0.1:34612","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-18T02:45:03.577165Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630436561678982785,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:45:04.43073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.187916ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:45:04.431115Z","caller":"traceutil/trace.go:171","msg":"trace[213364568] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:780; }","duration":"1.355018456s","start":"2026-02-18T02:45:03.076074Z","end":"2026-02-18T02:45:04.431092Z","steps":["trace[213364568] 'read index received' (duration: 855.361069ms)","trace[213364568] 'applied index is now lower than readState.Index' (duration: 499.656097ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:45:04.431453Z","caller":"traceutil/trace.go:171","msg":"trace[1968871601] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"1.45268951s","start":"2026-02-18T02:45:02.978743Z","end":"2026-02-18T02:45:04.431433Z","steps":["trace[1968871601] 'process raft request' (duration: 952.684844ms)","trace[1968871601] 'compare' (duration: 499.054873ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:45:04.431559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:02.978729Z","time spent":"1.452779581s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:45:04.431785Z","caller":"traceutil/trace.go:171","msg":"trace[175366761] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"1.452895434s","start":"2026-02-18T02:45:02.978874Z","end":"2026-02-18T02:45:04.43177Z","steps":["trace[175366761] 'process raft request' (duration: 1.452014913s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:04.431886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:02.97887Z","time spent":"1.452968996s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:45:04.432083Z","caller":"traceutil/trace.go:171","msg":"trace[246402656] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"1.452379363s","start":"2026-02-18T02:45:02.979689Z","end":"2026-02-18T02:45:04.432068Z","steps":["trace[246402656] 'process raft request' (duration: 1.451294198s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:04.432208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:02.979676Z","time spent":"1.452487305s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:45:04.432397Z","caller":"traceutil/trace.go:171","msg":"trace[1464072548] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"1.452340691s","start":"2026-02-18T02:45:02.980042Z","end":"2026-02-18T02:45:04.432383Z","steps":["trace[1464072548] 'process raft request' (duration: 1.45100638s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:04.432484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:02.980035Z","time spent":"1.452404083s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:45:04.432735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.356650053s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:45:04.432788Z","caller":"traceutil/trace.go:171","msg":"trace[1426898326] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:755; }","duration":"1.356741435s","start":"2026-02-18T02:45:03.076032Z","end":"2026-02-18T02:45:04.432773Z","steps":["trace[1426898326] 'agreement among raft nodes before linearized reading' (duration: 1.356648233s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:04.432841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:03.076012Z","time spent":"1.356814906s","remote":"127.0.0.1:34370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:45:04.433057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.825081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:45:04.433106Z","caller":"traceutil/trace.go:171","msg":"trace[1870164778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"993.901133ms","start":"2026-02-18T02:45:03.439192Z","end":"2026-02-18T02:45:04.433093Z","steps":["trace[1870164778] 'agreement among raft nodes before linearized reading' (duration: 993.835892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:04.43315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:03.439031Z","time spent":"994.109888ms","remote":"127.0.0.1:34332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T02:45:04.433812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.161575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.219\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T02:45:04.434887Z","caller":"traceutil/trace.go:171","msg":"trace[1821165460] range","detail":"{range_begin:/registry/masterleases/199.19.213.219; range_end:; response_count:1; response_revision:755; }","duration":"127.360372ms","start":"2026-02-18T02:45:04.307514Z","end":"2026-02-18T02:45:04.434875Z","steps":["trace[1821165460] 'agreement among raft nodes before linearized reading' (duration: 126.083523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:04.943545Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630436561678982792,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:45:05.139548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.438761ms","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-18T02:45:05.140029Z","caller":"traceutil/trace.go:171","msg":"trace[848480839] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:755; }","duration":"463.928733ms","start":"2026-02-18T02:45:04.676088Z","end":"2026-02-18T02:45:05.140017Z","steps":["trace[848480839] 'range keys from in-memory index tree' (duration: 463.42289ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:45:05.140315Z","caller":"traceutil/trace.go:171","msg":"trace[705071872] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:784; }","duration":"697.378236ms","start":"2026-02-18T02:45:04.44293Z","end":"2026-02-18T02:45:05.140308Z","steps":["trace[705071872] 'read index received' (duration: 619.322836ms)","trace[705071872] 'applied index is now lower than readState.Index' (duration: 78.05475ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:45:05.140431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:04.436979Z","time spent":"703.449963ms","remote":"127.0.0.1:34368","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-18T02:45:05.140618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.683493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:45:05.140683Z","caller":"traceutil/trace.go:171","msg":"trace[1665785717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"697.759084ms","start":"2026-02-18T02:45:04.442916Z","end":"2026-02-18T02:45:05.140675Z","steps":["trace[1665785717] 'agreement among raft nodes before linearized reading' (duration: 697.681452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:05.140755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:04.44291Z","time spent":"697.839087ms","remote":"127.0.0.1:34332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T02:45:05.140896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.22459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:45:05.140963Z","caller":"traceutil/trace.go:171","msg":"trace[484403437] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:755; }","duration":"186.320801ms","start":"2026-02-18T02:45:04.954636Z","end":"2026-02-18T02:45:05.140957Z","steps":["trace[484403437] 'agreement among raft nodes before linearized reading' (duration: 186.24061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:05.141177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.594551ms","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-18T02:45:05.141289Z","caller":"traceutil/trace.go:171","msg":"trace[1373959978] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:755; }","duration":"164.730885ms","start":"2026-02-18T02:45:04.976551Z","end":"2026-02-18T02:45:05.141282Z","steps":["trace[1373959978] 'agreement among raft nodes before linearized reading' (duration: 164.566841ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:45:05.424551Z","caller":"traceutil/trace.go:171","msg":"trace[860471892] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:786; }","duration":"219.811497ms","start":"2026-02-18T02:45:05.204703Z","end":"2026-02-18T02:45:05.424514Z","steps":["trace[860471892] 'read index received' (duration: 218.623741ms)","trace[860471892] 'applied index is now lower than readState.Index' (duration: 1.185856ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:45:05.425403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.657906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-18T02:45:05.425762Z","caller":"traceutil/trace.go:171","msg":"trace[1032590112] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:758; }","duration":"221.037024ms","start":"2026-02-18T02:45:05.204664Z","end":"2026-02-18T02:45:05.425701Z","steps":["trace[1032590112] 'agreement among raft nodes before linearized reading' (duration: 220.41626ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:45:05.426492Z","caller":"traceutil/trace.go:171","msg":"trace[1704466922] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"243.649345ms","start":"2026-02-18T02:45:05.182791Z","end":"2026-02-18T02:45:05.426441Z","steps":["trace[1704466922] 'process raft request' (duration: 241.644799ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:45:05.426733Z","caller":"traceutil/trace.go:171","msg":"trace[732588071] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"279.599456ms","start":"2026-02-18T02:45:05.147124Z","end":"2026-02-18T02:45:05.426724Z","steps":["trace[732588071] 'process raft request' (duration: 276.298562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:20.933129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.977598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:45:20.933179Z","caller":"traceutil/trace.go:171","msg":"trace[936259767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:850; }","duration":"497.062191ms","start":"2026-02-18T02:45:20.436105Z","end":"2026-02-18T02:45:20.933167Z","steps":["trace[936259767] 'agreement among raft nodes before linearized reading' (duration: 496.977399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:20.933214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:20.43609Z","time spent":"497.116331ms","remote":"127.0.0.1:34346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T02:45:20.933032Z","caller":"traceutil/trace.go:171","msg":"trace[1608348949] linearizableReadLoop","detail":"{readStateIndex:885; appliedIndex:884; }","duration":"496.821125ms","start":"2026-02-18T02:45:20.436135Z","end":"2026-02-18T02:45:20.932956Z","steps":["trace[1608348949] 'read index received' (duration: 404.881557ms)","trace[1608348949] 'applied index is now lower than readState.Index' (duration: 91.937838ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:45:20.933795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.257476ms","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-18T02:45:20.933854Z","caller":"traceutil/trace.go:171","msg":"trace[308716580] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:850; }","duration":"382.350269ms","start":"2026-02-18T02:45:20.551492Z","end":"2026-02-18T02:45:20.933842Z","steps":["trace[308716580] 'agreement among raft nodes before linearized reading' (duration: 382.205775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:20.933894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:20.551458Z","time spent":"382.428541ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T02:45:20.93405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.889833ms","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-18T02:45:20.934076Z","caller":"traceutil/trace.go:171","msg":"trace[1738897109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:850; }","duration":"367.945424ms","start":"2026-02-18T02:45:20.566123Z","end":"2026-02-18T02:45:20.934069Z","steps":["trace[1738897109] 'agreement among raft nodes before linearized reading' (duration: 367.84928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:20.934096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:20.566119Z","time spent":"367.972695ms","remote":"127.0.0.1:34612","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-18T02:45:20.934111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.042366ms","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-18T02:45:20.934151Z","caller":"traceutil/trace.go:171","msg":"trace[890368808] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:850; }","duration":"368.095237ms","start":"2026-02-18T02:45:20.566044Z","end":"2026-02-18T02:45:20.93414Z","steps":["trace[890368808] 'agreement among raft nodes before linearized reading' (duration: 367.994445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:20.934186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:20.566038Z","time spent":"368.139048ms","remote":"127.0.0.1:34612","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-18T02:45:20.934292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.701864ms","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-18T02:45:20.934313Z","caller":"traceutil/trace.go:171","msg":"trace[1469232226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:850; }","duration":"373.742855ms","start":"2026-02-18T02:45:20.560563Z","end":"2026-02-18T02:45:20.934306Z","steps":["trace[1469232226] 'agreement among raft nodes before linearized reading' (duration: 373.688044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:20.93433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:20.560551Z","time spent":"373.774415ms","remote":"127.0.0.1:34612","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-18T02:45:23.447582Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630436561678983016,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:45:23.94809Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630436561678983016,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:45:24.449191Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630436561678983016,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:45:24.603074Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.656117539s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T02:45:24.604971Z","caller":"traceutil/trace.go:171","msg":"trace[1074370303] linearizableReadLoop","detail":"{readStateIndex:891; appliedIndex:890; }","duration":"1.657689805s","start":"2026-02-18T02:45:22.947256Z","end":"2026-02-18T02:45:24.604946Z","steps":["trace[1074370303] 'read index received' (duration: 1.65746964s)","trace[1074370303] 'applied index is now lower than readState.Index' (duration: 219.025µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:45:24.605534Z","caller":"traceutil/trace.go:171","msg":"trace[412502255] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"1.658607655s","start":"2026-02-18T02:45:22.946909Z","end":"2026-02-18T02:45:24.605516Z","steps":["trace[412502255] 'process raft request' (duration: 1.657877179s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:24.606537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.65489285s","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-18T02:45:24.606599Z","caller":"traceutil/trace.go:171","msg":"trace[1132210882] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:856; }","duration":"1.654960442s","start":"2026-02-18T02:45:22.951622Z","end":"2026-02-18T02:45:24.606582Z","steps":["trace[1132210882] 'agreement among raft nodes before linearized reading' (duration: 1.654837859s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:24.606654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:22.951616Z","time spent":"1.655024304s","remote":"127.0.0.1:34612","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-18T02:45:24.605843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.658560794s","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-18T02:45:24.607609Z","caller":"traceutil/trace.go:171","msg":"trace[846007762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:856; }","duration":"1.660343365s","start":"2026-02-18T02:45:22.947246Z","end":"2026-02-18T02:45:24.607589Z","steps":["trace[846007762] 'agreement among raft nodes before linearized reading' (duration: 1.658445361s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:24.607754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:22.947241Z","time spent":"1.660494428s","remote":"127.0.0.1:34612","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-18T02:45:24.605968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.658189316s","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-18T02:45:24.608Z","caller":"traceutil/trace.go:171","msg":"trace[609796802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:856; }","duration":"1.660224012s","start":"2026-02-18T02:45:22.947759Z","end":"2026-02-18T02:45:24.607983Z","steps":["trace[609796802] 'agreement among raft nodes before linearized reading' (duration: 1.658131375s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:24.608053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:22.947745Z","time spent":"1.660292723s","remote":"127.0.0.1:34612","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-18T02:45:24.606082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.737688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.219\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T02:45:24.608205Z","caller":"traceutil/trace.go:171","msg":"trace[650215464] range","detail":"{range_begin:/registry/masterleases/199.19.213.219; range_end:; response_count:1; response_revision:856; }","duration":"299.896726ms","start":"2026-02-18T02:45:24.308299Z","end":"2026-02-18T02:45:24.608196Z","steps":["trace[650215464] 'agreement among raft nodes before linearized reading' (duration: 297.706306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:24.606172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045250377s","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-18T02:45:24.608393Z","caller":"traceutil/trace.go:171","msg":"trace[988961428] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:856; }","duration":"1.047493477s","start":"2026-02-18T02:45:23.56089Z","end":"2026-02-18T02:45:24.608384Z","steps":["trace[988961428] 'agreement among raft nodes before linearized reading' (duration: 1.045214116s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:24.608433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:23.560878Z","time spent":"1.047541088s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T02:45:24.606269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092697989s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:45:24.608643Z","caller":"traceutil/trace.go:171","msg":"trace[1206699368] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:856; }","duration":"1.095090793s","start":"2026-02-18T02:45:23.513541Z","end":"2026-02-18T02:45:24.608632Z","steps":["trace[1206699368] 'agreement among raft nodes before linearized reading' (duration: 1.092651327s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:24.608681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:23.513512Z","time spent":"1.095157194s","remote":"127.0.0.1:34420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":87,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:45:24.606314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169888502s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:45:24.608913Z","caller":"traceutil/trace.go:171","msg":"trace[1429280906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:856; }","duration":"1.172507152s","start":"2026-02-18T02:45:23.436397Z","end":"2026-02-18T02:45:24.608904Z","steps":["trace[1429280906] 'agreement among raft nodes before linearized reading' (duration: 1.169894163s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:24.608951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:23.436385Z","time spent":"1.172556953s","remote":"127.0.0.1:34332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T02:45:24.606389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.252796506s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:45:24.609182Z","caller":"traceutil/trace.go:171","msg":"trace[1940137069] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:856; }","duration":"1.25562051s","start":"2026-02-18T02:45:23.35355Z","end":"2026-02-18T02:45:24.60917Z","steps":["trace[1940137069] 'agreement among raft nodes before linearized reading' (duration: 1.252784656s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:24.609221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:23.353536Z","time spent":"1.25567371s","remote":"127.0.0.1:34774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:45:24.606439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.610697083s","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-18T02:45:24.609452Z","caller":"traceutil/trace.go:171","msg":"trace[1822098336] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:856; }","duration":"1.613727532s","start":"2026-02-18T02:45:22.995715Z","end":"2026-02-18T02:45:24.609443Z","steps":["trace[1822098336] 'agreement among raft nodes before linearized reading' (duration: 1.610698262s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:24.60949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:22.995706Z","time spent":"1.613772432s","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:45:24.610623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:22.946899Z","time spent":"1.659650278s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:45:25.113129Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630436561678983019,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:45:26.623081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.010517515s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T02:45:26.623132Z","caller":"traceutil/trace.go:171","msg":"trace[1885268601] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.010585117s","start":"2026-02-18T02:45:24.612537Z","end":"2026-02-18T02:45:26.623122Z","steps":["trace[1885268601] 'agreement among raft nodes before linearized reading' (duration: 2.010528236s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:26.623203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:24.612531Z","time spent":"2.010665259s","remote":"127.0.0.1:34346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/18 02:45:26 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T02:45:26.629556Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.254238888s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T02:45:26.763746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.088731173s","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-18T02:45:26.763869Z","caller":"traceutil/trace.go:171","msg":"trace[676646918] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:856; }","duration":"2.088906507s","start":"2026-02-18T02:45:24.674944Z","end":"2026-02-18T02:45:26.763851Z","steps":["trace[676646918] 'range keys from in-memory index tree' (duration: 2.088718033s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:26.764709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.389513434s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-18T02:45:26.765102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:24.612077Z","time spent":"2.153017426s","remote":"127.0.0.1:34368","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-18T02:45:26.767142Z","caller":"traceutil/trace.go:171","msg":"trace[1667142476] linearizableReadLoop","detail":"{readStateIndex:896; appliedIndex:891; }","duration":"2.15457657s","start":"2026-02-18T02:45:24.612551Z","end":"2026-02-18T02:45:26.767128Z","steps":["trace[1667142476] 'read index received' (duration: 762.734783ms)","trace[1667142476] 'applied index is now lower than readState.Index' (duration: 1.391840767s)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:45:26.767352Z","caller":"traceutil/trace.go:171","msg":"trace[1321664838] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"2.151211614s","start":"2026-02-18T02:45:24.616129Z","end":"2026-02-18T02:45:26.767341Z","steps":["trace[1321664838] 'process raft request' (duration: 2.148635466s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:26.76744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:24.616118Z","time spent":"2.151269516s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:45:26.767615Z","caller":"traceutil/trace.go:171","msg":"trace[1102596357] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"2.151415809s","start":"2026-02-18T02:45:24.616188Z","end":"2026-02-18T02:45:26.767604Z","steps":["trace[1102596357] 'process raft request' (duration: 2.150840166s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:26.767681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:24.616183Z","time spent":"2.15146228s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:45:26.768462Z","caller":"traceutil/trace.go:171","msg":"trace[179417579] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"2.150893858s","start":"2026-02-18T02:45:24.617553Z","end":"2026-02-18T02:45:26.768447Z","steps":["trace[179417579] 'process raft request' (duration: 2.149542078s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:26.768895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:24.617546Z","time spent":"2.151284136s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:45:26.767896Z","caller":"traceutil/trace.go:171","msg":"trace[1748577893] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"2.151653654s","start":"2026-02-18T02:45:24.616232Z","end":"2026-02-18T02:45:26.767886Z","steps":["trace[1748577893] 'process raft request' (duration: 2.150835126s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:26.772418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:24.616228Z","time spent":"2.156067664s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:45:27.267365Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630436561678983028,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T02:45:27.319875Z","caller":"traceutil/trace.go:171","msg":"trace[1053207380] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"696.851835ms","start":"2026-02-18T02:45:26.623008Z","end":"2026-02-18T02:45:27.31986Z","steps":["trace[1053207380] 'process raft request' (duration: 696.817375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:27.319969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:26.623Z","time spent":"696.923857ms","remote":"127.0.0.1:34420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T02:45:27.320163Z","caller":"traceutil/trace.go:171","msg":"trace[965198763] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"1.503818928s","start":"2026-02-18T02:45:25.816339Z","end":"2026-02-18T02:45:27.320157Z","steps":["trace[965198763] 'process raft request' (duration: 1.503401209s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:27.320202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:25.816321Z","time spent":"1.503855268s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:45:27.64966Z","caller":"traceutil/trace.go:171","msg":"trace[1526475867] linearizableReadLoop","detail":"{readStateIndex:899; appliedIndex:898; }","duration":"882.453518ms","start":"2026-02-18T02:45:26.767185Z","end":"2026-02-18T02:45:27.649638Z","steps":["trace[1526475867] 'read index received' (duration: 553.162937ms)","trace[1526475867] 'applied index is now lower than readState.Index' (duration: 329.289701ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:45:27.649805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026872462s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:45:27.649834Z","caller":"traceutil/trace.go:171","msg":"trace[1765920483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:863; }","duration":"1.026942403s","start":"2026-02-18T02:45:26.622883Z","end":"2026-02-18T02:45:27.649826Z","steps":["trace[1765920483] 'agreement among raft nodes before linearized reading' (duration: 1.026843701s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:27.649864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:26.62287Z","time spent":"1.026987635s","remote":"127.0.0.1:34332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T02:45:27.650059Z","caller":"traceutil/trace.go:171","msg":"trace[854128676] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"881.698292ms","start":"2026-02-18T02:45:26.768343Z","end":"2026-02-18T02:45:27.650041Z","steps":["trace[854128676] 'process raft request' (duration: 819.815344ms)","trace[854128676] 'compare' (duration: 61.220563ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:45:27.650166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:26.768325Z","time spent":"881.774983ms","remote":"127.0.0.1:34368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:45:27.651169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028224952s","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-18T02:45:27.651215Z","caller":"traceutil/trace.go:171","msg":"trace[1268605720] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:863; }","duration":"1.028282214s","start":"2026-02-18T02:45:26.622922Z","end":"2026-02-18T02:45:27.651204Z","steps":["trace[1268605720] 'agreement among raft nodes before linearized reading' (duration: 1.028142211s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:27.651254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:26.622919Z","time spent":"1.028328385s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-18T02:45:37.779068Z","caller":"traceutil/trace.go:171","msg":"trace[1708221762] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"315.492799ms","start":"2026-02-18T02:45:37.463563Z","end":"2026-02-18T02:45:37.779056Z","steps":["trace[1708221762] 'process raft request' (duration: 315.192232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:37.779149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:37.463548Z","time spent":"315.55772ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:45:38.121167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.259732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:45:38.121234Z","caller":"traceutil/trace.go:171","msg":"trace[1561629528] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"337.850374ms","start":"2026-02-18T02:45:37.78337Z","end":"2026-02-18T02:45:38.12122Z","steps":["trace[1561629528] 'process raft request' (duration: 223.48222ms)","trace[1561629528] 'compare' (duration: 114.086858ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:45:38.121265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:37.78336Z","time spent":"337.890486ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:45:38.861146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.403443ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:45:38.861204Z","caller":"traceutil/trace.go:171","msg":"trace[244111142] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:930; }","duration":"426.583608ms","start":"2026-02-18T02:45:38.43461Z","end":"2026-02-18T02:45:38.861194Z","steps":["trace[244111142] 'read index received' (duration: 43.044802ms)","trace[244111142] 'applied index is now lower than readState.Index' (duration: 383.538276ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:45:38.861283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.67861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:45:38.861297Z","caller":"traceutil/trace.go:171","msg":"trace[152320815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:893; }","duration":"426.7148ms","start":"2026-02-18T02:45:38.434576Z","end":"2026-02-18T02:45:38.861291Z","steps":["trace[152320815] 'agreement among raft nodes before linearized reading' (duration: 426.678209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:38.861323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:38.434563Z","time spent":"426.756282ms","remote":"127.0.0.1:34332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T02:45:38.861268Z","caller":"traceutil/trace.go:171","msg":"trace[457501672] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"606.868302ms","start":"2026-02-18T02:45:38.254374Z","end":"2026-02-18T02:45:38.861242Z","steps":["trace[457501672] 'process raft request' (duration: 223.319466ms)","trace[457501672] 'compare' (duration: 383.336561ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:45:38.861431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.060718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:45:38.861444Z","caller":"traceutil/trace.go:171","msg":"trace[2006017938] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:893; }","duration":"230.075819ms","start":"2026-02-18T02:45:38.631365Z","end":"2026-02-18T02:45:38.861441Z","steps":["trace[2006017938] 'agreement among raft nodes before linearized reading' (duration: 230.052228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:38.861435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:38.254358Z","time spent":"607.006375ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:45:39.537292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.831614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:45:39.537353Z","caller":"traceutil/trace.go:171","msg":"trace[1970109673] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:931; }","duration":"625.498892ms","start":"2026-02-18T02:45:38.911844Z","end":"2026-02-18T02:45:39.537343Z","steps":["trace[1970109673] 'read index received' (duration: 71.542346ms)","trace[1970109673] 'applied index is now lower than readState.Index' (duration: 553.955306ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:45:39.537495Z","caller":"traceutil/trace.go:171","msg":"trace[679048567] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"672.700039ms","start":"2026-02-18T02:45:38.864788Z","end":"2026-02-18T02:45:39.537488Z","steps":["trace[679048567] 'process raft request' (duration: 118.608421ms)","trace[679048567] 'compare' (duration: 553.72492ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:45:39.537528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:38.864778Z","time spent":"672.73245ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:45:39.537661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.82826ms","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-18T02:45:39.537675Z","caller":"traceutil/trace.go:171","msg":"trace[238344624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:894; }","duration":"625.879322ms","start":"2026-02-18T02:45:38.91179Z","end":"2026-02-18T02:45:39.53767Z","steps":["trace[238344624] 'agreement among raft nodes before linearized reading' (duration: 625.81814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:39.537689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:38.911772Z","time spent":"625.913682ms","remote":"127.0.0.1:34612","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-18T02:45:39.537777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.517322ms","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-18T02:45:39.537791Z","caller":"traceutil/trace.go:171","msg":"trace[370394905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:894; }","duration":"613.547583ms","start":"2026-02-18T02:45:38.924239Z","end":"2026-02-18T02:45:39.537787Z","steps":["trace[370394905] 'agreement among raft nodes before linearized reading' (duration: 613.507612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:39.537805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:38.924232Z","time spent":"613.569923ms","remote":"127.0.0.1:34612","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-18T02:45:39.537977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.096899ms","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-18T02:45:39.537991Z","caller":"traceutil/trace.go:171","msg":"trace[388473088] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:894; }","duration":"546.151101ms","start":"2026-02-18T02:45:38.991836Z","end":"2026-02-18T02:45:39.537987Z","steps":["trace[388473088] 'agreement among raft nodes before linearized reading' (duration: 546.10586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:39.538003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:38.991823Z","time spent":"546.176911ms","remote":"127.0.0.1:34612","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-18T02:45:40.437687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.905547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:45:40.437746Z","caller":"traceutil/trace.go:171","msg":"trace[2121637659] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"890.355558ms","start":"2026-02-18T02:45:39.547381Z","end":"2026-02-18T02:45:40.437736Z","steps":["trace[2121637659] 'process raft request' (duration: 640.350778ms)","trace[2121637659] 'compare' (duration: 249.844946ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:45:40.437775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:39.547366Z","time spent":"890.396558ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:45:41.378153Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.035606449s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T02:45:41.710095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.608492ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:45:41.710492Z","caller":"traceutil/trace.go:171","msg":"trace[1293148808] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"2.16295475s","start":"2026-02-18T02:45:39.547524Z","end":"2026-02-18T02:45:41.710478Z","steps":["trace[1293148808] 'process raft request' (duration: 1.830905978s)","trace[1293148808] 'compare' (duration: 331.349137ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:45:41.710542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:39.547504Z","time spent":"2.163014602s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:45:41.710716Z","caller":"traceutil/trace.go:171","msg":"trace[1294122490] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"1.840381942s","start":"2026-02-18T02:45:39.870326Z","end":"2026-02-18T02:45:41.710708Z","steps":["trace[1294122490] 'process raft request' (duration: 1.83983391s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:41.710748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:39.870312Z","time spent":"1.840417523s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:45:41.710847Z","caller":"traceutil/trace.go:171","msg":"trace[227242802] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:932; }","duration":"1.586279801s","start":"2026-02-18T02:45:40.124561Z","end":"2026-02-18T02:45:41.71084Z","steps":["trace[227242802] 'read index received' (duration: 63.248939ms)","trace[227242802] 'applied index is now lower than readState.Index' (duration: 1.523030122s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:45:41.72374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.30296763s","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-18T02:45:41.72377Z","caller":"traceutil/trace.go:171","msg":"trace[1416023635] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:897; }","duration":"1.303026211s","start":"2026-02-18T02:45:40.420737Z","end":"2026-02-18T02:45:41.723763Z","steps":["trace[1416023635] 'agreement among raft nodes before linearized reading' (duration: 1.30296588s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:41.723787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:40.420725Z","time spent":"1.303058222s","remote":"127.0.0.1:34462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:45:41.723909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.985554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:45:41.723922Z","caller":"traceutil/trace.go:171","msg":"trace[2056043103] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:897; }","duration":"950.033585ms","start":"2026-02-18T02:45:40.773884Z","end":"2026-02-18T02:45:41.723918Z","steps":["trace[2056043103] 'agreement among raft nodes before linearized reading' (duration: 950.008685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:41.723936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:40.773866Z","time spent":"950.065307ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-18T02:45:41.723954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.162617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-18T02:45:41.723999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.28837579s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:45:41.724003Z","caller":"traceutil/trace.go:171","msg":"trace[350788535] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:897; }","duration":"182.252478ms","start":"2026-02-18T02:45:41.541736Z","end":"2026-02-18T02:45:41.723989Z","steps":["trace[350788535] 'agreement among raft nodes before linearized reading' (duration: 182.123575ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:45:41.724014Z","caller":"traceutil/trace.go:171","msg":"trace[1945649745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:897; }","duration":"1.288415961s","start":"2026-02-18T02:45:40.435593Z","end":"2026-02-18T02:45:41.724009Z","steps":["trace[1945649745] 'agreement among raft nodes before linearized reading' (duration: 1.288393971s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:41.724031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:40.435578Z","time spent":"1.288449912s","remote":"127.0.0.1:34346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T02:45:41.724176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.599612653s","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-18T02:45:41.724196Z","caller":"traceutil/trace.go:171","msg":"trace[1523586683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:897; }","duration":"1.599653303s","start":"2026-02-18T02:45:40.124536Z","end":"2026-02-18T02:45:41.72419Z","steps":["trace[1523586683] 'agreement among raft nodes before linearized reading' (duration: 1.586404014s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:41.724214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:40.124524Z","time spent":"1.599684504s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-18T02:45:42.888784Z","caller":"traceutil/trace.go:171","msg":"trace[1556623210] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"445.451914ms","start":"2026-02-18T02:45:42.443309Z","end":"2026-02-18T02:45:42.888761Z","steps":["trace[1556623210] 'process raft request' (duration: 445.308111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:42.888939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:42.443301Z","time spent":"445.560568ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:45:44.239054Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630436561678983143,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T02:45:44.337917Z","caller":"traceutil/trace.go:171","msg":"trace[2053249733] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"618.148317ms","start":"2026-02-18T02:45:43.719749Z","end":"2026-02-18T02:45:44.337897Z","steps":["trace[2053249733] 'process raft request' (duration: 617.999474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:44.338058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:43.719734Z","time spent":"618.259239ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:45:45.070981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.309275ms","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-18T02:45:45.071069Z","caller":"traceutil/trace.go:171","msg":"trace[124619087] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:901; }","duration":"396.406757ms","start":"2026-02-18T02:45:44.674649Z","end":"2026-02-18T02:45:45.071055Z","steps":["trace[124619087] 'range keys from in-memory index tree' (duration: 396.296474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:45.07135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.327243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:45:45.071386Z","caller":"traceutil/trace.go:171","msg":"trace[1010326810] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:940; }","duration":"1.333324116s","start":"2026-02-18T02:45:43.738055Z","end":"2026-02-18T02:45:45.071379Z","steps":["trace[1010326810] 'read index received' (duration: 600.484858ms)","trace[1010326810] 'applied index is now lower than readState.Index' (duration: 732.838598ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:45:45.071539Z","caller":"traceutil/trace.go:171","msg":"trace[1684476163] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"1.351236391s","start":"2026-02-18T02:45:43.720295Z","end":"2026-02-18T02:45:45.071531Z","steps":["trace[1684476163] 'process raft request' (duration: 866.683543ms)","trace[1684476163] 'compare' (duration: 483.798651ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:45:45.071586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:43.72028Z","time spent":"1.351284462s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:45:45.071741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.333684774s","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-18T02:45:45.071765Z","caller":"traceutil/trace.go:171","msg":"trace[86538343] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:902; }","duration":"1.333727474s","start":"2026-02-18T02:45:43.738031Z","end":"2026-02-18T02:45:45.071759Z","steps":["trace[86538343] 'agreement among raft nodes before linearized reading' (duration: 1.333652513s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:45.071783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:43.738022Z","time spent":"1.333756825s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T02:45:45.071898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.333771486s","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-18T02:45:45.071963Z","caller":"traceutil/trace.go:171","msg":"trace[731238890] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:902; }","duration":"1.333859137s","start":"2026-02-18T02:45:43.738086Z","end":"2026-02-18T02:45:45.071945Z","steps":["trace[731238890] 'agreement among raft nodes before linearized reading' (duration: 1.333667153s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:45.072022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.82105ms","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-18T02:45:45.072042Z","caller":"traceutil/trace.go:171","msg":"trace[830720871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:902; }","duration":"178.887741ms","start":"2026-02-18T02:45:44.893149Z","end":"2026-02-18T02:45:45.072036Z","steps":["trace[830720871] 'agreement among raft nodes before linearized reading' (duration: 178.83336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:45.072206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.066508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.219\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T02:45:45.072232Z","caller":"traceutil/trace.go:171","msg":"trace[126414710] range","detail":"{range_begin:/registry/masterleases/199.19.213.219; range_end:; response_count:1; response_revision:902; }","duration":"762.12677ms","start":"2026-02-18T02:45:44.310098Z","end":"2026-02-18T02:45:45.072225Z","steps":["trace[126414710] 'agreement among raft nodes before linearized reading' (duration: 762.072808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:45.072248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:44.310079Z","time spent":"762.16573ms","remote":"127.0.0.1:34368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.219\" "} {"level":"warn","ts":"2026-02-18T02:45:45.072252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:43.738081Z","time spent":"1.334150734s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T02:45:45.688011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:45.075764Z","time spent":"612.241024ms","remote":"127.0.0.1:34368","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-18T02:45:45.738125Z","caller":"traceutil/trace.go:171","msg":"trace[907126173] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:941; }","duration":"298.198968ms","start":"2026-02-18T02:45:45.439905Z","end":"2026-02-18T02:45:45.738104Z","steps":["trace[907126173] 'read index received' (duration: 247.062043ms)","trace[907126173] 'applied index is now lower than readState.Index' (duration: 51.136145ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:45:45.738303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.381512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:45:45.738345Z","caller":"traceutil/trace.go:171","msg":"trace[462560258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:905; }","duration":"298.466804ms","start":"2026-02-18T02:45:45.439868Z","end":"2026-02-18T02:45:45.738334Z","steps":["trace[462560258] 'agreement among raft nodes before linearized reading' (duration: 298.360402ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:45:45.739723Z","caller":"traceutil/trace.go:171","msg":"trace[688021768] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"661.806893ms","start":"2026-02-18T02:45:45.077908Z","end":"2026-02-18T02:45:45.739715Z","steps":["trace[688021768] 'process raft request' (duration: 659.913861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:45.739789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:45.077898Z","time spent":"661.853414ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:45:45.739928Z","caller":"traceutil/trace.go:171","msg":"trace[770242686] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"661.19821ms","start":"2026-02-18T02:45:45.078723Z","end":"2026-02-18T02:45:45.739922Z","steps":["trace[770242686] 'process raft request' (duration: 659.233026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:45.739963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:45.078713Z","time spent":"661.23274ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:45:45.740081Z","caller":"traceutil/trace.go:171","msg":"trace[1138612405] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"660.204146ms","start":"2026-02-18T02:45:45.079869Z","end":"2026-02-18T02:45:45.740073Z","steps":["trace[1138612405] 'process raft request' (duration: 658.197212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:45.740114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:45:45.079862Z","time spent":"660.235238ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:45:48.538133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.327179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:45:48.538188Z","caller":"traceutil/trace.go:171","msg":"trace[1083951125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"101.402151ms","start":"2026-02-18T02:45:48.436773Z","end":"2026-02-18T02:45:48.538175Z","steps":["trace[1083951125] 'range keys from in-memory index tree' (duration: 101.292638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:48.539868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.128325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-18T02:45:48.539897Z","caller":"traceutil/trace.go:171","msg":"trace[288109570] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:928; }","duration":"105.187807ms","start":"2026-02-18T02:45:48.4347Z","end":"2026-02-18T02:45:48.539888Z","steps":["trace[288109570] 'range keys from in-memory index tree' (duration: 103.235953ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:45:48.648887Z","caller":"traceutil/trace.go:171","msg":"trace[413600945] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"104.992543ms","start":"2026-02-18T02:45:48.543878Z","end":"2026-02-18T02:45:48.64887Z","steps":["trace[413600945] 'process raft request' (duration: 104.898111ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:45:50.063616Z","caller":"traceutil/trace.go:171","msg":"trace[527833554] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:975; }","duration":"222.688012ms","start":"2026-02-18T02:45:49.840899Z","end":"2026-02-18T02:45:50.063587Z","steps":["trace[527833554] 'read index received' (duration: 135.418861ms)","trace[527833554] 'applied index is now lower than readState.Index' (duration: 87.267421ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:45:50.063808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.893067ms","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-18T02:45:50.063863Z","caller":"traceutil/trace.go:171","msg":"trace[96254820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:935; }","duration":"222.989018ms","start":"2026-02-18T02:45:49.84086Z","end":"2026-02-18T02:45:50.063849Z","steps":["trace[96254820] 'agreement among raft nodes before linearized reading' (duration: 222.799614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:50.063899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.506854ms","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-18T02:45:50.063936Z","caller":"traceutil/trace.go:171","msg":"trace[128723578] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:935; }","duration":"213.573525ms","start":"2026-02-18T02:45:49.850351Z","end":"2026-02-18T02:45:50.063925Z","steps":["trace[128723578] 'agreement among raft nodes before linearized reading' (duration: 213.394511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:50.064095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.713209ms","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-18T02:45:50.064115Z","caller":"traceutil/trace.go:171","msg":"trace[1441300133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:935; }","duration":"213.738219ms","start":"2026-02-18T02:45:49.850371Z","end":"2026-02-18T02:45:50.064109Z","steps":["trace[1441300133] 'agreement among raft nodes before linearized reading' (duration: 213.683688ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:45:58.247876Z","caller":"traceutil/trace.go:171","msg":"trace[77041327] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"111.57109ms","start":"2026-02-18T02:45:58.136288Z","end":"2026-02-18T02:45:58.24786Z","steps":["trace[77041327] 'process raft request' (duration: 108.924891ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:46:01.556948Z","caller":"traceutil/trace.go:171","msg":"trace[957795699] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"133.451716ms","start":"2026-02-18T02:46:01.423477Z","end":"2026-02-18T02:46:01.556929Z","steps":["trace[957795699] 'read index received' (duration: 133.283252ms)","trace[957795699] 'applied index is now lower than readState.Index' (duration: 167.724µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:46:01.55721Z","caller":"traceutil/trace.go:171","msg":"trace[1128679725] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"378.677466ms","start":"2026-02-18T02:46:01.178524Z","end":"2026-02-18T02:46:01.557201Z","steps":["trace[1128679725] 'process raft request' (duration: 378.288417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:46:01.557295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:46:01.178509Z","time spent":"378.721417ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:46:01.55839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.904228ms","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:533"} {"level":"info","ts":"2026-02-18T02:46:01.558437Z","caller":"traceutil/trace.go:171","msg":"trace[1525610977] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1009; }","duration":"134.99776ms","start":"2026-02-18T02:46:01.423424Z","end":"2026-02-18T02:46:01.558421Z","steps":["trace[1525610977] 'agreement among raft nodes before linearized reading' (duration: 134.055499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:46:02.663872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.855173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-18T02:46:02.663946Z","caller":"traceutil/trace.go:171","msg":"trace[1726353982] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1010; }","duration":"969.978707ms","start":"2026-02-18T02:46:01.693949Z","end":"2026-02-18T02:46:02.663928Z","steps":["trace[1726353982] 'range keys from in-memory index tree' (duration: 969.684259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:46:02.664015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:46:01.693927Z","time spent":"970.078769ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-18T02:46:02.664327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.45202ms","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:536"} {"level":"info","ts":"2026-02-18T02:46:02.664374Z","caller":"traceutil/trace.go:171","msg":"trace[1117977767] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1010; }","duration":"871.524241ms","start":"2026-02-18T02:46:01.792823Z","end":"2026-02-18T02:46:02.664348Z","steps":["trace[1117977767] 'range keys from in-memory index tree' (duration: 871.284585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:46:02.664412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:46:01.792801Z","time spent":"871.603453ms","remote":"127.0.0.1:34612","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-18T02:46:02.666909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.957577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:46:02.666997Z","caller":"traceutil/trace.go:171","msg":"trace[616923199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1010; }","duration":"227.168962ms","start":"2026-02-18T02:46:02.439814Z","end":"2026-02-18T02:46:02.666983Z","steps":["trace[616923199] 'range keys from in-memory index tree' (duration: 226.841465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:46:02.667396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.052364ms","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-18T02:46:02.667504Z","caller":"traceutil/trace.go:171","msg":"trace[1481326961] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1010; }","duration":"533.209707ms","start":"2026-02-18T02:46:02.134287Z","end":"2026-02-18T02:46:02.667496Z","steps":["trace[1481326961] 'range keys from in-memory index tree' (duration: 532.921791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:46:02.667611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:46:02.134268Z","time spent":"533.33432ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T02:46:02.667918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.433413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-18T02:46:02.667975Z","caller":"traceutil/trace.go:171","msg":"trace[1579697280] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1010; }","duration":"406.540665ms","start":"2026-02-18T02:46:02.261427Z","end":"2026-02-18T02:46:02.667968Z","steps":["trace[1579697280] 'range keys from in-memory index tree' (duration: 406.356711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:46:02.668014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:46:02.26141Z","time spent":"406.593847ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T02:46:02.668255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.440653ms","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-18T02:46:02.668346Z","caller":"traceutil/trace.go:171","msg":"trace[792134665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1010; }","duration":"406.545075ms","start":"2026-02-18T02:46:02.261793Z","end":"2026-02-18T02:46:02.668338Z","steps":["trace[792134665] 'range keys from in-memory index tree' (duration: 406.371612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:46:02.670866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:46:02.261787Z","time spent":"409.069892ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T02:46:02.668609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.200199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-18T02:46:02.66953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:46:02.342378Z","time spent":"327.148912ms","remote":"127.0.0.1:43440","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-18T02:46:02.669581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.294435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:46:02.674154Z","caller":"traceutil/trace.go:171","msg":"trace[1812091823] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1010; }","duration":"134.776205ms","start":"2026-02-18T02:46:02.539357Z","end":"2026-02-18T02:46:02.674133Z","steps":["trace[1812091823] 'range keys from in-memory index tree' (duration: 129.086707ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:46:02.687123Z","caller":"traceutil/trace.go:171","msg":"trace[694813895] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1010; }","duration":"327.933559ms","start":"2026-02-18T02:46:02.346242Z","end":"2026-02-18T02:46:02.674176Z","steps":["trace[694813895] 'count revisions from in-memory index tree' (duration: 323.148472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:46:02.687239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:46:02.346229Z","time spent":"340.992985ms","remote":"127.0.0.1:35280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-02-18T02:46:03.02614Z","caller":"traceutil/trace.go:171","msg":"trace[749123324] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"346.935218ms","start":"2026-02-18T02:46:02.679185Z","end":"2026-02-18T02:46:03.02612Z","steps":["trace[749123324] 'process raft request' (duration: 346.265223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:46:03.027109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:46:02.67917Z","time spent":"347.764767ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:46:03.027596Z","caller":"traceutil/trace.go:171","msg":"trace[2085642588] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"348.328101ms","start":"2026-02-18T02:46:02.679245Z","end":"2026-02-18T02:46:03.027574Z","steps":["trace[2085642588] 'process raft request' (duration: 346.308955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:46:03.027803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:46:02.67924Z","time spent":"348.402042ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:46:03.028028Z","caller":"traceutil/trace.go:171","msg":"trace[845240936] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"348.735799ms","start":"2026-02-18T02:46:02.679279Z","end":"2026-02-18T02:46:03.028015Z","steps":["trace[845240936] 'process raft request' (duration: 346.436437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:46:03.028262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:46:02.679273Z","time spent":"348.899124ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:46:03.028551Z","caller":"traceutil/trace.go:171","msg":"trace[705806140] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"347.915961ms","start":"2026-02-18T02:46:02.680618Z","end":"2026-02-18T02:46:03.028534Z","steps":["trace[705806140] 'process raft request' (duration: 345.139778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:46:03.028709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:46:02.680612Z","time spent":"347.985162ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:46:03.240182Z","caller":"traceutil/trace.go:171","msg":"trace[653851173] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"146.315316ms","start":"2026-02-18T02:46:03.09371Z","end":"2026-02-18T02:46:03.240026Z","steps":["trace[653851173] 'process raft request' (duration: 80.726164ms)","trace[653851173] 'compare' (duration: 65.249205ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:46:35.887196Z","caller":"traceutil/trace.go:171","msg":"trace[690079969] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"114.084795ms","start":"2026-02-18T02:46:35.773082Z","end":"2026-02-18T02:46:35.887167Z","steps":["trace[690079969] 'process raft request' (duration: 73.903092ms)","trace[690079969] 'compare' (duration: 40.066031ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:47:00.244355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.954915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:47:00.244771Z","caller":"traceutil/trace.go:171","msg":"trace[1291316971] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"251.241085ms","start":"2026-02-18T02:46:59.993511Z","end":"2026-02-18T02:47:00.244752Z","steps":["trace[1291316971] 'process raft request' (duration: 140.80222ms)","trace[1291316971] 'compare' (duration: 109.684269ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:47:18.475963Z","caller":"traceutil/trace.go:171","msg":"trace[1322784360] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1378; }","duration":"101.557097ms","start":"2026-02-18T02:47:18.374393Z","end":"2026-02-18T02:47:18.47595Z","steps":["trace[1322784360] 'read index received' (duration: 6.685988ms)","trace[1322784360] 'applied index is now lower than readState.Index' (duration: 94.870499ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:47:18.476092Z","caller":"traceutil/trace.go:171","msg":"trace[1548889634] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"153.419762ms","start":"2026-02-18T02:47:18.322664Z","end":"2026-02-18T02:47:18.476084Z","steps":["trace[1548889634] 'process raft request' (duration: 58.43132ms)","trace[1548889634] 'compare' (duration: 94.796238ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:47:18.476233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.831024ms","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-18T02:47:18.476249Z","caller":"traceutil/trace.go:171","msg":"trace[478487740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1321; }","duration":"101.887554ms","start":"2026-02-18T02:47:18.374357Z","end":"2026-02-18T02:47:18.476244Z","steps":["trace[478487740] 'agreement among raft nodes before linearized reading' (duration: 101.823213ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:47:42.048944Z","caller":"traceutil/trace.go:171","msg":"trace[1712438105] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"121.327821ms","start":"2026-02-18T02:47:41.927587Z","end":"2026-02-18T02:47:42.048915Z","steps":["trace[1712438105] 'process raft request' (duration: 61.234018ms)","trace[1712438105] 'compare' (duration: 60.011631ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:47:48.47957Z","caller":"traceutil/trace.go:171","msg":"trace[1614369374] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"111.781122ms","start":"2026-02-18T02:47:48.367772Z","end":"2026-02-18T02:47:48.479553Z","steps":["trace[1614369374] 'process raft request' (duration: 111.561246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:47:50.959198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.331024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:47:50.959308Z","caller":"traceutil/trace.go:171","msg":"trace[1306196638] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"277.051521ms","start":"2026-02-18T02:47:50.682236Z","end":"2026-02-18T02:47:50.959288Z","steps":["trace[1306196638] 'process raft request' (duration: 159.475662ms)","trace[1306196638] 'compare' (duration: 117.196381ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:48:30.326845Z","caller":"traceutil/trace.go:171","msg":"trace[279101922] transaction","detail":"{read_only:false; response_revision:1615; number_of_response:1; }","duration":"116.719731ms","start":"2026-02-18T02:48:30.210107Z","end":"2026-02-18T02:48:30.326826Z","steps":["trace[279101922] 'process raft request' (duration: 116.581208ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:48:32.177474Z","caller":"traceutil/trace.go:171","msg":"trace[1163095462] transaction","detail":"{read_only:false; response_revision:1621; number_of_response:1; }","duration":"101.805551ms","start":"2026-02-18T02:48:32.07537Z","end":"2026-02-18T02:48:32.177175Z","steps":["trace[1163095462] 'process raft request' (duration: 101.665738ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:14.587108Z","caller":"traceutil/trace.go:171","msg":"trace[146620237] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"135.821137ms","start":"2026-02-18T02:49:14.451267Z","end":"2026-02-18T02:49:14.587089Z","steps":["trace[146620237] 'process raft request' (duration: 74.037624ms)","trace[146620237] 'compare' (duration: 61.549428ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:49:14.587244Z","caller":"traceutil/trace.go:171","msg":"trace[524031372] linearizableReadLoop","detail":"{readStateIndex:1909; appliedIndex:1907; }","duration":"134.325524ms","start":"2026-02-18T02:49:14.452888Z","end":"2026-02-18T02:49:14.587214Z","steps":["trace[524031372] 'read index received' (duration: 72.428098ms)","trace[524031372] 'applied index is now lower than readState.Index' (duration: 61.895956ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:49:14.58739Z","caller":"traceutil/trace.go:171","msg":"trace[1337980442] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"134.527269ms","start":"2026-02-18T02:49:14.452857Z","end":"2026-02-18T02:49:14.587384Z","steps":["trace[1337980442] 'process raft request' (duration: 134.198641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:14.587486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.571709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:49:14.58758Z","caller":"traceutil/trace.go:171","msg":"trace[75332879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1826; }","duration":"134.681772ms","start":"2026-02-18T02:49:14.452878Z","end":"2026-02-18T02:49:14.58756Z","steps":["trace[75332879] 'agreement among raft nodes before linearized reading' (duration: 134.55087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:15.037079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.664572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:49:15.037608Z","caller":"traceutil/trace.go:171","msg":"trace[637038108] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"366.594641ms","start":"2026-02-18T02:49:14.670999Z","end":"2026-02-18T02:49:15.037594Z","steps":["trace[637038108] 'process raft request' (duration: 207.343486ms)","trace[637038108] 'compare' (duration: 158.456627ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:49:15.037943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:49:14.670986Z","time spent":"366.884167ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:49:15.037548Z","caller":"traceutil/trace.go:171","msg":"trace[11150714] linearizableReadLoop","detail":"{readStateIndex:1911; appliedIndex:1910; }","duration":"342.700143ms","start":"2026-02-18T02:49:14.694828Z","end":"2026-02-18T02:49:15.037528Z","steps":["trace[11150714] 'read index received' (duration: 183.441368ms)","trace[11150714] 'applied index is now lower than readState.Index' (duration: 159.257125ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:49:15.038969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.140224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-18T02:49:15.039441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.606136ms","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:526"} {"level":"info","ts":"2026-02-18T02:49:15.03951Z","caller":"traceutil/trace.go:171","msg":"trace[1319617068] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1828; }","duration":"122.707609ms","start":"2026-02-18T02:49:14.916785Z","end":"2026-02-18T02:49:15.039492Z","steps":["trace[1319617068] 'agreement among raft nodes before linearized reading' (duration: 122.469774ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:15.039452Z","caller":"traceutil/trace.go:171","msg":"trace[360765416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1828; }","duration":"344.315109ms","start":"2026-02-18T02:49:14.694815Z","end":"2026-02-18T02:49:15.039131Z","steps":["trace[360765416] 'agreement among raft nodes before linearized reading' (duration: 344.052602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:15.040091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:49:14.694746Z","time spent":"345.332711ms","remote":"127.0.0.1:34612","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-18T02:49:29.634109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.179524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pod-garbage-collector\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-02-18T02:49:29.634332Z","caller":"traceutil/trace.go:171","msg":"trace[577433615] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pod-garbage-collector; range_end:; response_count:1; response_revision:1893; }","duration":"121.41748ms","start":"2026-02-18T02:49:29.512903Z","end":"2026-02-18T02:49:29.634321Z","steps":["trace[577433615] 'range keys from in-memory index tree' (duration: 121.138993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:29.706705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.722438ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T02:49:29.766423Z","caller":"traceutil/trace.go:171","msg":"trace[1214814547] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"125.699215ms","start":"2026-02-18T02:49:29.6407Z","end":"2026-02-18T02:49:29.766399Z","steps":["trace[1214814547] 'process raft request' (duration: 125.567222ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:49:30.797648Z","caller":"traceutil/trace.go:171","msg":"trace[716463399] linearizableReadLoop","detail":"{readStateIndex:1984; appliedIndex:1983; }","duration":"101.318305ms","start":"2026-02-18T02:49:30.696316Z","end":"2026-02-18T02:49:30.797635Z","steps":["trace[716463399] 'read index received' (duration: 101.243314ms)","trace[716463399] 'applied index is now lower than readState.Index' (duration: 74.511µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:49:30.797819Z","caller":"traceutil/trace.go:171","msg":"trace[907251822] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"121.8754ms","start":"2026-02-18T02:49:30.675932Z","end":"2026-02-18T02:49:30.797808Z","steps":["trace[907251822] 'process raft request' (duration: 121.632325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:49:30.7979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.569861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:resourcequota-controller\" ","response":"range_response_count:1 size:771"} {"level":"info","ts":"2026-02-18T02:49:30.797964Z","caller":"traceutil/trace.go:171","msg":"trace[1375568541] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:resourcequota-controller; range_end:; response_count:1; response_revision:1898; }","duration":"101.662603ms","start":"2026-02-18T02:49:30.696289Z","end":"2026-02-18T02:49:30.797952Z","steps":["trace[1375568541] 'agreement among raft nodes before linearized reading' (duration: 101.533641ms)"],"step_count":1}