{"level":"warn","ts":"2026-03-03T17:41:24.386313Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-03T17:41:24.386401Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235: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-03-03T17:41:24.386487Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-03T17:41:24.386503Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-03-03T17:41:24.386529Z","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-03-03T17:41:24.387186Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-03-03T17:41:24.38727Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235: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-03-03T17:41:24.410196Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"22.692158ms"} {"level":"info","ts":"2026-03-03T17:41:24.419683Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-03-03T17:41:24.420559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-03-03T17:41:24.420608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-03-03T17:41:24.420639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-03T17:41:24.420665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-03-03T17:41:24.420723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-03-03T17:41:24.436356Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-03T17:41:24.439188Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-03T17:41:24.440643Z","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-03-03T17:41:24.443552Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-03T17:41:24.444044Z","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-03-03T17:41:24.444096Z","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-03-03T17:41:24.444107Z","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-03-03T17:41:24.444159Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-03T17:41:24.444811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-03-03T17:41:24.44498Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-03-03T17:41:24.447791Z","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-03-03T17:41:24.44787Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-03-03T17:41:24.447897Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-03-03T17:41:24.448783Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-03T17:41:24.448828Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-03T17:41:24.521577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-03-03T17:41:24.521634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-03T17:41:24.521696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-03-03T17:41:24.521729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-03-03T17:41:24.521745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-03-03T17:41:24.521766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-03-03T17:41:24.52178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-03-03T17:41:24.589162Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T17:41:24.590464Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-03-03T17:41:24.590631Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T17:41:24.590805Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T17:41:24.592126Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-03T17:41:24.592168Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-03T17:41:24.593713Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T17:41:24.593916Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T17:41:24.593961Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T17:41:24.595272Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-03-03T17:41:24.598075Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-03T17:41:26.399455Z","caller":"traceutil/trace.go:171","msg":"trace[1549506638] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"204.612853ms","start":"2026-03-03T17:41:26.194802Z","end":"2026-03-03T17:41:26.399415Z","steps":["trace[1549506638] 'process raft request' (duration: 116.860954ms)","trace[1549506638] 'compare' (duration: 87.486547ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:41:26.399484Z","caller":"traceutil/trace.go:171","msg":"trace[1816982749] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"204.588283ms","start":"2026-03-03T17:41:26.194877Z","end":"2026-03-03T17:41:26.399465Z","steps":["trace[1816982749] 'process raft request' (duration: 204.410361ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:26.399689Z","caller":"traceutil/trace.go:171","msg":"trace[1719716475] linearizableReadLoop","detail":"{readStateIndex:11; appliedIndex:6; }","duration":"202.624465ms","start":"2026-03-03T17:41:26.197031Z","end":"2026-03-03T17:41:26.399656Z","steps":["trace[1719716475] 'read index received' (duration: 114.645514ms)","trace[1719716475] 'applied index is now lower than readState.Index' (duration: 87.977791ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:41:26.399769Z","caller":"traceutil/trace.go:171","msg":"trace[1327578286] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"204.657553ms","start":"2026-03-03T17:41:26.195094Z","end":"2026-03-03T17:41:26.399752Z","steps":["trace[1327578286] 'process raft request' (duration: 204.23797ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:26.399833Z","caller":"traceutil/trace.go:171","msg":"trace[795532903] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"191.826903ms","start":"2026-03-03T17:41:26.20799Z","end":"2026-03-03T17:41:26.399817Z","steps":["trace[795532903] 'process raft request' (duration: 191.63881ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:26.399851Z","caller":"traceutil/trace.go:171","msg":"trace[1775872903] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"204.694685ms","start":"2026-03-03T17:41:26.195149Z","end":"2026-03-03T17:41:26.399844Z","steps":["trace[1775872903] 'process raft request' (duration: 204.24539ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:26.39989Z","caller":"traceutil/trace.go:171","msg":"trace[866958971] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"193.055984ms","start":"2026-03-03T17:41:26.206811Z","end":"2026-03-03T17:41:26.399867Z","steps":["trace[866958971] 'process raft request' (duration: 192.777721ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:26.399929Z","caller":"traceutil/trace.go:171","msg":"trace[706196614] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"204.416602ms","start":"2026-03-03T17:41:26.195505Z","end":"2026-03-03T17:41:26.399921Z","steps":["trace[706196614] 'process raft request' (duration: 203.990587ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:26.399867Z","caller":"traceutil/trace.go:171","msg":"trace[753850399] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"202.751236ms","start":"2026-03-03T17:41:26.197037Z","end":"2026-03-03T17:41:26.399789Z","steps":["trace[753850399] 'process raft request' (duration: 202.513914ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:41:26.400123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.570882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T17:41:26.400098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.304035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-03T17:41:26.400074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.823037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T17:41:26.400211Z","caller":"traceutil/trace.go:171","msg":"trace[1139031700] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"202.996858ms","start":"2026-03-03T17:41:26.197198Z","end":"2026-03-03T17:41:26.400195Z","steps":["trace[1139031700] 'agreement among raft nodes before linearized reading' (duration: 202.659245ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:26.400172Z","caller":"traceutil/trace.go:171","msg":"trace[1768274442] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"152.675333ms","start":"2026-03-03T17:41:26.247484Z","end":"2026-03-03T17:41:26.40016Z","steps":["trace[1768274442] 'agreement among raft nodes before linearized reading' (duration: 152.567952ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:41:26.400236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.196891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-03-03T17:41:26.400277Z","caller":"traceutil/trace.go:171","msg":"trace[1317276811] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"203.239271ms","start":"2026-03-03T17:41:26.197026Z","end":"2026-03-03T17:41:26.400266Z","steps":["trace[1317276811] 'agreement among raft nodes before linearized reading' (duration: 203.16291ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:26.400209Z","caller":"traceutil/trace.go:171","msg":"trace[1710840956] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"140.439747ms","start":"2026-03-03T17:41:26.259747Z","end":"2026-03-03T17:41:26.400187Z","steps":["trace[1710840956] 'agreement among raft nodes before linearized reading' (duration: 140.212455ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:41:26.400404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.315156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T17:41:26.400649Z","caller":"traceutil/trace.go:171","msg":"trace[914472101] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"176.593318ms","start":"2026-03-03T17:41:26.224025Z","end":"2026-03-03T17:41:26.400618Z","steps":["trace[914472101] 'agreement among raft nodes before linearized reading' (duration: 176.039283ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:41:26.643247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.71876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-03T17:41:26.64335Z","caller":"traceutil/trace.go:171","msg":"trace[321132828] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"238.371102ms","start":"2026-03-03T17:41:26.404961Z","end":"2026-03-03T17:41:26.643333Z","steps":["trace[321132828] 'process raft request' (duration: 135.102687ms)","trace[321132828] 'compare' (duration: 102.591429ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:41:26.645917Z","caller":"traceutil/trace.go:171","msg":"trace[842967369] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"239.489742ms","start":"2026-03-03T17:41:26.406409Z","end":"2026-03-03T17:41:26.645899Z","steps":["trace[842967369] 'process raft request' (duration: 239.391752ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:26.645936Z","caller":"traceutil/trace.go:171","msg":"trace[511547901] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"240.055338ms","start":"2026-03-03T17:41:26.405861Z","end":"2026-03-03T17:41:26.645916Z","steps":["trace[511547901] 'process raft request' (duration: 239.821645ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:26.646025Z","caller":"traceutil/trace.go:171","msg":"trace[1947720633] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"239.362261ms","start":"2026-03-03T17:41:26.406654Z","end":"2026-03-03T17:41:26.646016Z","steps":["trace[1947720633] 'process raft request' (duration: 239.21952ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:26.646025Z","caller":"traceutil/trace.go:171","msg":"trace[1011663109] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"240.907026ms","start":"2026-03-03T17:41:26.405108Z","end":"2026-03-03T17:41:26.646015Z","steps":["trace[1011663109] 'process raft request' (duration: 240.788415ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:26.646019Z","caller":"traceutil/trace.go:171","msg":"trace[378401038] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"239.504762ms","start":"2026-03-03T17:41:26.406479Z","end":"2026-03-03T17:41:26.645984Z","steps":["trace[378401038] 'process raft request' (duration: 239.346341ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:26.646081Z","caller":"traceutil/trace.go:171","msg":"trace[735442979] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"238.944147ms","start":"2026-03-03T17:41:26.40713Z","end":"2026-03-03T17:41:26.646075Z","steps":["trace[735442979] 'process raft request' (duration: 238.800366ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:26.646193Z","caller":"traceutil/trace.go:171","msg":"trace[411418767] transaction","detail":"{read_only:false; number_of_response:0; response_revision:18; }","duration":"238.849546ms","start":"2026-03-03T17:41:26.407329Z","end":"2026-03-03T17:41:26.646179Z","steps":["trace[411418767] 'process raft request' (duration: 238.688325ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:26.646292Z","caller":"traceutil/trace.go:171","msg":"trace[1249590680] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"237.15555ms","start":"2026-03-03T17:41:26.409126Z","end":"2026-03-03T17:41:26.646282Z","steps":["trace[1249590680] 'process raft request' (duration: 236.924708ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:26.646305Z","caller":"traceutil/trace.go:171","msg":"trace[117727851] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"236.240482ms","start":"2026-03-03T17:41:26.410052Z","end":"2026-03-03T17:41:26.646293Z","steps":["trace[117727851] 'process raft request' (duration: 236.097111ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:26.646277Z","caller":"traceutil/trace.go:171","msg":"trace[410624588] linearizableReadLoop","detail":"{readStateIndex:19; appliedIndex:14; }","duration":"239.563413ms","start":"2026-03-03T17:41:26.40669Z","end":"2026-03-03T17:41:26.646254Z","steps":["trace[410624588] 'read index received' (duration: 133.31724ms)","trace[410624588] 'applied index is now lower than readState.Index' (duration: 106.244933ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:41:26.646425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.715455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-l7lhp\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-03-03T17:41:26.646452Z","caller":"traceutil/trace.go:171","msg":"trace[1224348470] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-l7lhp; range_end:; response_count:1; response_revision:20; }","duration":"239.775285ms","start":"2026-03-03T17:41:26.40667Z","end":"2026-03-03T17:41:26.646445Z","steps":["trace[1224348470] 'agreement among raft nodes before linearized reading' (duration: 239.703235ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:41:26.653169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.677089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-03-03T17:41:26.653193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.785022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-03-03T17:41:26.653209Z","caller":"traceutil/trace.go:171","msg":"trace[2113955093] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:21; }","duration":"195.72919ms","start":"2026-03-03T17:41:26.45747Z","end":"2026-03-03T17:41:26.653199Z","steps":["trace[2113955093] 'agreement among raft nodes before linearized reading' (duration: 195.574998ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:41:26.653192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.370196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T17:41:26.653265Z","caller":"traceutil/trace.go:171","msg":"trace[1825420874] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:21; }","duration":"190.863243ms","start":"2026-03-03T17:41:26.462365Z","end":"2026-03-03T17:41:26.653228Z","steps":["trace[1825420874] 'agreement among raft nodes before linearized reading' (duration: 190.722822ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:26.653276Z","caller":"traceutil/trace.go:171","msg":"trace[1986808622] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:21; }","duration":"195.528157ms","start":"2026-03-03T17:41:26.457722Z","end":"2026-03-03T17:41:26.653251Z","steps":["trace[1986808622] 'agreement among raft nodes before linearized reading' (duration: 195.321155ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:28.438043Z","caller":"traceutil/trace.go:171","msg":"trace[365405756] linearizableReadLoop","detail":"{readStateIndex:194; appliedIndex:193; }","duration":"239.845646ms","start":"2026-03-03T17:41:28.198169Z","end":"2026-03-03T17:41:28.438015Z","steps":["trace[365405756] 'read index received' (duration: 148.352547ms)","trace[365405756] 'applied index is now lower than readState.Index' (duration: 91.492149ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:41:28.438094Z","caller":"traceutil/trace.go:171","msg":"trace[122610223] transaction","detail":"{read_only:false; response_revision:189; number_of_response:1; }","duration":"286.61489ms","start":"2026-03-03T17:41:28.151453Z","end":"2026-03-03T17:41:28.438068Z","steps":["trace[122610223] 'process raft request' (duration: 195.154431ms)","trace[122610223] 'compare' (duration: 91.271597ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:41:28.438278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.097959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:41:28.43862Z","caller":"traceutil/trace.go:171","msg":"trace[1679212559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:189; }","duration":"240.496793ms","start":"2026-03-03T17:41:28.198108Z","end":"2026-03-03T17:41:28.438605Z","steps":["trace[1679212559] 'agreement among raft nodes before linearized reading' (duration: 240.081599ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:30.399288Z","caller":"traceutil/trace.go:171","msg":"trace[26750582] linearizableReadLoop","detail":"{readStateIndex:281; appliedIndex:280; }","duration":"105.056258ms","start":"2026-03-03T17:41:30.294207Z","end":"2026-03-03T17:41:30.399264Z","steps":["trace[26750582] 'read index received' (duration: 104.894846ms)","trace[26750582] 'applied index is now lower than readState.Index' (duration: 160.472µs)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:41:30.399738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.32702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-03T17:41:30.399818Z","caller":"traceutil/trace.go:171","msg":"trace[1221478652] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:275; }","duration":"105.636284ms","start":"2026-03-03T17:41:30.29416Z","end":"2026-03-03T17:41:30.399796Z","steps":["trace[1221478652] 'agreement among raft nodes before linearized reading' (duration: 105.22643ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:30.399356Z","caller":"traceutil/trace.go:171","msg":"trace[1665878145] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"151.418601ms","start":"2026-03-03T17:41:30.247915Z","end":"2026-03-03T17:41:30.399334Z","steps":["trace[1665878145] 'process raft request' (duration: 151.23227ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:32.389694Z","caller":"traceutil/trace.go:171","msg":"trace[1414561023] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:340; }","duration":"193.968681ms","start":"2026-03-03T17:41:32.195678Z","end":"2026-03-03T17:41:32.389647Z","steps":["trace[1414561023] 'read index received' (duration: 94.830601ms)","trace[1414561023] 'applied index is now lower than readState.Index' (duration: 99.13728ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:41:32.389874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.175823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-03-03T17:41:32.389911Z","caller":"traceutil/trace.go:171","msg":"trace[1781745722] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:327; }","duration":"194.269854ms","start":"2026-03-03T17:41:32.19563Z","end":"2026-03-03T17:41:32.389899Z","steps":["trace[1781745722] 'agreement among raft nodes before linearized reading' (duration: 194.154233ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:32.390281Z","caller":"traceutil/trace.go:171","msg":"trace[708576140] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"221.196005ms","start":"2026-03-03T17:41:32.169073Z","end":"2026-03-03T17:41:32.390269Z","steps":["trace[708576140] 'process raft request' (duration: 121.47108ms)","trace[708576140] 'compare' (duration: 98.930539ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:41:49.785844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.970276ms","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-03-03T17:41:49.78591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.6068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-03T17:41:49.786036Z","caller":"traceutil/trace.go:171","msg":"trace[1439458003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:461; }","duration":"368.798821ms","start":"2026-03-03T17:41:49.417218Z","end":"2026-03-03T17:41:49.786017Z","steps":["trace[1439458003] 'range keys from in-memory index tree' (duration: 368.419068ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:49.785957Z","caller":"traceutil/trace.go:171","msg":"trace[132511532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:461; }","duration":"367.174088ms","start":"2026-03-03T17:41:49.418756Z","end":"2026-03-03T17:41:49.78593Z","steps":["trace[132511532] 'range keys from in-memory index tree' (duration: 366.803975ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:41:49.786089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:41:49.417198Z","time spent":"368.874423ms","remote":"127.0.0.1:38446","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-03-03T17:41:49.786171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:41:49.418725Z","time spent":"367.42663ms","remote":"127.0.0.1:38446","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":"info","ts":"2026-03-03T17:41:50.016457Z","caller":"traceutil/trace.go:171","msg":"trace[1539761375] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:480; }","duration":"221.969502ms","start":"2026-03-03T17:41:49.794468Z","end":"2026-03-03T17:41:50.016438Z","steps":["trace[1539761375] 'read index received' (duration: 221.774841ms)","trace[1539761375] 'applied index is now lower than readState.Index' (duration: 193.541µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:41:50.016478Z","caller":"traceutil/trace.go:171","msg":"trace[1677876727] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"226.256047ms","start":"2026-03-03T17:41:49.790187Z","end":"2026-03-03T17:41:50.016443Z","steps":["trace[1677876727] 'process raft request' (duration: 226.100136ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:50.016492Z","caller":"traceutil/trace.go:171","msg":"trace[680606548] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"226.265636ms","start":"2026-03-03T17:41:49.790205Z","end":"2026-03-03T17:41:50.01647Z","steps":["trace[680606548] 'process raft request' (duration: 226.197546ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:41:50.016561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.081113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-03T17:41:50.01659Z","caller":"traceutil/trace.go:171","msg":"trace[581203593] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:463; }","duration":"222.157503ms","start":"2026-03-03T17:41:49.794424Z","end":"2026-03-03T17:41:50.016581Z","steps":["trace[581203593] 'agreement among raft nodes before linearized reading' (duration: 222.089053ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:41:50.326433Z","caller":"traceutil/trace.go:171","msg":"trace[591093271] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"305.756263ms","start":"2026-03-03T17:41:50.020628Z","end":"2026-03-03T17:41:50.326384Z","steps":["trace[591093271] 'process raft request' (duration: 283.960739ms)","trace[591093271] 'compare' (duration: 21.661203ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:41:50.326862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:41:50.020612Z","time spent":"305.903034ms","remote":"127.0.0.1:38446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:41:54.823175Z","caller":"traceutil/trace.go:171","msg":"trace[1247485372] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"139.496653ms","start":"2026-03-03T17:41:54.683655Z","end":"2026-03-03T17:41:54.823152Z","steps":["trace[1247485372] 'process raft request' (duration: 139.190351ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:41:56.053882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.924404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-03-03T17:41:56.053984Z","caller":"traceutil/trace.go:171","msg":"trace[308518435] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:508; }","duration":"492.056205ms","start":"2026-03-03T17:41:55.561905Z","end":"2026-03-03T17:41:56.053961Z","steps":["trace[308518435] 'range keys from in-memory index tree' (duration: 491.831683ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:41:56.054036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:41:55.561889Z","time spent":"492.130456ms","remote":"127.0.0.1:38358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4396,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-03T17:41:56.718757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.221962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T17:41:56.718947Z","caller":"traceutil/trace.go:171","msg":"trace[121652134] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:529; }","duration":"360.39504ms","start":"2026-03-03T17:41:56.358533Z","end":"2026-03-03T17:41:56.718928Z","steps":["trace[121652134] 'read index received' (duration: 32.93µs)","trace[121652134] 'applied index is now lower than readState.Index' (duration: 360.36122ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:41:56.719Z","caller":"traceutil/trace.go:171","msg":"trace[1918852771] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"661.712713ms","start":"2026-03-03T17:41:56.057246Z","end":"2026-03-03T17:41:56.718959Z","steps":["trace[1918852771] 'process raft request' (duration: 186.126057ms)","trace[1918852771] 'compare' (duration: 475.131702ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:41:56.71918Z","caller":"traceutil/trace.go:171","msg":"trace[205591292] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"656.844075ms","start":"2026-03-03T17:41:56.062324Z","end":"2026-03-03T17:41:56.719168Z","steps":["trace[205591292] 'process raft request' (duration: 656.544812ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:41:56.719252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:41:56.062306Z","time spent":"656.912175ms","remote":"127.0.0.1:38358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4567,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T17:41:56.719349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.800933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-03T17:41:56.719396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:41:56.057205Z","time spent":"661.945444ms","remote":"127.0.0.1:38446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:41:56.719441Z","caller":"traceutil/trace.go:171","msg":"trace[125254236] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:511; }","duration":"360.926584ms","start":"2026-03-03T17:41:56.358495Z","end":"2026-03-03T17:41:56.719421Z","steps":["trace[125254236] 'agreement among raft nodes before linearized reading' (duration: 360.759493ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:41:56.719404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.262674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T17:41:56.719492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:41:56.358479Z","time spent":"361.004515ms","remote":"127.0.0.1:38446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-03T17:41:56.719512Z","caller":"traceutil/trace.go:171","msg":"trace[1758850233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"120.445986ms","start":"2026-03-03T17:41:56.599041Z","end":"2026-03-03T17:41:56.719487Z","steps":["trace[1758850233] 'agreement among raft nodes before linearized reading' (duration: 120.228854ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:41:57.406551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.899043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T17:41:57.406681Z","caller":"traceutil/trace.go:171","msg":"trace[1246520911] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"302.227957ms","start":"2026-03-03T17:41:57.104437Z","end":"2026-03-03T17:41:57.406665Z","steps":["trace[1246520911] 'process raft request' (duration: 302.184857ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:41:57.40672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:41:57.104402Z","time spent":"302.300579ms","remote":"127.0.0.1:38446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:41:57.40689Z","caller":"traceutil/trace.go:171","msg":"trace[1656731313] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"683.019982ms","start":"2026-03-03T17:41:56.723863Z","end":"2026-03-03T17:41:57.406883Z","steps":["trace[1656731313] 'process raft request' (duration: 335.734496ms)","trace[1656731313] 'compare' (duration: 346.49393ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:41:57.406916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:41:56.723735Z","time spent":"683.167993ms","remote":"127.0.0.1:38446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:41:57.406969Z","caller":"traceutil/trace.go:171","msg":"trace[20201911] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"579.634289ms","start":"2026-03-03T17:41:56.82733Z","end":"2026-03-03T17:41:57.406964Z","steps":["trace[20201911] 'read index received' (duration: 232.245752ms)","trace[20201911] 'applied index is now lower than readState.Index' (duration: 347.387907ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:41:57.406997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.676069ms","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-03-03T17:41:57.407008Z","caller":"traceutil/trace.go:171","msg":"trace[1830799824] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:513; }","duration":"579.725499ms","start":"2026-03-03T17:41:56.827278Z","end":"2026-03-03T17:41:57.407004Z","steps":["trace[1830799824] 'agreement among raft nodes before linearized reading' (duration: 579.700349ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:41:57.40702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:41:56.827254Z","time spent":"579.76321ms","remote":"127.0.0.1:38446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-03T17:42:01.043087Z","caller":"traceutil/trace.go:171","msg":"trace[1763510289] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"309.04344ms","start":"2026-03-03T17:42:00.734027Z","end":"2026-03-03T17:42:01.043071Z","steps":["trace[1763510289] 'process raft request' (duration: 308.960009ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:42:01.043183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:42:00.734001Z","time spent":"309.13414ms","remote":"127.0.0.1:38446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T17:42:03.922098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.687844ms","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-03-03T17:42:03.922221Z","caller":"traceutil/trace.go:171","msg":"trace[2041833509] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:551; }","duration":"471.857449ms","start":"2026-03-03T17:42:03.450346Z","end":"2026-03-03T17:42:03.922203Z","steps":["trace[2041833509] 'range keys from in-memory index tree' (duration: 471.532448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:42:03.922234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.128552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-03T17:42:03.922273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:42:03.450322Z","time spent":"471.941362ms","remote":"127.0.0.1:38446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-03T17:42:03.92232Z","caller":"traceutil/trace.go:171","msg":"trace[373261735] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:551; }","duration":"479.262876ms","start":"2026-03-03T17:42:03.443032Z","end":"2026-03-03T17:42:03.922295Z","steps":["trace[373261735] 'range keys from in-memory index tree' (duration: 478.910184ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:42:03.922372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:42:03.443011Z","time spent":"479.35138ms","remote":"127.0.0.1:38446","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-03-03T17:42:03.922421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.581724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:42:03.922515Z","caller":"traceutil/trace.go:171","msg":"trace[2069159636] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"319.950177ms","start":"2026-03-03T17:42:03.602537Z","end":"2026-03-03T17:42:03.922487Z","steps":["trace[2069159636] 'range keys from in-memory index tree' (duration: 319.504241ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:42:03.922637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:42:03.60252Z","time spent":"320.099112ms","remote":"127.0.0.1:38190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-03T17:42:04.161334Z","caller":"traceutil/trace.go:171","msg":"trace[1334827625] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"232.684319ms","start":"2026-03-03T17:42:03.928615Z","end":"2026-03-03T17:42:04.161299Z","steps":["trace[1334827625] 'process raft request' (duration: 160.507489ms)","trace[1334827625] 'compare' (duration: 71.986174ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:42:04.161223Z","caller":"traceutil/trace.go:171","msg":"trace[2077749924] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:572; }","duration":"192.308904ms","start":"2026-03-03T17:42:03.968892Z","end":"2026-03-03T17:42:04.161201Z","steps":["trace[2077749924] 'read index received' (duration: 10.248418ms)","trace[2077749924] 'applied index is now lower than readState.Index' (duration: 182.059786ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:42:04.16195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.036471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wz9qp\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-03T17:42:04.161987Z","caller":"traceutil/trace.go:171","msg":"trace[1377536060] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wz9qp; range_end:; response_count:1; response_revision:553; }","duration":"193.129094ms","start":"2026-03-03T17:42:03.968849Z","end":"2026-03-03T17:42:04.161979Z","steps":["trace[1377536060] 'agreement among raft nodes before linearized reading' (duration: 192.549593ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:42:04.38223Z","caller":"traceutil/trace.go:171","msg":"trace[1416108720] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"205.24532ms","start":"2026-03-03T17:42:04.176964Z","end":"2026-03-03T17:42:04.382209Z","steps":["trace[1416108720] 'process raft request' (duration: 188.39025ms)","trace[1416108720] 'compare' (duration: 16.777537ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:42:05.428658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.958393ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-03-03T17:42:05.428769Z","caller":"traceutil/trace.go:171","msg":"trace[1703552701] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:576; }","duration":"375.219675ms","start":"2026-03-03T17:42:05.05353Z","end":"2026-03-03T17:42:05.428749Z","steps":["trace[1703552701] 'read index received' (duration: 265.20134ms)","trace[1703552701] 'applied index is now lower than readState.Index' (duration: 110.016715ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:42:05.428964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.418083ms","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-03-03T17:42:05.429027Z","caller":"traceutil/trace.go:171","msg":"trace[1733075423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"375.511165ms","start":"2026-03-03T17:42:05.053498Z","end":"2026-03-03T17:42:05.429009Z","steps":["trace[1733075423] 'agreement among raft nodes before linearized reading' (duration: 375.304308ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:42:05.429077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:42:05.053482Z","time spent":"375.577368ms","remote":"127.0.0.1:38446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T17:42:08.605094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.007063638s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:42:08.605147Z","caller":"traceutil/trace.go:171","msg":"trace[1508432696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"3.007168382s","start":"2026-03-03T17:42:05.597966Z","end":"2026-03-03T17:42:08.605135Z","steps":["trace[1508432696] 'range keys from in-memory index tree' (duration: 3.006946703s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:42:08.605141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.133975805s","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-03-03T17:42:08.605168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:42:05.597942Z","time spent":"3.007221603s","remote":"127.0.0.1:38190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-03T17:42:08.605177Z","caller":"traceutil/trace.go:171","msg":"trace[924987197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"1.134041217s","start":"2026-03-03T17:42:07.471122Z","end":"2026-03-03T17:42:08.605163Z","steps":["trace[924987197] 'range keys from in-memory index tree' (duration: 1.133900332s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:42:08.605206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:42:07.471103Z","time spent":"1.134097328s","remote":"127.0.0.1:38446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T17:42:08.605292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.622915228s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-03T17:42:08.605306Z","caller":"traceutil/trace.go:171","msg":"trace[1317657736] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:556; }","duration":"2.622963629s","start":"2026-03-03T17:42:05.982338Z","end":"2026-03-03T17:42:08.605302Z","steps":["trace[1317657736] 'range keys from in-memory index tree' (duration: 2.622838654s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:42:08.605318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:42:05.982316Z","time spent":"2.622998931s","remote":"127.0.0.1:38446","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-03-03T17:42:08.605545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006931649s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:42:08.605608Z","caller":"traceutil/trace.go:171","msg":"trace[2141121122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"1.007029832s","start":"2026-03-03T17:42:07.598572Z","end":"2026-03-03T17:42:08.605601Z","steps":["trace[2141121122] 'range keys from in-memory index tree' (duration: 1.006873567s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:42:08.605628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:42:07.598552Z","time spent":"1.007070754s","remote":"127.0.0.1:38198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T17:42:08.605702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.387638224s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-03T17:42:08.605723Z","caller":"traceutil/trace.go:171","msg":"trace[851960930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:556; }","duration":"2.387696346s","start":"2026-03-03T17:42:06.218022Z","end":"2026-03-03T17:42:08.605718Z","steps":["trace[851960930] 'range keys from in-memory index tree' (duration: 2.387523739s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:42:08.60569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:42:06.939998Z","time spent":"1.66568422s","remote":"127.0.0.1:34262","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-03T17:42:08.605739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:42:06.218002Z","time spent":"2.387732217s","remote":"127.0.0.1:38446","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":"warn","ts":"2026-03-03T17:42:08.605758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.440322008s","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-03-03T17:42:08.605952Z","caller":"traceutil/trace.go:171","msg":"trace[1712981617] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:556; }","duration":"2.440510954s","start":"2026-03-03T17:42:06.165405Z","end":"2026-03-03T17:42:08.605916Z","steps":["trace[1712981617] 'range keys from in-memory index tree' (duration: 2.440260435s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:42:08.606056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:42:06.165388Z","time spent":"2.440636459s","remote":"127.0.0.1:38446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-03T17:42:08.618408Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879340757665987,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T17:42:08.634718Z","caller":"traceutil/trace.go:171","msg":"trace[1630652131] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"823.18774ms","start":"2026-03-03T17:42:07.811515Z","end":"2026-03-03T17:42:08.634703Z","steps":["trace[1630652131] 'process raft request' (duration: 823.065876ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:42:08.635311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:42:07.81149Z","time spent":"823.270723ms","remote":"127.0.0.1:38446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:42:08.651926Z","caller":"traceutil/trace.go:171","msg":"trace[1183370620] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:578; }","duration":"534.369858ms","start":"2026-03-03T17:42:08.117515Z","end":"2026-03-03T17:42:08.651885Z","steps":["trace[1183370620] 'read index received' (duration: 517.121761ms)","trace[1183370620] 'applied index is now lower than readState.Index' (duration: 17.247357ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:42:08.652055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.533554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:42:08.652083Z","caller":"traceutil/trace.go:171","msg":"trace[225348314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:562; }","duration":"534.615717ms","start":"2026-03-03T17:42:08.117459Z","end":"2026-03-03T17:42:08.652075Z","steps":["trace[225348314] 'agreement among raft nodes before linearized reading' (duration: 534.557455ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:42:08.652109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:42:08.117438Z","time spent":"534.665379ms","remote":"127.0.0.1:38190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-03T17:42:09.103749Z","caller":"traceutil/trace.go:171","msg":"trace[1367195427] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"108.710355ms","start":"2026-03-03T17:42:08.995016Z","end":"2026-03-03T17:42:09.103727Z","steps":["trace[1367195427] 'process raft request' (duration: 108.496518ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:42:10.472154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.624165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:42:10.472254Z","caller":"traceutil/trace.go:171","msg":"trace[1890190815] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:587; }","duration":"282.792141ms","start":"2026-03-03T17:42:10.189442Z","end":"2026-03-03T17:42:10.472234Z","steps":["trace[1890190815] 'range keys from in-memory index tree' (duration: 282.47524ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:42:10.580487Z","caller":"traceutil/trace.go:171","msg":"trace[1193133107] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"187.889498ms","start":"2026-03-03T17:42:10.392577Z","end":"2026-03-03T17:42:10.580467Z","steps":["trace[1193133107] 'process raft request' (duration: 187.771815ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:42:20.228816Z","caller":"traceutil/trace.go:171","msg":"trace[1027153840] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"129.074011ms","start":"2026-03-03T17:42:20.099724Z","end":"2026-03-03T17:42:20.228798Z","steps":["trace[1027153840] 'process raft request' (duration: 30.710338ms)","trace[1027153840] 'compare' (duration: 98.291231ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:42:20.281785Z","caller":"traceutil/trace.go:171","msg":"trace[509121400] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"181.725816ms","start":"2026-03-03T17:42:20.100035Z","end":"2026-03-03T17:42:20.281761Z","steps":["trace[509121400] 'process raft request' (duration: 181.550621ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:42:20.281965Z","caller":"traceutil/trace.go:171","msg":"trace[584459090] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"176.48031ms","start":"2026-03-03T17:42:20.105458Z","end":"2026-03-03T17:42:20.281938Z","steps":["trace[584459090] 'process raft request' (duration: 176.253403ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:43:29.199512Z","caller":"traceutil/trace.go:171","msg":"trace[570373456] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1008; }","duration":"116.389106ms","start":"2026-03-03T17:43:29.083097Z","end":"2026-03-03T17:43:29.199486Z","steps":["trace[570373456] 'read index received' (duration: 116.132222ms)","trace[570373456] 'applied index is now lower than readState.Index' (duration: 255.604µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:43:29.199675Z","caller":"traceutil/trace.go:171","msg":"trace[2136981090] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"117.503696ms","start":"2026-03-03T17:43:29.082157Z","end":"2026-03-03T17:43:29.199661Z","steps":["trace[2136981090] 'process raft request' (duration: 117.16048ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:43:29.199867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.17895ms","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-03-03T17:43:29.199952Z","caller":"traceutil/trace.go:171","msg":"trace[1889975107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:967; }","duration":"111.282391ms","start":"2026-03-03T17:43:29.088638Z","end":"2026-03-03T17:43:29.19992Z","steps":["trace[1889975107] 'agreement among raft nodes before linearized reading' (duration: 111.099778ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:43:29.200129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.913591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-03T17:43:29.200163Z","caller":"traceutil/trace.go:171","msg":"trace[1786507353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:967; }","duration":"108.966072ms","start":"2026-03-03T17:43:29.091189Z","end":"2026-03-03T17:43:29.200155Z","steps":["trace[1786507353] 'agreement among raft nodes before linearized reading' (duration: 108.884621ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:43:29.200471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.254991ms","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-03-03T17:43:29.200554Z","caller":"traceutil/trace.go:171","msg":"trace[28184582] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:967; }","duration":"117.459575ms","start":"2026-03-03T17:43:29.083073Z","end":"2026-03-03T17:43:29.200532Z","steps":["trace[28184582] 'agreement among raft nodes before linearized reading' (duration: 117.103848ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:43:37.41254Z","caller":"traceutil/trace.go:171","msg":"trace[1059713509] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"171.187477ms","start":"2026-03-03T17:43:37.241333Z","end":"2026-03-03T17:43:37.412521Z","steps":["trace[1059713509] 'read index received' (duration: 171.037575ms)","trace[1059713509] 'applied index is now lower than readState.Index' (duration: 149.302µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:43:37.412663Z","caller":"traceutil/trace.go:171","msg":"trace[509401591] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"172.662432ms","start":"2026-03-03T17:43:37.239966Z","end":"2026-03-03T17:43:37.412628Z","steps":["trace[509401591] 'process raft request' (duration: 172.444718ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:43:37.412748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.39109ms","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-03-03T17:43:37.412775Z","caller":"traceutil/trace.go:171","msg":"trace[954214842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:997; }","duration":"171.464932ms","start":"2026-03-03T17:43:37.241301Z","end":"2026-03-03T17:43:37.412766Z","steps":["trace[954214842] 'agreement among raft nodes before linearized reading' (duration: 171.314449ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:43:37.412919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.488154ms","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-03-03T17:43:37.413007Z","caller":"traceutil/trace.go:171","msg":"trace[1615931921] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:997; }","duration":"168.619306ms","start":"2026-03-03T17:43:37.244369Z","end":"2026-03-03T17:43:37.412988Z","steps":["trace[1615931921] 'agreement among raft nodes before linearized reading' (duration: 168.370392ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:43:37.413374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.967938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-03T17:43:37.413473Z","caller":"traceutil/trace.go:171","msg":"trace[529366118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:997; }","duration":"170.100389ms","start":"2026-03-03T17:43:37.243356Z","end":"2026-03-03T17:43:37.413457Z","steps":["trace[529366118] 'agreement among raft nodes before linearized reading' (duration: 169.342228ms)"],"step_count":1}