{"level":"warn","ts":"2026-02-25T02:07:22.20427Z","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-25T02:07:22.204366Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.244: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.244:2380","--initial-cluster=instance=https://199.204.45.244:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.244:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.244: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-25T02:07:22.204439Z","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-25T02:07:22.204456Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-02-25T02:07:22.204476Z","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-25T02:07:22.205239Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"]} {"level":"info","ts":"2026-02-25T02:07:22.205338Z","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":16,"max-cpu-available":16,"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.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.244: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-25T02:07:22.209041Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.453282ms"} {"level":"info","ts":"2026-02-25T02:07:22.215461Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1686e9b1ea717edf","cluster-id":"71c60fe001ca5b3c"} {"level":"info","ts":"2026-02-25T02:07:22.215533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T02:07:22.215553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 0"} {"level":"info","ts":"2026-02-25T02:07:22.215565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1686e9b1ea717edf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T02:07:22.215573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 1"} {"level":"info","ts":"2026-02-25T02:07:22.215606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"warn","ts":"2026-02-25T02:07:22.221853Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T02:07:22.22326Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T02:07:22.224273Z","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-25T02:07:22.226424Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1686e9b1ea717edf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T02:07:22.226594Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1686e9b1ea717edf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T02:07:22.226823Z","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-25T02:07:22.226956Z","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-25T02:07:22.226976Z","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-25T02:07:22.228511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"info","ts":"2026-02-25T02:07:22.229278Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","added-peer-id":"1686e9b1ea717edf","added-peer-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-02-25T02:07:22.23085Z","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-25T02:07:22.231003Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-02-25T02:07:22.231103Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-02-25T02:07:22.231173Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1686e9b1ea717edf","initial-advertise-peer-urls":["https://199.204.45.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T02:07:22.231223Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T02:07:22.516052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T02:07:22.516124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T02:07:22.516147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgPreVoteResp from 1686e9b1ea717edf at term 1"} {"level":"info","ts":"2026-02-25T02:07:22.516167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became candidate at term 2"} {"level":"info","ts":"2026-02-25T02:07:22.516179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgVoteResp from 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-02-25T02:07:22.516195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became leader at term 2"} {"level":"info","ts":"2026-02-25T02:07:22.51621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1686e9b1ea717edf elected leader 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-02-25T02:07:22.517294Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1686e9b1ea717edf","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.244:2379]}","request-path":"/0/members/1686e9b1ea717edf/attributes","cluster-id":"71c60fe001ca5b3c","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T02:07:22.517461Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:07:22.517515Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:07:22.517655Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:07:22.517954Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T02:07:22.518028Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T02:07:22.518643Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:07:22.518737Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:07:22.518771Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:07:22.520083Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T02:07:22.520722Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.244:2379"} {"level":"info","ts":"2026-02-25T02:07:24.244036Z","caller":"traceutil/trace.go:171","msg":"trace[939617587] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"168.793489ms","start":"2026-02-25T02:07:24.075224Z","end":"2026-02-25T02:07:24.244018Z","steps":["trace[939617587] 'read index received' (duration: 168.745518ms)","trace[939617587] 'applied index is now lower than readState.Index' (duration: 47.251µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:24.244063Z","caller":"traceutil/trace.go:171","msg":"trace[1738919735] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"169.080906ms","start":"2026-02-25T02:07:24.074954Z","end":"2026-02-25T02:07:24.244034Z","steps":["trace[1738919735] 'process raft request' (duration: 168.933513ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:24.244186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.948142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T02:07:24.244272Z","caller":"traceutil/trace.go:171","msg":"trace[1470831058] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:2; }","duration":"169.003833ms","start":"2026-02-25T02:07:24.075219Z","end":"2026-02-25T02:07:24.244223Z","steps":["trace[1470831058] 'agreement among raft nodes before linearized reading' (duration: 168.899391ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:24.400321Z","caller":"traceutil/trace.go:171","msg":"trace[656922237] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"235.933283ms","start":"2026-02-25T02:07:24.164338Z","end":"2026-02-25T02:07:24.400271Z","steps":["trace[656922237] 'process raft request' (duration: 235.632486ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:24.400383Z","caller":"traceutil/trace.go:171","msg":"trace[2011765082] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"235.565765ms","start":"2026-02-25T02:07:24.164771Z","end":"2026-02-25T02:07:24.400337Z","steps":["trace[2011765082] 'process raft request' (duration: 235.352531ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:24.400384Z","caller":"traceutil/trace.go:171","msg":"trace[458880874] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"235.767889ms","start":"2026-02-25T02:07:24.164583Z","end":"2026-02-25T02:07:24.400351Z","steps":["trace[458880874] 'process raft request' (duration: 235.507464ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:24.400439Z","caller":"traceutil/trace.go:171","msg":"trace[286619364] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"235.605306ms","start":"2026-02-25T02:07:24.164773Z","end":"2026-02-25T02:07:24.400378Z","steps":["trace[286619364] 'process raft request' (duration: 235.383331ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:24.400499Z","caller":"traceutil/trace.go:171","msg":"trace[798413244] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"235.508774ms","start":"2026-02-25T02:07:24.164962Z","end":"2026-02-25T02:07:24.400471Z","steps":["trace[798413244] 'process raft request' (duration: 235.218428ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:24.400953Z","caller":"traceutil/trace.go:171","msg":"trace[278949023] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"235.899441ms","start":"2026-02-25T02:07:24.165038Z","end":"2026-02-25T02:07:24.400938Z","steps":["trace[278949023] 'process raft request' (duration: 235.167816ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:24.401031Z","caller":"traceutil/trace.go:171","msg":"trace[375945580] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"156.90917ms","start":"2026-02-25T02:07:24.244102Z","end":"2026-02-25T02:07:24.401011Z","steps":["trace[375945580] 'read index received' (duration: 155.96049ms)","trace[375945580] 'applied index is now lower than readState.Index' (duration: 947.44µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:24.401236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.714879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T02:07:24.401314Z","caller":"traceutil/trace.go:171","msg":"trace[1624899753] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"237.833562ms","start":"2026-02-25T02:07:24.163462Z","end":"2026-02-25T02:07:24.401295Z","steps":["trace[1624899753] 'agreement among raft nodes before linearized reading' (duration: 237.560436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:24.401341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.841559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:2865"} {"level":"info","ts":"2026-02-25T02:07:24.401395Z","caller":"traceutil/trace.go:171","msg":"trace[902913834] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"322.899011ms","start":"2026-02-25T02:07:24.078482Z","end":"2026-02-25T02:07:24.401381Z","steps":["trace[902913834] 'agreement among raft nodes before linearized reading' (duration: 322.650165ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:24.401411Z","caller":"traceutil/trace.go:171","msg":"trace[1442183098] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"227.753232ms","start":"2026-02-25T02:07:24.173616Z","end":"2026-02-25T02:07:24.401369Z","steps":["trace[1442183098] 'process raft request' (duration: 227.327562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:24.401378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.789493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T02:07:24.401542Z","caller":"traceutil/trace.go:171","msg":"trace[383334787] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:0; response_revision:10; }","duration":"239.957727ms","start":"2026-02-25T02:07:24.161553Z","end":"2026-02-25T02:07:24.401511Z","steps":["trace[383334787] 'agreement among raft nodes before linearized reading' (duration: 239.792914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:24.401535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.314138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T02:07:24.401593Z","caller":"traceutil/trace.go:171","msg":"trace[1152511324] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"228.245652ms","start":"2026-02-25T02:07:24.173255Z","end":"2026-02-25T02:07:24.401501Z","steps":["trace[1152511324] 'process raft request' (duration: 227.624388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:24.401299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.526873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T02:07:24.401581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.987753ms","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-25T02:07:24.401718Z","caller":"traceutil/trace.go:171","msg":"trace[552325023] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"322.977723ms","start":"2026-02-25T02:07:24.078722Z","end":"2026-02-25T02:07:24.401699Z","steps":["trace[552325023] 'agreement among raft nodes before linearized reading' (duration: 322.358589ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:24.401728Z","caller":"traceutil/trace.go:171","msg":"trace[1386359195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:10; }","duration":"323.151007ms","start":"2026-02-25T02:07:24.078557Z","end":"2026-02-25T02:07:24.401708Z","steps":["trace[1386359195] 'agreement among raft nodes before linearized reading' (duration: 322.942973ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:24.401608Z","caller":"traceutil/trace.go:171","msg":"trace[2057628548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10; }","duration":"322.399391ms","start":"2026-02-25T02:07:24.07919Z","end":"2026-02-25T02:07:24.401589Z","steps":["trace[2057628548] 'agreement among raft nodes before linearized reading' (duration: 322.160806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:24.401888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.321871ms","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":"warn","ts":"2026-02-25T02:07:24.401891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:24.078541Z","time spent":"323.285889ms","remote":"127.0.0.1:57578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T02:07:24.401865Z","caller":"traceutil/trace.go:171","msg":"trace[1285102354] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"156.642115ms","start":"2026-02-25T02:07:24.245192Z","end":"2026-02-25T02:07:24.401835Z","steps":["trace[1285102354] 'process raft request' (duration: 156.543693ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:24.401938Z","caller":"traceutil/trace.go:171","msg":"trace[1801834001] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"237.395163ms","start":"2026-02-25T02:07:24.164527Z","end":"2026-02-25T02:07:24.401922Z","steps":["trace[1801834001] 'agreement among raft nodes before linearized reading' (duration: 237.286571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:24.401962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.438023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T02:07:24.401672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.051591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T02:07:24.401977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:24.079184Z","time spent":"322.756248ms","remote":"127.0.0.1:57294","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-02-25T02:07:24.402025Z","caller":"traceutil/trace.go:171","msg":"trace[296974480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:11; }","duration":"323.542224ms","start":"2026-02-25T02:07:24.078467Z","end":"2026-02-25T02:07:24.402009Z","steps":["trace[296974480] 'agreement among raft nodes before linearized reading' (duration: 323.449992ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:24.402037Z","caller":"traceutil/trace.go:171","msg":"trace[827542227] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"187.445418ms","start":"2026-02-25T02:07:24.214574Z","end":"2026-02-25T02:07:24.402019Z","steps":["trace[827542227] 'agreement among raft nodes before linearized reading' (duration: 187.01814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:24.402071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:24.078452Z","time spent":"323.605386ms","remote":"127.0.0.1:57578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T02:07:24.401444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:24.078431Z","time spent":"323.000913ms","remote":"127.0.0.1:57462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":2887,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-25T02:07:24.401788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:24.078708Z","time spent":"323.051394ms","remote":"127.0.0.1:57710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-02-25T02:07:24.402326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.775269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T02:07:24.402389Z","caller":"traceutil/trace.go:171","msg":"trace[1623742377] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:11; }","duration":"323.843551ms","start":"2026-02-25T02:07:24.078528Z","end":"2026-02-25T02:07:24.402371Z","steps":["trace[1623742377] 'agreement among raft nodes before linearized reading' (duration: 323.755929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:24.402439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:24.07852Z","time spent":"323.906282ms","remote":"127.0.0.1:57578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"info","ts":"2026-02-25T02:07:30.242578Z","caller":"traceutil/trace.go:171","msg":"trace[1390935938] linearizableReadLoop","detail":"{readStateIndex:305; appliedIndex:304; }","duration":"130.53196ms","start":"2026-02-25T02:07:30.112026Z","end":"2026-02-25T02:07:30.242558Z","steps":["trace[1390935938] 'read index received' (duration: 130.403357ms)","trace[1390935938] 'applied index is now lower than readState.Index' (duration: 127.653µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:30.242669Z","caller":"traceutil/trace.go:171","msg":"trace[875668826] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"566.653399ms","start":"2026-02-25T02:07:29.675978Z","end":"2026-02-25T02:07:30.242632Z","steps":["trace[875668826] 'process raft request' (duration: 566.461105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:30.242789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.747664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-25T02:07:30.243053Z","caller":"traceutil/trace.go:171","msg":"trace[1079349683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:294; }","duration":"130.976959ms","start":"2026-02-25T02:07:30.111979Z","end":"2026-02-25T02:07:30.242956Z","steps":["trace[1079349683] 'agreement among raft nodes before linearized reading' (duration: 130.690703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:30.243259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:29.675927Z","time spent":"566.833633ms","remote":"127.0.0.1:57506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:07:36.343313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.041814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-02-25T02:07:36.343384Z","caller":"traceutil/trace.go:171","msg":"trace[130485447] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:362; }","duration":"981.151666ms","start":"2026-02-25T02:07:35.362217Z","end":"2026-02-25T02:07:36.343369Z","steps":["trace[130485447] 'range keys from in-memory index tree' (duration: 980.959943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:36.343369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.578703ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:07:36.343417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:35.362204Z","time spent":"981.206098ms","remote":"127.0.0.1:57474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4282,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-02-25T02:07:36.343434Z","caller":"traceutil/trace.go:171","msg":"trace[1283062695] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:362; }","duration":"628.645784ms","start":"2026-02-25T02:07:35.714779Z","end":"2026-02-25T02:07:36.343425Z","steps":["trace[1283062695] 'range keys from in-memory index tree' (duration: 628.523421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:36.343575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.249291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T02:07:36.343675Z","caller":"traceutil/trace.go:171","msg":"trace[1618726712] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:362; }","duration":"633.428866ms","start":"2026-02-25T02:07:35.710224Z","end":"2026-02-25T02:07:36.343653Z","steps":["trace[1618726712] 'range keys from in-memory index tree' (duration: 632.913954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:36.343722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:35.710203Z","time spent":"633.508838ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.244\" "} {"level":"warn","ts":"2026-02-25T02:07:37.042551Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197921830693419,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:07:37.189606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:36.344867Z","time spent":"844.734294ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-25T02:07:37.233021Z","caller":"traceutil/trace.go:171","msg":"trace[1229862131] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"885.651238ms","start":"2026-02-25T02:07:36.347334Z","end":"2026-02-25T02:07:37.232985Z","steps":["trace[1229862131] 'process raft request' (duration: 885.401393ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:37.23341Z","caller":"traceutil/trace.go:171","msg":"trace[153363384] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"881.333917ms","start":"2026-02-25T02:07:36.352059Z","end":"2026-02-25T02:07:37.233393Z","steps":["trace[153363384] 'process raft request' (duration: 881.182494ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:37.233444Z","caller":"traceutil/trace.go:171","msg":"trace[1140652168] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"886.088947ms","start":"2026-02-25T02:07:36.347333Z","end":"2026-02-25T02:07:37.233422Z","steps":["trace[1140652168] 'process raft request' (duration: 885.596577ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:37.233372Z","caller":"traceutil/trace.go:171","msg":"trace[1075203792] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:374; }","duration":"691.615731ms","start":"2026-02-25T02:07:36.541731Z","end":"2026-02-25T02:07:37.233347Z","steps":["trace[1075203792] 'read index received' (duration: 647.656431ms)","trace[1075203792] 'applied index is now lower than readState.Index' (duration: 43.95818ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:37.233508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.775984ms","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-25T02:07:37.233612Z","caller":"traceutil/trace.go:171","msg":"trace[1350913849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:365; }","duration":"691.884627ms","start":"2026-02-25T02:07:36.541668Z","end":"2026-02-25T02:07:37.233553Z","steps":["trace[1350913849] 'agreement among raft nodes before linearized reading' (duration: 691.780415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:37.233669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:36.541646Z","time spent":"692.008589ms","remote":"127.0.0.1:57578","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-25T02:07:37.233997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:36.347305Z","time spent":"885.820192ms","remote":"127.0.0.1:57578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:07:37.234046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.301398ms","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-25T02:07:37.234145Z","caller":"traceutil/trace.go:171","msg":"trace[1499815469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:366; }","duration":"640.463971ms","start":"2026-02-25T02:07:36.593654Z","end":"2026-02-25T02:07:37.234118Z","steps":["trace[1499815469] 'agreement among raft nodes before linearized reading' (duration: 640.287248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:37.234164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:36.352041Z","time spent":"881.423229ms","remote":"127.0.0.1:57474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4053,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:07:37.234223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:36.593628Z","time spent":"640.577093ms","remote":"127.0.0.1:57578","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-02-25T02:07:37.234355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:36.347293Z","time spent":"886.21476ms","remote":"127.0.0.1:57578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:38.516751Z","caller":"traceutil/trace.go:171","msg":"trace[2110855418] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"151.340324ms","start":"2026-02-25T02:07:38.365387Z","end":"2026-02-25T02:07:38.516728Z","steps":["trace[2110855418] 'process raft request' (duration: 151.194761ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:41.799792Z","caller":"traceutil/trace.go:171","msg":"trace[657020159] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"126.173838ms","start":"2026-02-25T02:07:41.673589Z","end":"2026-02-25T02:07:41.799763Z","steps":["trace[657020159] 'process raft request' (duration: 73.62847ms)","trace[657020159] 'compare' (duration: 52.408546ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:48.077694Z","caller":"traceutil/trace.go:171","msg":"trace[1895186144] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"316.366584ms","start":"2026-02-25T02:07:47.761296Z","end":"2026-02-25T02:07:48.077662Z","steps":["trace[1895186144] 'process raft request' (duration: 316.233781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:48.077877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:47.761268Z","time spent":"316.517608ms","remote":"127.0.0.1:57578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:51.601929Z","caller":"traceutil/trace.go:171","msg":"trace[1936642320] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"228.965277ms","start":"2026-02-25T02:07:51.372931Z","end":"2026-02-25T02:07:51.601896Z","steps":["trace[1936642320] 'process raft request' (duration: 228.788963ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:55.454993Z","caller":"traceutil/trace.go:171","msg":"trace[1276233637] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:489; }","duration":"133.055132ms","start":"2026-02-25T02:07:55.32191Z","end":"2026-02-25T02:07:55.454965Z","steps":["trace[1276233637] 'read index received' (duration: 132.95771ms)","trace[1276233637] 'applied index is now lower than readState.Index' (duration: 96.332µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:55.455367Z","caller":"traceutil/trace.go:171","msg":"trace[1901330282] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"161.456826ms","start":"2026-02-25T02:07:55.293896Z","end":"2026-02-25T02:07:55.455353Z","steps":["trace[1901330282] 'process raft request' (duration: 160.907865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:55.455592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.673405ms","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-25T02:07:55.455638Z","caller":"traceutil/trace.go:171","msg":"trace[1845483087] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:474; }","duration":"133.758077ms","start":"2026-02-25T02:07:55.321867Z","end":"2026-02-25T02:07:55.455625Z","steps":["trace[1845483087] 'agreement among raft nodes before linearized reading' (duration: 133.673435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:55.455809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.617165ms","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-25T02:07:55.455849Z","caller":"traceutil/trace.go:171","msg":"trace[677774950] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:474; }","duration":"102.695907ms","start":"2026-02-25T02:07:55.353142Z","end":"2026-02-25T02:07:55.455838Z","steps":["trace[677774950] 'agreement among raft nodes before linearized reading' (duration: 102.634856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:56.061294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.149115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:07:56.061512Z","caller":"traceutil/trace.go:171","msg":"trace[887651961] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:490; }","duration":"510.979552ms","start":"2026-02-25T02:07:55.550515Z","end":"2026-02-25T02:07:56.061495Z","steps":["trace[887651961] 'read index received' (duration: 165.344537ms)","trace[887651961] 'applied index is now lower than readState.Index' (duration: 345.634105ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:56.061653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.145926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4025"} {"level":"info","ts":"2026-02-25T02:07:56.061689Z","caller":"traceutil/trace.go:171","msg":"trace[678403222] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:477; }","duration":"511.218617ms","start":"2026-02-25T02:07:55.55046Z","end":"2026-02-25T02:07:56.061679Z","steps":["trace[678403222] 'agreement among raft nodes before linearized reading' (duration: 511.150255ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:56.061666Z","caller":"traceutil/trace.go:171","msg":"trace[80571750] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"602.281191ms","start":"2026-02-25T02:07:55.459363Z","end":"2026-02-25T02:07:56.061644Z","steps":["trace[80571750] 'process raft request' (duration: 602.046906ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:56.061693Z","caller":"traceutil/trace.go:171","msg":"trace[1643766253] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"602.350953ms","start":"2026-02-25T02:07:55.459285Z","end":"2026-02-25T02:07:56.061636Z","steps":["trace[1643766253] 'process raft request' (duration: 256.634766ms)","trace[1643766253] 'compare' (duration: 345.026812ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:56.061763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.960947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.244\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T02:07:56.061675Z","caller":"traceutil/trace.go:171","msg":"trace[1141205367] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"602.094298ms","start":"2026-02-25T02:07:55.459503Z","end":"2026-02-25T02:07:56.061597Z","steps":["trace[1141205367] 'process raft request' (duration: 601.959155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:56.061787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.968518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:07:56.061793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.60702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-25T02:07:56.061833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:55.459265Z","time spent":"602.507876ms","remote":"127.0.0.1:57578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:56.06184Z","caller":"traceutil/trace.go:171","msg":"trace[1627922627] range","detail":"{range_begin:/registry/masterleases/199.204.45.244; range_end:; response_count:1; response_revision:477; }","duration":"351.043238ms","start":"2026-02-25T02:07:55.710775Z","end":"2026-02-25T02:07:56.061818Z","steps":["trace[1627922627] 'agreement among raft nodes before linearized reading' (duration: 350.898015ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:56.061877Z","caller":"traceutil/trace.go:171","msg":"trace[1358061660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:477; }","duration":"375.084991ms","start":"2026-02-25T02:07:55.68677Z","end":"2026-02-25T02:07:56.061855Z","steps":["trace[1358061660] 'agreement among raft nodes before linearized reading' (duration: 374.935518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:56.061878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:55.459344Z","time spent":"602.416454ms","remote":"127.0.0.1:57578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:07:56.0619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:55.710738Z","time spent":"351.15402ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.244\" "} {"level":"info","ts":"2026-02-25T02:07:56.061906Z","caller":"traceutil/trace.go:171","msg":"trace[1525666414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:477; }","duration":"417.762803ms","start":"2026-02-25T02:07:55.644112Z","end":"2026-02-25T02:07:56.061875Z","steps":["trace[1525666414] 'agreement among raft nodes before linearized reading' (duration: 417.542048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:56.061962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:55.644086Z","time spent":"417.866595ms","remote":"127.0.0.1:57578","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-02-25T02:07:56.061906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:55.686756Z","time spent":"375.144362ms","remote":"127.0.0.1:57294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:07:56.061724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:55.550433Z","time spent":"511.283458ms","remote":"127.0.0.1:57462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4047,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-25T02:07:56.062003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:55.459491Z","time spent":"602.353263ms","remote":"127.0.0.1:57578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:56.192737Z","caller":"traceutil/trace.go:171","msg":"trace[449268325] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:496; }","duration":"118.337024ms","start":"2026-02-25T02:07:56.074367Z","end":"2026-02-25T02:07:56.192704Z","steps":["trace[449268325] 'read index received' (duration: 117.273192ms)","trace[449268325] 'applied index is now lower than readState.Index' (duration: 1.062352ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:56.192846Z","caller":"traceutil/trace.go:171","msg":"trace[970993312] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"122.44924ms","start":"2026-02-25T02:07:56.070349Z","end":"2026-02-25T02:07:56.192799Z","steps":["trace[970993312] 'process raft request' (duration: 121.192354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:56.192901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.507947ms","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-25T02:07:56.192971Z","caller":"traceutil/trace.go:171","msg":"trace[1606123802] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:480; }","duration":"118.628739ms","start":"2026-02-25T02:07:56.074327Z","end":"2026-02-25T02:07:56.192956Z","steps":["trace[1606123802] 'agreement among raft nodes before linearized reading' (duration: 118.487866ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:58.185768Z","caller":"traceutil/trace.go:171","msg":"trace[445942350] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"116.419203ms","start":"2026-02-25T02:07:58.069326Z","end":"2026-02-25T02:07:58.185745Z","steps":["trace[445942350] 'process raft request' (duration: 116.27654ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:58.28989Z","caller":"traceutil/trace.go:171","msg":"trace[1244075246] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:497; }","duration":"217.59616ms","start":"2026-02-25T02:07:58.072276Z","end":"2026-02-25T02:07:58.289872Z","steps":["trace[1244075246] 'read index received' (duration: 113.294689ms)","trace[1244075246] 'applied index is now lower than readState.Index' (duration: 104.300731ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:58.289984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.699562ms","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-25T02:07:58.28995Z","caller":"traceutil/trace.go:171","msg":"trace[2008649628] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"219.703844ms","start":"2026-02-25T02:07:58.070204Z","end":"2026-02-25T02:07:58.289908Z","steps":["trace[2008649628] 'process raft request' (duration: 215.956515ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:58.290009Z","caller":"traceutil/trace.go:171","msg":"trace[2043667273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:483; }","duration":"217.757183ms","start":"2026-02-25T02:07:58.072242Z","end":"2026-02-25T02:07:58.29Z","steps":["trace[2043667273] 'agreement among raft nodes before linearized reading' (duration: 217.701152ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:58.290006Z","caller":"traceutil/trace.go:171","msg":"trace[403112256] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"219.743435ms","start":"2026-02-25T02:07:58.070217Z","end":"2026-02-25T02:07:58.289961Z","steps":["trace[403112256] 'process raft request' (duration: 219.621373ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:59.681223Z","caller":"traceutil/trace.go:171","msg":"trace[1172830588] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"233.645884ms","start":"2026-02-25T02:07:59.447554Z","end":"2026-02-25T02:07:59.681199Z","steps":["trace[1172830588] 'process raft request' (duration: 233.522692ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:59.909274Z","caller":"traceutil/trace.go:171","msg":"trace[2106405141] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"222.266448ms","start":"2026-02-25T02:07:59.686976Z","end":"2026-02-25T02:07:59.909243Z","steps":["trace[2106405141] 'process raft request' (duration: 179.849711ms)","trace[2106405141] 'compare' (duration: 42.299334ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:00.116017Z","caller":"traceutil/trace.go:171","msg":"trace[607822348] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"202.355551ms","start":"2026-02-25T02:07:59.913628Z","end":"2026-02-25T02:08:00.115984Z","steps":["trace[607822348] 'process raft request' (duration: 147.174077ms)","trace[607822348] 'compare' (duration: 55.00367ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:00.33491Z","caller":"traceutil/trace.go:171","msg":"trace[1743129464] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:509; }","duration":"144.636654ms","start":"2026-02-25T02:08:00.190247Z","end":"2026-02-25T02:08:00.334884Z","steps":["trace[1743129464] 'read index received' (duration: 134.694866ms)","trace[1743129464] 'applied index is now lower than readState.Index' (duration: 9.940548ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:00.334933Z","caller":"traceutil/trace.go:171","msg":"trace[928932719] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"214.395983ms","start":"2026-02-25T02:08:00.120524Z","end":"2026-02-25T02:08:00.33492Z","steps":["trace[928932719] 'process raft request' (duration: 204.472745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:00.335036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.791138ms","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-25T02:08:00.335075Z","caller":"traceutil/trace.go:171","msg":"trace[323719968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:493; }","duration":"144.8827ms","start":"2026-02-25T02:08:00.190183Z","end":"2026-02-25T02:08:00.335066Z","steps":["trace[323719968] 'agreement among raft nodes before linearized reading' (duration: 144.784598ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:00.527889Z","caller":"traceutil/trace.go:171","msg":"trace[1082057571] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"189.096794ms","start":"2026-02-25T02:08:00.338773Z","end":"2026-02-25T02:08:00.527869Z","steps":["trace[1082057571] 'process raft request' (duration: 188.46126ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:00.527967Z","caller":"traceutil/trace.go:171","msg":"trace[536812872] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"188.437141ms","start":"2026-02-25T02:08:00.339479Z","end":"2026-02-25T02:08:00.527916Z","steps":["trace[536812872] 'process raft request' (duration: 188.36664ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:00.528054Z","caller":"traceutil/trace.go:171","msg":"trace[997782910] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"188.585914ms","start":"2026-02-25T02:08:00.339445Z","end":"2026-02-25T02:08:00.528031Z","steps":["trace[997782910] 'process raft request' (duration: 188.342879ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:00.528144Z","caller":"traceutil/trace.go:171","msg":"trace[1423318574] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"188.493543ms","start":"2026-02-25T02:08:00.339622Z","end":"2026-02-25T02:08:00.528115Z","steps":["trace[1423318574] 'process raft request' (duration: 188.257958ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:00.692255Z","caller":"traceutil/trace.go:171","msg":"trace[63126716] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"128.599299ms","start":"2026-02-25T02:08:00.563624Z","end":"2026-02-25T02:08:00.692223Z","steps":["trace[63126716] 'process raft request' (duration: 128.444126ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:00.855851Z","caller":"traceutil/trace.go:171","msg":"trace[219330775] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:520; }","duration":"130.601881ms","start":"2026-02-25T02:08:00.725212Z","end":"2026-02-25T02:08:00.855813Z","steps":["trace[219330775] 'read index received' (duration: 111.703525ms)","trace[219330775] 'applied index is now lower than readState.Index' (duration: 18.896846ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:00.855892Z","caller":"traceutil/trace.go:171","msg":"trace[277506580] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"133.090443ms","start":"2026-02-25T02:08:00.722771Z","end":"2026-02-25T02:08:00.855861Z","steps":["trace[277506580] 'process raft request' (duration: 114.173217ms)","trace[277506580] 'compare' (duration: 18.712662ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:00.856138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.884097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:3343"} {"level":"info","ts":"2026-02-25T02:08:00.856235Z","caller":"traceutil/trace.go:171","msg":"trace[1486382785] range","detail":"{range_begin:/registry/deployments/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:504; }","duration":"131.013309ms","start":"2026-02-25T02:08:00.725197Z","end":"2026-02-25T02:08:00.85621Z","steps":["trace[1486382785] 'agreement among raft nodes before linearized reading' (duration: 130.787124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:00.92872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.837684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-25T02:08:00.928817Z","caller":"traceutil/trace.go:171","msg":"trace[1311644604] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:504; }","duration":"173.949687ms","start":"2026-02-25T02:08:00.754847Z","end":"2026-02-25T02:08:00.928797Z","steps":["trace[1311644604] 'agreement among raft nodes before linearized reading' (duration: 173.777193ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:00.967518Z","caller":"traceutil/trace.go:171","msg":"trace[53304961] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"106.746442ms","start":"2026-02-25T02:08:00.860743Z","end":"2026-02-25T02:08:00.96749Z","steps":["trace[53304961] 'process raft request' (duration: 106.521287ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:00.967581Z","caller":"traceutil/trace.go:171","msg":"trace[1687896057] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"105.162818ms","start":"2026-02-25T02:08:00.862393Z","end":"2026-02-25T02:08:00.967556Z","steps":["trace[1687896057] 'process raft request' (duration: 105.105607ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:00.967905Z","caller":"traceutil/trace.go:171","msg":"trace[110972396] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"105.559498ms","start":"2026-02-25T02:08:00.86231Z","end":"2026-02-25T02:08:00.96787Z","steps":["trace[110972396] 'process raft request' (duration: 105.125968ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:00.96799Z","caller":"traceutil/trace.go:171","msg":"trace[314833247] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"107.142291ms","start":"2026-02-25T02:08:00.860835Z","end":"2026-02-25T02:08:00.967977Z","steps":["trace[314833247] 'process raft request' (duration: 106.557138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:04.743659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.714247ms","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-25T02:08:04.743731Z","caller":"traceutil/trace.go:171","msg":"trace[235438973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:518; }","duration":"102.824459ms","start":"2026-02-25T02:08:04.640892Z","end":"2026-02-25T02:08:04.743717Z","steps":["trace[235438973] 'range keys from in-memory index tree' (duration: 102.538434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:04.743833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.110301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:08:04.743856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.279935ms","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-25T02:08:04.743897Z","caller":"traceutil/trace.go:171","msg":"trace[1035314401] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:518; }","duration":"364.223035ms","start":"2026-02-25T02:08:04.379658Z","end":"2026-02-25T02:08:04.743881Z","steps":["trace[1035314401] 'range keys from in-memory index tree' (duration: 363.98747ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:04.743921Z","caller":"traceutil/trace.go:171","msg":"trace[1648324400] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:518; }","duration":"317.390966ms","start":"2026-02-25T02:08:04.426512Z","end":"2026-02-25T02:08:04.743903Z","steps":["trace[1648324400] 'range keys from in-memory index tree' (duration: 317.12806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:04.743968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:04.379628Z","time spent":"364.329738ms","remote":"127.0.0.1:57370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"warn","ts":"2026-02-25T02:08:04.744006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:04.42649Z","time spent":"317.503929ms","remote":"127.0.0.1:57578","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-25T02:08:04.744007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.345346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rm7nt\" ","response":"range_response_count:1 size:16656"} {"level":"warn","ts":"2026-02-25T02:08:04.74403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.268038ms","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-25T02:08:04.744063Z","caller":"traceutil/trace.go:171","msg":"trace[260962019] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:518; }","duration":"169.871852ms","start":"2026-02-25T02:08:04.574181Z","end":"2026-02-25T02:08:04.744053Z","steps":["trace[260962019] 'range keys from in-memory index tree' (duration: 169.160367ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:04.744074Z","caller":"traceutil/trace.go:171","msg":"trace[835335146] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rm7nt; range_end:; response_count:1; response_revision:518; }","duration":"296.434398ms","start":"2026-02-25T02:08:04.447619Z","end":"2026-02-25T02:08:04.744053Z","steps":["trace[835335146] 'range keys from in-memory index tree' (duration: 296.166082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:04.744011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.787989ms","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-25T02:08:04.744164Z","caller":"traceutil/trace.go:171","msg":"trace[819394236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:518; }","duration":"103.105296ms","start":"2026-02-25T02:08:04.641041Z","end":"2026-02-25T02:08:04.744146Z","steps":["trace[819394236] 'range keys from in-memory index tree' (duration: 102.578274ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:04.894859Z","caller":"traceutil/trace.go:171","msg":"trace[32934424] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"146.974063ms","start":"2026-02-25T02:08:04.747863Z","end":"2026-02-25T02:08:04.894837Z","steps":["trace[32934424] 'process raft request' (duration: 146.83009ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:05.207247Z","caller":"traceutil/trace.go:171","msg":"trace[1809596975] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"459.272842ms","start":"2026-02-25T02:08:04.747931Z","end":"2026-02-25T02:08:05.207204Z","steps":["trace[1809596975] 'process raft request' (duration: 458.927575ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:05.207247Z","caller":"traceutil/trace.go:171","msg":"trace[1463399789] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:536; }","duration":"365.40187ms","start":"2026-02-25T02:08:04.84181Z","end":"2026-02-25T02:08:05.207212Z","steps":["trace[1463399789] 'read index received' (duration: 52.901876ms)","trace[1463399789] 'applied index is now lower than readState.Index' (duration: 312.498824ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:05.207416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.576824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:05.207458Z","caller":"traceutil/trace.go:171","msg":"trace[1075610670] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:523; }","duration":"365.686386ms","start":"2026-02-25T02:08:04.841755Z","end":"2026-02-25T02:08:05.207442Z","steps":["trace[1075610670] 'agreement among raft nodes before linearized reading' (duration: 365.588904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:05.207442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:04.747906Z","time spent":"459.428876ms","remote":"127.0.0.1:57578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:08:05.207509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:04.841684Z","time spent":"365.814269ms","remote":"127.0.0.1:57370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"info","ts":"2026-02-25T02:08:05.207576Z","caller":"traceutil/trace.go:171","msg":"trace[951766290] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"458.930115ms","start":"2026-02-25T02:08:04.748609Z","end":"2026-02-25T02:08:05.207539Z","steps":["trace[951766290] 'process raft request' (duration: 458.490616ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:05.207618Z","caller":"traceutil/trace.go:171","msg":"trace[728055728] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"438.870886ms","start":"2026-02-25T02:08:04.76873Z","end":"2026-02-25T02:08:05.207601Z","steps":["trace[728055728] 'process raft request' (duration: 438.412397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:05.207766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:04.748599Z","time spent":"459.071508ms","remote":"127.0.0.1:57578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:08:05.207817Z","caller":"traceutil/trace.go:171","msg":"trace[407607385] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"459.383544ms","start":"2026-02-25T02:08:04.74841Z","end":"2026-02-25T02:08:05.207794Z","steps":["trace[407607385] 'process raft request' (duration: 458.641849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:05.207848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:04.768705Z","time spent":"439.09431ms","remote":"127.0.0.1:57474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:08:05.20798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:04.748386Z","time spent":"459.505497ms","remote":"127.0.0.1:57578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:08:05.659302Z","caller":"traceutil/trace.go:171","msg":"trace[2077938236] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"102.225277ms","start":"2026-02-25T02:08:05.557045Z","end":"2026-02-25T02:08:05.65927Z","steps":["trace[2077938236] 'process raft request' (duration: 102.013762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:06.360127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.978731ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-02-25T02:08:06.360235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:05.712336Z","time spent":"647.894337ms","remote":"127.0.0.1:57302","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T02:08:07.398684Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197921830693798,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:08:07.899893Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197921830693798,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:08:08.400633Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197921830693798,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:08:08.620372Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.750299514s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T02:08:08.620663Z","caller":"traceutil/trace.go:171","msg":"trace[926037939] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:544; }","duration":"1.722682647s","start":"2026-02-25T02:08:06.897964Z","end":"2026-02-25T02:08:08.620646Z","steps":["trace[926037939] 'read index received' (duration: 1.722553405s)","trace[926037939] 'applied index is now lower than readState.Index' (duration: 128.492µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:08.620731Z","caller":"traceutil/trace.go:171","msg":"trace[163815077] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"1.750745984s","start":"2026-02-25T02:08:06.869967Z","end":"2026-02-25T02:08:08.620713Z","steps":["trace[163815077] 'process raft request' (duration: 1.750586021s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:08.620839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.722875711s","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-25T02:08:08.620867Z","caller":"traceutil/trace.go:171","msg":"trace[1842213845] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:526; }","duration":"1.722931302s","start":"2026-02-25T02:08:06.897929Z","end":"2026-02-25T02:08:08.62086Z","steps":["trace[1842213845] 'agreement among raft nodes before linearized reading' (duration: 1.722781719s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:08.62089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:06.897919Z","time spent":"1.722965733s","remote":"127.0.0.1:57578","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-02-25T02:08:08.620858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:06.869953Z","time spent":"1.750840455s","remote":"127.0.0.1:57378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":365,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:08:08.620995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.063161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:08.621098Z","caller":"traceutil/trace.go:171","msg":"trace[930436461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"935.206553ms","start":"2026-02-25T02:08:07.685866Z","end":"2026-02-25T02:08:08.621072Z","steps":["trace[930436461] 'agreement among raft nodes before linearized reading' (duration: 935.023578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:08.621098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.408970888s","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-25T02:08:08.621149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:07.685846Z","time spent":"935.292196ms","remote":"127.0.0.1:57292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:08:08.62117Z","caller":"traceutil/trace.go:171","msg":"trace[1132455190] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:526; }","duration":"1.409066951s","start":"2026-02-25T02:08:07.212085Z","end":"2026-02-25T02:08:08.621152Z","steps":["trace[1132455190] 'agreement among raft nodes before linearized reading' (duration: 1.408852036s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:08.62117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.409955189s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-25T02:08:08.620994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.006702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:08.621258Z","caller":"traceutil/trace.go:171","msg":"trace[1235777272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"992.339449ms","start":"2026-02-25T02:08:07.628911Z","end":"2026-02-25T02:08:08.62125Z","steps":["trace[1235777272] 'agreement among raft nodes before linearized reading' (duration: 992.012332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:08.621279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:07.628887Z","time spent":"992.385809ms","remote":"127.0.0.1:57294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:08:08.621267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:07.212077Z","time spent":"1.409127592s","remote":"127.0.0.1:57578","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-02-25T02:08:08.621271Z","caller":"traceutil/trace.go:171","msg":"trace[1540367040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:526; }","duration":"1.410100143s","start":"2026-02-25T02:08:07.211136Z","end":"2026-02-25T02:08:08.621236Z","steps":["trace[1540367040] 'agreement among raft nodes before linearized reading' (duration: 1.409711624s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:08.62104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.409618292s","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-25T02:08:08.62133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:07.211111Z","time spent":"1.410206055s","remote":"127.0.0.1:57578","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-25T02:08:08.621348Z","caller":"traceutil/trace.go:171","msg":"trace[1913775715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:526; }","duration":"1.409931199s","start":"2026-02-25T02:08:07.21139Z","end":"2026-02-25T02:08:08.621322Z","steps":["trace[1913775715] 'agreement among raft nodes before linearized reading' (duration: 1.409555882s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:08.621411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:07.21138Z","time spent":"1.410004681s","remote":"127.0.0.1:57578","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-25T02:08:10.847501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.065487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:08:10.84763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.321782ms","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-25T02:08:10.847637Z","caller":"traceutil/trace.go:171","msg":"trace[262353181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:676; }","duration":"161.249851ms","start":"2026-02-25T02:08:10.686362Z","end":"2026-02-25T02:08:10.847611Z","steps":["trace[262353181] 'range keys from in-memory index tree' (duration: 160.978096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:10.847639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.273432ms","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-25T02:08:10.847685Z","caller":"traceutil/trace.go:171","msg":"trace[913722819] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:676; }","duration":"183.404204ms","start":"2026-02-25T02:08:10.66426Z","end":"2026-02-25T02:08:10.847664Z","steps":["trace[913722819] 'range keys from in-memory index tree' (duration: 183.150299ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:10.847702Z","caller":"traceutil/trace.go:171","msg":"trace[1389431671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:676; }","duration":"183.394254ms","start":"2026-02-25T02:08:10.664293Z","end":"2026-02-25T02:08:10.847688Z","steps":["trace[1389431671] 'range keys from in-memory index tree' (duration: 183.029937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:10.84755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.141678ms","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-25T02:08:10.847838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.270571ms","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-25T02:08:10.847924Z","caller":"traceutil/trace.go:171","msg":"trace[2109976075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:676; }","duration":"183.361524ms","start":"2026-02-25T02:08:10.664544Z","end":"2026-02-25T02:08:10.847906Z","steps":["trace[2109976075] 'range keys from in-memory index tree' (duration: 183.000226ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:10.847845Z","caller":"traceutil/trace.go:171","msg":"trace[1058095245] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:676; }","duration":"183.500406ms","start":"2026-02-25T02:08:10.664307Z","end":"2026-02-25T02:08:10.847808Z","steps":["trace[1058095245] 'range keys from in-memory index tree' (duration: 182.937564ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:10.854177Z","caller":"traceutil/trace.go:171","msg":"trace[1300073787] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"113.663257ms","start":"2026-02-25T02:08:10.740504Z","end":"2026-02-25T02:08:10.854167Z","steps":["trace[1300073787] 'process raft request' (duration: 113.576925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:13.923751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.23926ms","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-25T02:08:13.923866Z","caller":"traceutil/trace.go:171","msg":"trace[608597047] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:715; }","duration":"182.368192ms","start":"2026-02-25T02:08:13.741469Z","end":"2026-02-25T02:08:13.923837Z","steps":["trace[608597047] 'range keys from in-memory index tree' (duration: 182.024375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:19.587575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.912427ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:08:19.587715Z","caller":"traceutil/trace.go:171","msg":"trace[1036729110] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"443.132158ms","start":"2026-02-25T02:08:19.144561Z","end":"2026-02-25T02:08:19.587693Z","steps":["trace[1036729110] 'process raft request' (duration: 109.025116ms)","trace[1036729110] 'compare' (duration: 333.621661ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:19.587781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:19.144528Z","time spent":"443.2233ms","remote":"127.0.0.1:46240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1238,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:08:21.234679Z","caller":"traceutil/trace.go:171","msg":"trace[1672290130] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"149.157287ms","start":"2026-02-25T02:08:21.085498Z","end":"2026-02-25T02:08:21.234655Z","steps":["trace[1672290130] 'process raft request' (duration: 149.017714ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:21.302062Z","caller":"traceutil/trace.go:171","msg":"trace[1969332861] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"215.156052ms","start":"2026-02-25T02:08:21.086877Z","end":"2026-02-25T02:08:21.302033Z","steps":["trace[1969332861] 'process raft request' (duration: 215.099901ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:21.302276Z","caller":"traceutil/trace.go:171","msg":"trace[788693460] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"216.125742ms","start":"2026-02-25T02:08:21.086111Z","end":"2026-02-25T02:08:21.302237Z","steps":["trace[788693460] 'process raft request' (duration: 209.165753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:22.291131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.552673ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:08:22.291427Z","caller":"traceutil/trace.go:171","msg":"trace[547862427] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"297.294702ms","start":"2026-02-25T02:08:21.994114Z","end":"2026-02-25T02:08:22.291408Z","steps":["trace[547862427] 'process raft request' (duration: 297.246701ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:22.29164Z","caller":"traceutil/trace.go:171","msg":"trace[1377891779] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"340.665572ms","start":"2026-02-25T02:08:21.950963Z","end":"2026-02-25T02:08:22.291629Z","steps":["trace[1377891779] 'process raft request' (duration: 184.532626ms)","trace[1377891779] 'compare' (duration: 155.316638ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:22.291678Z","caller":"traceutil/trace.go:171","msg":"trace[963748206] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"329.396051ms","start":"2026-02-25T02:08:21.962248Z","end":"2026-02-25T02:08:22.291644Z","steps":["trace[963748206] 'process raft request' (duration: 329.058603ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:22.29171Z","caller":"traceutil/trace.go:171","msg":"trace[895854727] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"335.45626ms","start":"2026-02-25T02:08:21.956222Z","end":"2026-02-25T02:08:22.291679Z","steps":["trace[895854727] 'process raft request' (duration: 335.0016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:22.291847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:21.962232Z","time spent":"329.524353ms","remote":"127.0.0.1:57800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:08:22.291869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:21.956211Z","time spent":"335.583093ms","remote":"127.0.0.1:57350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:08:22.291701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:21.950937Z","time spent":"340.732883ms","remote":"127.0.0.1:57774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4191,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:08:22.792523Z","caller":"traceutil/trace.go:171","msg":"trace[1745664409] linearizableReadLoop","detail":"{readStateIndex:856; appliedIndex:855; }","duration":"105.707135ms","start":"2026-02-25T02:08:22.686786Z","end":"2026-02-25T02:08:22.792493Z","steps":["trace[1745664409] 'read index received' (duration: 99.084924ms)","trace[1745664409] 'applied index is now lower than readState.Index' (duration: 6.620941ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:22.792664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.870668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:22.7927Z","caller":"traceutil/trace.go:171","msg":"trace[1299121310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:832; }","duration":"105.97944ms","start":"2026-02-25T02:08:22.686708Z","end":"2026-02-25T02:08:22.792687Z","steps":["trace[1299121310] 'agreement among raft nodes before linearized reading' (duration: 105.900799ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:22.792664Z","caller":"traceutil/trace.go:171","msg":"trace[145205685] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"205.416073ms","start":"2026-02-25T02:08:22.587214Z","end":"2026-02-25T02:08:22.79263Z","steps":["trace[145205685] 'process raft request' (duration: 198.650839ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:22.948628Z","caller":"traceutil/trace.go:171","msg":"trace[1435131517] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"150.225351ms","start":"2026-02-25T02:08:22.798381Z","end":"2026-02-25T02:08:22.948606Z","steps":["trace[1435131517] 'process raft request' (duration: 150.091118ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:22.951605Z","caller":"traceutil/trace.go:171","msg":"trace[346440397] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"152.739593ms","start":"2026-02-25T02:08:22.798836Z","end":"2026-02-25T02:08:22.951575Z","steps":["trace[346440397] 'process raft request' (duration: 152.627281ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:25.187634Z","caller":"traceutil/trace.go:171","msg":"trace[1730293081] linearizableReadLoop","detail":"{readStateIndex:871; appliedIndex:870; }","duration":"164.741971ms","start":"2026-02-25T02:08:25.022867Z","end":"2026-02-25T02:08:25.187609Z","steps":["trace[1730293081] 'read index received' (duration: 164.522097ms)","trace[1730293081] 'applied index is now lower than readState.Index' (duration: 216.034µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:25.187668Z","caller":"traceutil/trace.go:171","msg":"trace[816708175] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"188.215994ms","start":"2026-02-25T02:08:24.999431Z","end":"2026-02-25T02:08:25.187647Z","steps":["trace[816708175] 'process raft request' (duration: 188.03275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:25.187817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.950955ms","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-25T02:08:25.187855Z","caller":"traceutil/trace.go:171","msg":"trace[233676583] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:847; }","duration":"165.062808ms","start":"2026-02-25T02:08:25.02278Z","end":"2026-02-25T02:08:25.187843Z","steps":["trace[233676583] 'agreement among raft nodes before linearized reading' (duration: 164.920265ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:28.813617Z","caller":"traceutil/trace.go:171","msg":"trace[977897627] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"183.039444ms","start":"2026-02-25T02:08:28.630551Z","end":"2026-02-25T02:08:28.81359Z","steps":["trace[977897627] 'process raft request' (duration: 182.837129ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:29.024817Z","caller":"traceutil/trace.go:171","msg":"trace[1124987740] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"268.344981ms","start":"2026-02-25T02:08:28.756455Z","end":"2026-02-25T02:08:29.0248Z","steps":["trace[1124987740] 'process raft request' (duration: 268.173558ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:29.552399Z","caller":"traceutil/trace.go:171","msg":"trace[976317209] linearizableReadLoop","detail":"{readStateIndex:899; appliedIndex:898; }","duration":"265.53446ms","start":"2026-02-25T02:08:29.286844Z","end":"2026-02-25T02:08:29.552379Z","steps":["trace[976317209] 'read index received' (duration: 265.388087ms)","trace[976317209] 'applied index is now lower than readState.Index' (duration: 140.613µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:29.55248Z","caller":"traceutil/trace.go:171","msg":"trace[478368209] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"322.494072ms","start":"2026-02-25T02:08:29.229956Z","end":"2026-02-25T02:08:29.55245Z","steps":["trace[478368209] 'process raft request' (duration: 322.297468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:29.552612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.297767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-25T02:08:29.55263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:29.229932Z","time spent":"322.612665ms","remote":"127.0.0.1:57474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4991,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:08:29.552662Z","caller":"traceutil/trace.go:171","msg":"trace[1775425228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:873; }","duration":"259.389579ms","start":"2026-02-25T02:08:29.293246Z","end":"2026-02-25T02:08:29.552636Z","steps":["trace[1775425228] 'agreement among raft nodes before linearized reading' (duration: 259.261226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:29.552642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.786686ms","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-25T02:08:29.552715Z","caller":"traceutil/trace.go:171","msg":"trace[1635996436] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:873; }","duration":"265.915979ms","start":"2026-02-25T02:08:29.28678Z","end":"2026-02-25T02:08:29.552696Z","steps":["trace[1635996436] 'agreement among raft nodes before linearized reading' (duration: 265.700504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:29.552858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.882083ms","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-25T02:08:29.552955Z","caller":"traceutil/trace.go:171","msg":"trace[852607890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:873; }","duration":"206.078908ms","start":"2026-02-25T02:08:29.346853Z","end":"2026-02-25T02:08:29.552932Z","steps":["trace[852607890] 'agreement among raft nodes before linearized reading' (duration: 205.77376ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:43.374284Z","caller":"traceutil/trace.go:171","msg":"trace[622518253] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"683.95638ms","start":"2026-02-25T02:08:42.690303Z","end":"2026-02-25T02:08:43.374259Z","steps":["trace[622518253] 'process raft request' (duration: 683.814267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:43.37453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:42.690287Z","time spent":"684.154484ms","remote":"127.0.0.1:57578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:08:57.836822Z","caller":"traceutil/trace.go:171","msg":"trace[1885583178] linearizableReadLoop","detail":"{readStateIndex:1114; appliedIndex:1113; }","duration":"116.573629ms","start":"2026-02-25T02:08:57.720209Z","end":"2026-02-25T02:08:57.836783Z","steps":["trace[1885583178] 'read index received' (duration: 116.183221ms)","trace[1885583178] 'applied index is now lower than readState.Index' (duration: 388.698µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:57.836897Z","caller":"traceutil/trace.go:171","msg":"trace[733662681] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"131.487689ms","start":"2026-02-25T02:08:57.705358Z","end":"2026-02-25T02:08:57.836846Z","steps":["trace[733662681] 'process raft request' (duration: 131.169182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:57.837173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.958668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T02:08:57.837228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.297635ms","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-25T02:08:57.83726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.315396ms","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-25T02:08:57.837286Z","caller":"traceutil/trace.go:171","msg":"trace[423109742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1077; }","duration":"104.431568ms","start":"2026-02-25T02:08:57.732835Z","end":"2026-02-25T02:08:57.837267Z","steps":["trace[423109742] 'agreement among raft nodes before linearized reading' (duration: 104.180653ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:57.837281Z","caller":"traceutil/trace.go:171","msg":"trace[1585494160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1077; }","duration":"117.145241ms","start":"2026-02-25T02:08:57.72011Z","end":"2026-02-25T02:08:57.837256Z","steps":["trace[1585494160] 'agreement among raft nodes before linearized reading' (duration: 116.856555ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:57.837304Z","caller":"traceutil/trace.go:171","msg":"trace[5970499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1077; }","duration":"104.400247ms","start":"2026-02-25T02:08:57.732892Z","end":"2026-02-25T02:08:57.837292Z","steps":["trace[5970499] 'agreement among raft nodes before linearized reading' (duration: 104.202533ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:09:06.187777Z","caller":"traceutil/trace.go:171","msg":"trace[1034261821] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"287.657256ms","start":"2026-02-25T02:09:05.900096Z","end":"2026-02-25T02:09:06.187753Z","steps":["trace[1034261821] 'process raft request' (duration: 287.519923ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:09:06.274088Z","caller":"traceutil/trace.go:171","msg":"trace[1117502511] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"373.955016ms","start":"2026-02-25T02:09:05.900103Z","end":"2026-02-25T02:09:06.274058Z","steps":["trace[1117502511] 'process raft request' (duration: 372.29157ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:09:06.274209Z","caller":"traceutil/trace.go:171","msg":"trace[296564708] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"372.800391ms","start":"2026-02-25T02:09:05.901375Z","end":"2026-02-25T02:09:06.274175Z","steps":["trace[296564708] 'process raft request' (duration: 372.643097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:09:06.274315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:09:05.900085Z","time spent":"374.101949ms","remote":"127.0.0.1:57578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:09:06.274352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:09:05.901365Z","time spent":"372.889862ms","remote":"127.0.0.1:57578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:09:42.168927Z","caller":"traceutil/trace.go:171","msg":"trace[2027996741] transaction","detail":"{read_only:false; response_revision:1565; number_of_response:1; }","duration":"459.289385ms","start":"2026-02-25T02:09:41.709608Z","end":"2026-02-25T02:09:42.168898Z","steps":["trace[2027996741] 'process raft request' (duration: 385.436346ms)","trace[2027996741] 'compare' (duration: 73.713676ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:09:42.169276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:09:41.709595Z","time spent":"459.606122ms","remote":"127.0.0.1:57578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:17:22.767679Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2118} {"level":"info","ts":"2026-02-25T02:17:22.8442Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2118,"took":"75.654037ms","hash":3503868576,"current-db-size-bytes":14561280,"current-db-size":"15 MB","current-db-size-in-use-bytes":4272128,"current-db-size-in-use":"4.3 MB"} {"level":"info","ts":"2026-02-25T02:17:22.844284Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3503868576,"revision":2118,"compact-revision":-1} {"level":"info","ts":"2026-02-25T02:22:22.775179Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3052} {"level":"info","ts":"2026-02-25T02:22:22.795538Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3052,"took":"19.468723ms","hash":1761448344,"current-db-size-bytes":14561280,"current-db-size":"15 MB","current-db-size-in-use-bytes":4661248,"current-db-size-in-use":"4.7 MB"} {"level":"info","ts":"2026-02-25T02:22:22.795612Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1761448344,"revision":3052,"compact-revision":2118} {"level":"warn","ts":"2026-02-25T02:24:36.540668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.543482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-bootstrap\" ","response":"range_response_count:1 size:550"} {"level":"info","ts":"2026-02-25T02:24:36.540829Z","caller":"traceutil/trace.go:171","msg":"trace[1104370529] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-autoapprove-bootstrap; range_end:; response_count:1; response_revision:4390; }","duration":"138.782877ms","start":"2026-02-25T02:24:36.402015Z","end":"2026-02-25T02:24:36.540798Z","steps":["trace[1104370529] 'range keys from in-memory index tree' (duration: 138.391958ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:24:37.076159Z","caller":"traceutil/trace.go:171","msg":"trace[1658756643] transaction","detail":"{read_only:false; response_revision:4393; number_of_response:1; }","duration":"149.045516ms","start":"2026-02-25T02:24:36.927082Z","end":"2026-02-25T02:24:37.076127Z","steps":["trace[1658756643] 'process raft request' (duration: 139.131934ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:24:37.076365Z","caller":"traceutil/trace.go:171","msg":"trace[1103845111] transaction","detail":"{read_only:false; response_revision:4394; number_of_response:1; }","duration":"145.99878ms","start":"2026-02-25T02:24:36.930318Z","end":"2026-02-25T02:24:37.076317Z","steps":["trace[1103845111] 'process raft request' (duration: 145.735334ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:24:37.076356Z","caller":"traceutil/trace.go:171","msg":"trace[52381542] linearizableReadLoop","detail":"{readStateIndex:4648; appliedIndex:4645; }","duration":"117.401751ms","start":"2026-02-25T02:24:36.958908Z","end":"2026-02-25T02:24:37.07631Z","steps":["trace[52381542] 'read index received' (duration: 4.088996ms)","trace[52381542] 'applied index is now lower than readState.Index' (duration: 113.310965ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:24:37.076549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.628766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:persistent-volume-binder\" ","response":"range_response_count:1 size:1076"} {"level":"warn","ts":"2026-02-25T02:24:37.076734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.908713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/local-path-provisioner\" ","response":"range_response_count:1 size:1081"} {"level":"info","ts":"2026-02-25T02:24:37.076805Z","caller":"traceutil/trace.go:171","msg":"trace[1468654996] range","detail":"{range_begin:/registry/clusterrolebindings/local-path-provisioner; range_end:; response_count:1; response_revision:4394; }","duration":"104.670459ms","start":"2026-02-25T02:24:36.972119Z","end":"2026-02-25T02:24:37.07679Z","steps":["trace[1468654996] 'agreement among raft nodes before linearized reading' (duration: 104.408073ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:24:37.076906Z","caller":"traceutil/trace.go:171","msg":"trace[1997015203] range","detail":"{range_begin:/registry/clusterroles/system:controller:persistent-volume-binder; range_end:; response_count:1; response_revision:4394; }","duration":"117.885301ms","start":"2026-02-25T02:24:36.958835Z","end":"2026-02-25T02:24:37.07672Z","steps":["trace[1997015203] 'agreement among raft nodes before linearized reading' (duration: 117.592675ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:25:05.572642Z","caller":"traceutil/trace.go:171","msg":"trace[1956360256] transaction","detail":"{read_only:false; response_revision:4480; number_of_response:1; }","duration":"183.344435ms","start":"2026-02-25T02:25:05.389245Z","end":"2026-02-25T02:25:05.57259Z","steps":["trace[1956360256] 'process raft request' (duration: 98.884026ms)","trace[1956360256] 'compare' (duration: 84.168613ms)"],"step_count":2}