{"level":"warn","ts":"2026-02-25T10:05:05.461412Z","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-25T10:05:05.461534Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.205:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.205:2380","--initial-cluster=instance=https://199.19.213.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.205: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-25T10:05:05.461618Z","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-25T10:05:05.461638Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.205:2380"]} {"level":"info","ts":"2026-02-25T10:05:05.461664Z","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-25T10:05:05.462675Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.205:2379"]} {"level":"info","ts":"2026-02-25T10:05:05.462759Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.205:2380"],"listen-peer-urls":["https://199.19.213.205:2380"],"advertise-client-urls":["https://199.19.213.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.205: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-25T10:05:05.469391Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.401001ms"} {"level":"info","ts":"2026-02-25T10:05:05.477314Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f69f598a0f963672","cluster-id":"740466542a91995c"} {"level":"info","ts":"2026-02-25T10:05:05.477431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T10:05:05.477471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 became follower at term 0"} {"level":"info","ts":"2026-02-25T10:05:05.477538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f69f598a0f963672 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T10:05:05.477554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 became follower at term 1"} {"level":"info","ts":"2026-02-25T10:05:05.477711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 switched to configuration voters=(17771021104129128050)"} {"level":"warn","ts":"2026-02-25T10:05:05.486543Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T10:05:05.488082Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T10:05:05.489104Z","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-25T10:05:05.492852Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f69f598a0f963672","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T10:05:05.493125Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f69f598a0f963672","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T10:05:05.493239Z","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-25T10:05:05.493811Z","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-25T10:05:05.493859Z","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-25T10:05:05.495423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 switched to configuration voters=(17771021104129128050)"} {"level":"info","ts":"2026-02-25T10:05:05.495614Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"740466542a91995c","local-member-id":"f69f598a0f963672","added-peer-id":"f69f598a0f963672","added-peer-peer-urls":["https://199.19.213.205:2380"]} {"level":"info","ts":"2026-02-25T10:05:05.4977Z","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-25T10:05:05.497927Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.205:2380"} {"level":"info","ts":"2026-02-25T10:05:05.497983Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.205:2380"} {"level":"info","ts":"2026-02-25T10:05:05.498229Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f69f598a0f963672","initial-advertise-peer-urls":["https://199.19.213.205:2380"],"listen-peer-urls":["https://199.19.213.205:2380"],"advertise-client-urls":["https://199.19.213.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T10:05:05.498298Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T10:05:05.67865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T10:05:05.678739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T10:05:05.678791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 received MsgPreVoteResp from f69f598a0f963672 at term 1"} {"level":"info","ts":"2026-02-25T10:05:05.678815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 became candidate at term 2"} {"level":"info","ts":"2026-02-25T10:05:05.678858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 received MsgVoteResp from f69f598a0f963672 at term 2"} {"level":"info","ts":"2026-02-25T10:05:05.678882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 became leader at term 2"} {"level":"info","ts":"2026-02-25T10:05:05.678901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f69f598a0f963672 elected leader f69f598a0f963672 at term 2"} {"level":"info","ts":"2026-02-25T10:05:05.679982Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f69f598a0f963672","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.205:2379]}","request-path":"/0/members/f69f598a0f963672/attributes","cluster-id":"740466542a91995c","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T10:05:05.680171Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T10:05:05.680266Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T10:05:05.680608Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:05:05.685655Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T10:05:05.685718Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T10:05:05.685847Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.205:2379"} {"level":"info","ts":"2026-02-25T10:05:05.686238Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T10:05:05.686446Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"740466542a91995c","local-member-id":"f69f598a0f963672","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:05:05.686643Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:05:05.694889Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-25T10:05:08.546362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.304291ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-25T10:05:08.546482Z","caller":"traceutil/trace.go:171","msg":"trace[957775344] linearizableReadLoop","detail":"{readStateIndex:82; appliedIndex:81; }","duration":"134.84027ms","start":"2026-02-25T10:05:08.411621Z","end":"2026-02-25T10:05:08.546462Z","steps":["trace[957775344] 'read index received' (duration: 10.463943ms)","trace[957775344] 'applied index is now lower than readState.Index' (duration: 124.374877ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:08.546648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.010244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T10:05:08.546729Z","caller":"traceutil/trace.go:171","msg":"trace[2126910258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:79; }","duration":"135.149807ms","start":"2026-02-25T10:05:08.411569Z","end":"2026-02-25T10:05:08.546718Z","steps":["trace[2126910258] 'agreement among raft nodes before linearized reading' (duration: 134.952652ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:08.54667Z","caller":"traceutil/trace.go:171","msg":"trace[2038948577] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"158.034381ms","start":"2026-02-25T10:05:08.388586Z","end":"2026-02-25T10:05:08.546621Z","steps":["trace[2038948577] 'process raft request' (duration: 33.562171ms)","trace[2038948577] 'compare' (duration: 123.143677ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:08.755342Z","caller":"traceutil/trace.go:171","msg":"trace[687146389] transaction","detail":"{read_only:false; response_revision:81; number_of_response:1; }","duration":"148.893131ms","start":"2026-02-25T10:05:08.606416Z","end":"2026-02-25T10:05:08.755309Z","steps":["trace[687146389] 'process raft request' (duration: 71.324547ms)","trace[687146389] 'compare' (duration: 77.384759ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:08.987313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.324324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-25T10:05:08.987454Z","caller":"traceutil/trace.go:171","msg":"trace[322144044] transaction","detail":"{read_only:false; response_revision:83; number_of_response:1; }","duration":"171.682872ms","start":"2026-02-25T10:05:08.815745Z","end":"2026-02-25T10:05:08.987428Z","steps":["trace[322144044] 'process raft request' (duration: 57.150982ms)","trace[322144044] 'compare' (duration: 114.13201ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:09.050396Z","caller":"traceutil/trace.go:171","msg":"trace[37892218] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"178.088413ms","start":"2026-02-25T10:05:08.872229Z","end":"2026-02-25T10:05:09.050317Z","steps":["trace[37892218] 'process raft request' (duration: 177.901478ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:09.298521Z","caller":"traceutil/trace.go:171","msg":"trace[2064585998] transaction","detail":"{read_only:false; response_revision:85; number_of_response:1; }","duration":"245.245254ms","start":"2026-02-25T10:05:09.053247Z","end":"2026-02-25T10:05:09.298492Z","steps":["trace[2064585998] 'process raft request' (duration: 223.93262ms)","trace[2064585998] 'compare' (duration: 21.14067ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:09.544933Z","caller":"traceutil/trace.go:171","msg":"trace[982745980] transaction","detail":"{read_only:false; response_revision:88; number_of_response:1; }","duration":"186.902873ms","start":"2026-02-25T10:05:09.358005Z","end":"2026-02-25T10:05:09.544908Z","steps":["trace[982745980] 'process raft request' (duration: 121.507475ms)","trace[982745980] 'compare' (duration: 65.010929ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:09.980184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.586134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-25T10:05:09.980401Z","caller":"traceutil/trace.go:171","msg":"trace[168378092] linearizableReadLoop","detail":"{readStateIndex:94; appliedIndex:92; }","duration":"369.029808ms","start":"2026-02-25T10:05:09.611353Z","end":"2026-02-25T10:05:09.980383Z","steps":["trace[168378092] 'read index received' (duration: 127.041803ms)","trace[168378092] 'applied index is now lower than readState.Index' (duration: 241.987094ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:09.980501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.14852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T10:05:09.980533Z","caller":"traceutil/trace.go:171","msg":"trace[945376453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:91; }","duration":"369.239942ms","start":"2026-02-25T10:05:09.611283Z","end":"2026-02-25T10:05:09.980523Z","steps":["trace[945376453] 'agreement among raft nodes before linearized reading' (duration: 369.1584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:09.980576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:09.611259Z","time spent":"369.306914ms","remote":"127.0.0.1:37246","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-25T10:05:09.980696Z","caller":"traceutil/trace.go:171","msg":"trace[908622910] transaction","detail":"{read_only:false; response_revision:90; number_of_response:1; }","duration":"430.556117ms","start":"2026-02-25T10:05:09.550106Z","end":"2026-02-25T10:05:09.980662Z","steps":["trace[908622910] 'process raft request' (duration: 188.402209ms)","trace[908622910] 'compare' (duration: 241.39813ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:09.980729Z","caller":"traceutil/trace.go:171","msg":"trace[329377278] transaction","detail":"{read_only:false; response_revision:91; number_of_response:1; }","duration":"430.530408ms","start":"2026-02-25T10:05:09.550178Z","end":"2026-02-25T10:05:09.980709Z","steps":["trace[329377278] 'process raft request' (duration: 430.124218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:09.980861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:09.550079Z","time spent":"430.693581ms","remote":"127.0.0.1:37600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1917,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:05:09.98109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:09.550164Z","time spent":"430.688601ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:10.271504Z","caller":"traceutil/trace.go:171","msg":"trace[1041990481] transaction","detail":"{read_only:false; response_revision:92; number_of_response:1; }","duration":"284.98953ms","start":"2026-02-25T10:05:09.986483Z","end":"2026-02-25T10:05:10.271472Z","steps":["trace[1041990481] 'process raft request' (duration: 263.02565ms)","trace[1041990481] 'compare' (duration: 21.825367ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:10.492125Z","caller":"traceutil/trace.go:171","msg":"trace[312455755] transaction","detail":"{read_only:false; response_revision:93; number_of_response:1; }","duration":"214.232487ms","start":"2026-02-25T10:05:10.27786Z","end":"2026-02-25T10:05:10.492092Z","steps":["trace[312455755] 'process raft request' (duration: 133.106606ms)","trace[312455755] 'compare' (duration: 80.974387ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:10.811401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.292974ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-25T10:05:10.811513Z","caller":"traceutil/trace.go:171","msg":"trace[59830536] linearizableReadLoop","detail":"{readStateIndex:98; appliedIndex:97; }","duration":"199.534311ms","start":"2026-02-25T10:05:10.611955Z","end":"2026-02-25T10:05:10.81149Z","steps":["trace[59830536] 'read index received' (duration: 34.951µs)","trace[59830536] 'applied index is now lower than readState.Index' (duration: 199.49793ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:10.81163Z","caller":"traceutil/trace.go:171","msg":"trace[686142280] transaction","detail":"{read_only:false; response_revision:95; number_of_response:1; }","duration":"259.143654ms","start":"2026-02-25T10:05:10.55246Z","end":"2026-02-25T10:05:10.811604Z","steps":["trace[686142280] 'process raft request' (duration: 49.571312ms)","trace[686142280] 'compare' (duration: 209.086779ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:10.811718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.754136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T10:05:10.811832Z","caller":"traceutil/trace.go:171","msg":"trace[404107680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:95; }","duration":"199.90175ms","start":"2026-02-25T10:05:10.611912Z","end":"2026-02-25T10:05:10.811814Z","steps":["trace[404107680] 'agreement among raft nodes before linearized reading' (duration: 199.680004ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:11.083291Z","caller":"traceutil/trace.go:171","msg":"trace[1359279902] transaction","detail":"{read_only:false; response_revision:96; number_of_response:1; }","duration":"265.785329ms","start":"2026-02-25T10:05:10.81747Z","end":"2026-02-25T10:05:11.083256Z","steps":["trace[1359279902] 'process raft request' (duration: 227.253593ms)","trace[1359279902] 'compare' (duration: 38.403132ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:11.302343Z","caller":"traceutil/trace.go:171","msg":"trace[330577496] transaction","detail":"{read_only:false; response_revision:98; number_of_response:1; }","duration":"147.494895ms","start":"2026-02-25T10:05:11.154782Z","end":"2026-02-25T10:05:11.302277Z","steps":["trace[330577496] 'process raft request' (duration: 74.850595ms)","trace[330577496] 'compare' (duration: 72.156768ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:11.325228Z","caller":"traceutil/trace.go:171","msg":"trace[1055433719] transaction","detail":"{read_only:false; response_revision:99; number_of_response:1; }","duration":"108.595982ms","start":"2026-02-25T10:05:11.216613Z","end":"2026-02-25T10:05:11.325209Z","steps":["trace[1055433719] 'process raft request' (duration: 108.399187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:11.797576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.003433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-25T10:05:11.797818Z","caller":"traceutil/trace.go:171","msg":"trace[948776365] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"171.798565ms","start":"2026-02-25T10:05:11.626001Z","end":"2026-02-25T10:05:11.797799Z","steps":["trace[948776365] 'process raft request' (duration: 171.728833ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:11.797891Z","caller":"traceutil/trace.go:171","msg":"trace[1891127129] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"172.088122ms","start":"2026-02-25T10:05:11.625766Z","end":"2026-02-25T10:05:11.797855Z","steps":["trace[1891127129] 'process raft request' (duration: 36.715029ms)","trace[1891127129] 'compare' (duration: 134.717756ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:14.09381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.945939ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:05:14.093929Z","caller":"traceutil/trace.go:171","msg":"trace[1610410913] linearizableReadLoop","detail":"{readStateIndex:268; appliedIndex:267; }","duration":"101.070703ms","start":"2026-02-25T10:05:13.992844Z","end":"2026-02-25T10:05:14.093915Z","steps":["trace[1610410913] 'read index received' (duration: 43.121µs)","trace[1610410913] 'applied index is now lower than readState.Index' (duration: 101.025992ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:14.09403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.176305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T10:05:14.094059Z","caller":"traceutil/trace.go:171","msg":"trace[1910306923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:263; }","duration":"101.262207ms","start":"2026-02-25T10:05:13.992789Z","end":"2026-02-25T10:05:14.094051Z","steps":["trace[1910306923] 'agreement among raft nodes before linearized reading' (duration: 101.163365ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:14.094114Z","caller":"traceutil/trace.go:171","msg":"trace[943553370] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"276.752435ms","start":"2026-02-25T10:05:13.817325Z","end":"2026-02-25T10:05:14.094077Z","steps":["trace[943553370] 'process raft request' (duration: 88.483988ms)","trace[943553370] 'compare' (duration: 187.808695ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:14.694345Z","caller":"traceutil/trace.go:171","msg":"trace[395392581] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"211.663583ms","start":"2026-02-25T10:05:14.482649Z","end":"2026-02-25T10:05:14.694313Z","steps":["trace[395392581] 'process raft request' (duration: 136.792077ms)","trace[395392581] 'compare' (duration: 74.679361ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:15.184604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.951885ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:05:15.184715Z","caller":"traceutil/trace.go:171","msg":"trace[1406926830] linearizableReadLoop","detail":"{readStateIndex:281; appliedIndex:280; }","duration":"408.572087ms","start":"2026-02-25T10:05:14.776126Z","end":"2026-02-25T10:05:15.184698Z","steps":["trace[1406926830] 'read index received' (duration: 35.441257ms)","trace[1406926830] 'applied index is now lower than readState.Index' (duration: 373.12954ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:15.18482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.672015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:15.184792Z","caller":"traceutil/trace.go:171","msg":"trace[165228410] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"441.756728ms","start":"2026-02-25T10:05:14.742987Z","end":"2026-02-25T10:05:15.184744Z","steps":["trace[165228410] 'process raft request' (duration: 68.579597ms)","trace[165228410] 'compare' (duration: 372.829181ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:15.184857Z","caller":"traceutil/trace.go:171","msg":"trace[1237015633] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:0; response_revision:276; }","duration":"435.723797ms","start":"2026-02-25T10:05:14.749122Z","end":"2026-02-25T10:05:15.184846Z","steps":["trace[1237015633] 'agreement among raft nodes before linearized reading' (duration: 435.642545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:15.184901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.749115Z","time spent":"435.779028ms","remote":"127.0.0.1:37476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" "} {"level":"warn","ts":"2026-02-25T10:05:15.185012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.742975Z","time spent":"441.924443ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:15.261339Z","caller":"traceutil/trace.go:171","msg":"trace[817905895] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"438.114517ms","start":"2026-02-25T10:05:14.823185Z","end":"2026-02-25T10:05:15.261299Z","steps":["trace[817905895] 'process raft request' (duration: 437.907942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:15.261576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.823163Z","time spent":"438.300671ms","remote":"127.0.0.1:37342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T10:05:15.54219Z","caller":"traceutil/trace.go:171","msg":"trace[953738603] transaction","detail":"{read_only:false; number_of_response:0; response_revision:277; }","duration":"614.477905ms","start":"2026-02-25T10:05:14.92766Z","end":"2026-02-25T10:05:15.542138Z","steps":["trace[953738603] 'process raft request' (duration: 584.136845ms)","trace[953738603] 'compare' (duration: 30.167756ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:15.542254Z","caller":"traceutil/trace.go:171","msg":"trace[920033176] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"275.271647ms","start":"2026-02-25T10:05:15.266962Z","end":"2026-02-25T10:05:15.542234Z","steps":["trace[920033176] 'process raft request' (duration: 275.203645ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:15.54223Z","caller":"traceutil/trace.go:171","msg":"trace[1773869055] transaction","detail":"{read_only:false; number_of_response:0; response_revision:277; }","duration":"599.792148ms","start":"2026-02-25T10:05:14.94238Z","end":"2026-02-25T10:05:15.542172Z","steps":["trace[1773869055] 'process raft request' (duration: 599.675025ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:15.542284Z","caller":"traceutil/trace.go:171","msg":"trace[1944657158] transaction","detail":"{read_only:false; number_of_response:0; response_revision:277; }","duration":"599.745816ms","start":"2026-02-25T10:05:14.942508Z","end":"2026-02-25T10:05:15.542254Z","steps":["trace[1944657158] 'process raft request' (duration: 599.619033ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:15.542333Z","caller":"traceutil/trace.go:171","msg":"trace[1252347942] transaction","detail":"{read_only:false; number_of_response:0; response_revision:277; }","duration":"599.826199ms","start":"2026-02-25T10:05:14.942499Z","end":"2026-02-25T10:05:15.542325Z","steps":["trace[1252347942] 'process raft request' (duration: 599.580183ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:15.542353Z","caller":"traceutil/trace.go:171","msg":"trace[695196495] transaction","detail":"{read_only:false; number_of_response:0; response_revision:277; }","duration":"598.872555ms","start":"2026-02-25T10:05:14.943442Z","end":"2026-02-25T10:05:15.542315Z","steps":["trace[695196495] 'process raft request' (duration: 598.7026ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:15.542395Z","caller":"traceutil/trace.go:171","msg":"trace[1854796719] linearizableReadLoop","detail":"{readStateIndex:288; appliedIndex:281; }","duration":"357.600049ms","start":"2026-02-25T10:05:15.18474Z","end":"2026-02-25T10:05:15.54234Z","steps":["trace[1854796719] 'read index received' (duration: 76.487746ms)","trace[1854796719] 'applied index is now lower than readState.Index' (duration: 281.107883ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:15.542444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.94235Z","time spent":"600.004943ms","remote":"127.0.0.1:37446","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:05:15.542461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.942487Z","time spent":"599.88951ms","remote":"127.0.0.1:37446","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:05:15.542465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.942477Z","time spent":"599.89205ms","remote":"127.0.0.1:37446","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:05:15.542499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.943435Z","time spent":"598.995319ms","remote":"127.0.0.1:37446","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T10:05:15.54225Z","caller":"traceutil/trace.go:171","msg":"trace[1083606573] transaction","detail":"{read_only:false; number_of_response:0; response_revision:277; }","duration":"599.696945ms","start":"2026-02-25T10:05:14.942507Z","end":"2026-02-25T10:05:15.542204Z","steps":["trace[1083606573] 'process raft request' (duration: 599.601373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:15.542595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.406859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:15.54269Z","caller":"traceutil/trace.go:171","msg":"trace[1331428416] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:278; }","duration":"716.527962ms","start":"2026-02-25T10:05:14.826121Z","end":"2026-02-25T10:05:15.542649Z","steps":["trace[1331428416] 'agreement among raft nodes before linearized reading' (duration: 716.231735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:15.542692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.942498Z","time spent":"600.144457ms","remote":"127.0.0.1:37446","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:05:15.542637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.84003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-02-25T10:05:15.542723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.676546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-02-25T10:05:15.542732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.826059Z","time spent":"716.664836ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-02-25T10:05:15.54274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.095084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:15.542748Z","caller":"traceutil/trace.go:171","msg":"trace[1892000050] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:278; }","duration":"719.005904ms","start":"2026-02-25T10:05:14.823728Z","end":"2026-02-25T10:05:15.542734Z","steps":["trace[1892000050] 'agreement among raft nodes before linearized reading' (duration: 718.783529ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:15.542763Z","caller":"traceutil/trace.go:171","msg":"trace[1823277494] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:278; }","duration":"354.759358ms","start":"2026-02-25T10:05:15.18799Z","end":"2026-02-25T10:05:15.54275Z","steps":["trace[1823277494] 'agreement among raft nodes before linearized reading' (duration: 354.671566ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:15.542789Z","caller":"traceutil/trace.go:171","msg":"trace[1845458988] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:278; }","duration":"758.186506ms","start":"2026-02-25T10:05:14.784588Z","end":"2026-02-25T10:05:15.542774Z","steps":["trace[1845458988] 'agreement among raft nodes before linearized reading' (duration: 758.094114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:15.542804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:15.187966Z","time spent":"354.831041ms","remote":"127.0.0.1:37384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-25T10:05:15.542831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.784565Z","time spent":"758.260608ms","remote":"127.0.0.1:37370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-25T10:05:15.542796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.823698Z","time spent":"719.088386ms","remote":"127.0.0.1:37626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-02-25T10:05:15.543272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:14.927629Z","time spent":"614.67839ms","remote":"127.0.0.1:37432","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T10:05:15.697521Z","caller":"traceutil/trace.go:171","msg":"trace[2116417603] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"150.024269ms","start":"2026-02-25T10:05:15.547465Z","end":"2026-02-25T10:05:15.697489Z","steps":["trace[2116417603] 'process raft request' (duration: 137.624927ms)","trace[2116417603] 'compare' (duration: 12.151536ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:15.697546Z","caller":"traceutil/trace.go:171","msg":"trace[12017484] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"123.746412ms","start":"2026-02-25T10:05:15.573776Z","end":"2026-02-25T10:05:15.697522Z","steps":["trace[12017484] 'process raft request' (duration: 123.696831ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:15.697572Z","caller":"traceutil/trace.go:171","msg":"trace[1243091293] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:289; }","duration":"149.900526ms","start":"2026-02-25T10:05:15.54764Z","end":"2026-02-25T10:05:15.697541Z","steps":["trace[1243091293] 'read index received' (duration: 21.94557ms)","trace[1243091293] 'applied index is now lower than readState.Index' (duration: 127.948886ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:15.697647Z","caller":"traceutil/trace.go:171","msg":"trace[1963260744] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"149.795884ms","start":"2026-02-25T10:05:15.547839Z","end":"2026-02-25T10:05:15.697635Z","steps":["trace[1963260744] 'process raft request' (duration: 149.565198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:15.697794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.132412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3707"} {"level":"info","ts":"2026-02-25T10:05:15.697831Z","caller":"traceutil/trace.go:171","msg":"trace[936682624] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:282; }","duration":"150.212624ms","start":"2026-02-25T10:05:15.547609Z","end":"2026-02-25T10:05:15.697821Z","steps":["trace[936682624] 'agreement among raft nodes before linearized reading' (duration: 150.068961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:15.69786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.05218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-25T10:05:15.697941Z","caller":"traceutil/trace.go:171","msg":"trace[1461941304] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:282; }","duration":"150.157943ms","start":"2026-02-25T10:05:15.547765Z","end":"2026-02-25T10:05:15.697923Z","steps":["trace[1461941304] 'agreement among raft nodes before linearized reading' (duration: 150.002449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:15.697929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.987443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3707"} {"level":"info","ts":"2026-02-25T10:05:15.698041Z","caller":"traceutil/trace.go:171","msg":"trace[1129514580] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:282; }","duration":"149.123476ms","start":"2026-02-25T10:05:15.548893Z","end":"2026-02-25T10:05:15.698016Z","steps":["trace[1129514580] 'agreement among raft nodes before linearized reading' (duration: 148.88221ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:15.872489Z","caller":"traceutil/trace.go:171","msg":"trace[1360300632] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"162.140753ms","start":"2026-02-25T10:05:15.710322Z","end":"2026-02-25T10:05:15.872462Z","steps":["trace[1360300632] 'process raft request' (duration: 161.963968ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:15.951154Z","caller":"traceutil/trace.go:171","msg":"trace[722735824] linearizableReadLoop","detail":"{readStateIndex:300; appliedIndex:296; }","duration":"106.977711ms","start":"2026-02-25T10:05:15.844153Z","end":"2026-02-25T10:05:15.95113Z","steps":["trace[722735824] 'read index received' (duration: 28.157546ms)","trace[722735824] 'applied index is now lower than readState.Index' (duration: 78.819055ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:15.951227Z","caller":"traceutil/trace.go:171","msg":"trace[292229553] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"202.276008ms","start":"2026-02-25T10:05:15.7489Z","end":"2026-02-25T10:05:15.951176Z","steps":["trace[292229553] 'process raft request' (duration: 202.129644ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:15.95126Z","caller":"traceutil/trace.go:171","msg":"trace[875653551] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"202.068453ms","start":"2026-02-25T10:05:15.749149Z","end":"2026-02-25T10:05:15.951218Z","steps":["trace[875653551] 'process raft request' (duration: 201.930099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:15.95151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.351459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T10:05:15.95151Z","caller":"traceutil/trace.go:171","msg":"trace[2119230622] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"203.485918ms","start":"2026-02-25T10:05:15.747985Z","end":"2026-02-25T10:05:15.951471Z","steps":["trace[2119230622] 'process raft request' (duration: 202.889603ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:15.95156Z","caller":"traceutil/trace.go:171","msg":"trace[295036318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:289; }","duration":"107.474352ms","start":"2026-02-25T10:05:15.844073Z","end":"2026-02-25T10:05:15.951548Z","steps":["trace[295036318] 'agreement among raft nodes before linearized reading' (duration: 107.190605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:16.463134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.544581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:05:16.463247Z","caller":"traceutil/trace.go:171","msg":"trace[1776581789] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"294.380576ms","start":"2026-02-25T10:05:16.168847Z","end":"2026-02-25T10:05:16.463227Z","steps":["trace[1776581789] 'process raft request' (duration: 161.65246ms)","trace[1776581789] 'compare' (duration: 132.387287ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:16.46401Z","caller":"traceutil/trace.go:171","msg":"trace[631630147] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"272.101718ms","start":"2026-02-25T10:05:16.191883Z","end":"2026-02-25T10:05:16.463984Z","steps":["trace[631630147] 'process raft request' (duration: 272.055306ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:16.464368Z","caller":"traceutil/trace.go:171","msg":"trace[147285602] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"273.366339ms","start":"2026-02-25T10:05:16.190988Z","end":"2026-02-25T10:05:16.464354Z","steps":["trace[147285602] 'process raft request' (duration: 272.848206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:16.80551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.660004ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:05:16.80569Z","caller":"traceutil/trace.go:171","msg":"trace[917526741] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"255.839659ms","start":"2026-02-25T10:05:16.54982Z","end":"2026-02-25T10:05:16.80566Z","steps":["trace[917526741] 'process raft request' (duration: 116.917409ms)","trace[917526741] 'compare' (duration: 138.397947ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:17.793966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.901229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:05:17.794044Z","caller":"traceutil/trace.go:171","msg":"trace[1323250585] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:325; }","duration":"735.99307ms","start":"2026-02-25T10:05:17.058037Z","end":"2026-02-25T10:05:17.79403Z","steps":["trace[1323250585] 'read index received' (duration: 55.271µs)","trace[1323250585] 'applied index is now lower than readState.Index' (duration: 735.936559ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:17.794148Z","caller":"traceutil/trace.go:171","msg":"trace[241857440] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"904.072922ms","start":"2026-02-25T10:05:16.890067Z","end":"2026-02-25T10:05:17.79414Z","steps":["trace[241857440] 'process raft request' (duration: 58.944547ms)","trace[241857440] 'compare' (duration: 844.52253ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:17.794204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:16.89004Z","time spent":"904.138454ms","remote":"127.0.0.1:37476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:05:17.794449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.416701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:17.794474Z","caller":"traceutil/trace.go:171","msg":"trace[361533346] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:315; }","duration":"736.484683ms","start":"2026-02-25T10:05:17.057981Z","end":"2026-02-25T10:05:17.794466Z","steps":["trace[361533346] 'agreement among raft nodes before linearized reading' (duration: 736.406241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:17.794535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:17.057957Z","time spent":"736.533083ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T10:05:17.794543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.134122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T10:05:17.794639Z","caller":"traceutil/trace.go:171","msg":"trace[715837063] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:315; }","duration":"605.287435ms","start":"2026-02-25T10:05:17.189333Z","end":"2026-02-25T10:05:17.79462Z","steps":["trace[715837063] 'agreement among raft nodes before linearized reading' (duration: 605.116771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:17.794677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:17.1893Z","time spent":"605.369148ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T10:05:17.954612Z","caller":"traceutil/trace.go:171","msg":"trace[1526083853] linearizableReadLoop","detail":"{readStateIndex:327; appliedIndex:326; }","duration":"155.877845ms","start":"2026-02-25T10:05:17.798654Z","end":"2026-02-25T10:05:17.954531Z","steps":["trace[1526083853] 'read index received' (duration: 154.265245ms)","trace[1526083853] 'applied index is now lower than readState.Index' (duration: 1.61084ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:17.954894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.184872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T10:05:17.954945Z","caller":"traceutil/trace.go:171","msg":"trace[263797501] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:316; }","duration":"156.347147ms","start":"2026-02-25T10:05:17.798584Z","end":"2026-02-25T10:05:17.954932Z","steps":["trace[263797501] 'agreement among raft nodes before linearized reading' (duration: 156.132611ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:17.954868Z","caller":"traceutil/trace.go:171","msg":"trace[56727084] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"156.374697ms","start":"2026-02-25T10:05:17.798302Z","end":"2026-02-25T10:05:17.954676Z","steps":["trace[56727084] 'process raft request' (duration: 154.530152ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:17.95629Z","caller":"traceutil/trace.go:171","msg":"trace[125610541] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"157.077765ms","start":"2026-02-25T10:05:17.799171Z","end":"2026-02-25T10:05:17.956249Z","steps":["trace[125610541] 'process raft request' (duration: 156.657725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:19.319995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.416517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslicemirroring-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:19.32012Z","caller":"traceutil/trace.go:171","msg":"trace[1853860104] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslicemirroring-controller; range_end:; response_count:0; response_revision:338; }","duration":"108.621631ms","start":"2026-02-25T10:05:19.211468Z","end":"2026-02-25T10:05:19.320089Z","steps":["trace[1853860104] 'range keys from in-memory index tree' (duration: 108.279933ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:23.300914Z","caller":"traceutil/trace.go:171","msg":"trace[1440519578] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"412.461014ms","start":"2026-02-25T10:05:22.888416Z","end":"2026-02-25T10:05:23.300877Z","steps":["trace[1440519578] 'process raft request' (duration: 412.248018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:23.301197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:22.888408Z","time spent":"412.668659ms","remote":"127.0.0.1:37446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4400,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:24.302082Z","caller":"traceutil/trace.go:171","msg":"trace[373172563] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:428; }","duration":"310.374205ms","start":"2026-02-25T10:05:23.991683Z","end":"2026-02-25T10:05:24.302057Z","steps":["trace[373172563] 'read index received' (duration: 302.51696ms)","trace[373172563] 'applied index is now lower than readState.Index' (duration: 7.856215ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:24.302183Z","caller":"traceutil/trace.go:171","msg":"trace[14436109] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"994.034045ms","start":"2026-02-25T10:05:23.308135Z","end":"2026-02-25T10:05:24.302169Z","steps":["trace[14436109] 'process raft request' (duration: 986.129948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:24.302278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.604911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:05:24.302281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:23.308105Z","time spent":"994.114847ms","remote":"127.0.0.1:37714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2881,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:24.302312Z","caller":"traceutil/trace.go:171","msg":"trace[729415168] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"310.700323ms","start":"2026-02-25T10:05:23.991602Z","end":"2026-02-25T10:05:24.302302Z","steps":["trace[729415168] 'agreement among raft nodes before linearized reading' (duration: 310.635781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:24.302341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:23.991569Z","time spent":"310.764905ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:05:24.302408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.423616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T10:05:24.302433Z","caller":"traceutil/trace.go:171","msg":"trace[199961369] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:416; }","duration":"308.467977ms","start":"2026-02-25T10:05:23.993957Z","end":"2026-02-25T10:05:24.302425Z","steps":["trace[199961369] 'agreement among raft nodes before linearized reading' (duration: 308.419006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:24.302451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:23.993945Z","time spent":"308.501588ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:05:24.302601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.268429ms","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-25T10:05:24.302624Z","caller":"traceutil/trace.go:171","msg":"trace[200554449] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:416; }","duration":"262.34351ms","start":"2026-02-25T10:05:24.040274Z","end":"2026-02-25T10:05:24.302618Z","steps":["trace[200554449] 'agreement among raft nodes before linearized reading' (duration: 262.277839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:24.302728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.082772ms","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-25T10:05:24.302855Z","caller":"traceutil/trace.go:171","msg":"trace[532650529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:416; }","duration":"220.284847ms","start":"2026-02-25T10:05:24.082527Z","end":"2026-02-25T10:05:24.302812Z","steps":["trace[532650529] 'agreement among raft nodes before linearized reading' (duration: 220.00813ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:26.023835Z","caller":"traceutil/trace.go:171","msg":"trace[1848131985] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"127.791537ms","start":"2026-02-25T10:05:25.896011Z","end":"2026-02-25T10:05:26.023803Z","steps":["trace[1848131985] 'process raft request' (duration: 127.618732ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:30.254866Z","caller":"traceutil/trace.go:171","msg":"trace[601801762] transaction","detail":"{read_only:false; number_of_response:1; response_revision:451; }","duration":"117.106434ms","start":"2026-02-25T10:05:30.137718Z","end":"2026-02-25T10:05:30.254824Z","steps":["trace[601801762] 'process raft request' (duration: 38.076119ms)","trace[601801762] 'compare' (duration: 78.751178ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:30.306443Z","caller":"traceutil/trace.go:171","msg":"trace[320343326] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"161.503772ms","start":"2026-02-25T10:05:30.144912Z","end":"2026-02-25T10:05:30.306416Z","steps":["trace[320343326] 'process raft request' (duration: 161.42268ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:30.306491Z","caller":"traceutil/trace.go:171","msg":"trace[1441972527] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"165.616499ms","start":"2026-02-25T10:05:30.140842Z","end":"2026-02-25T10:05:30.306459Z","steps":["trace[1441972527] 'process raft request' (duration: 165.354391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:30.580458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.795292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:05:30.580595Z","caller":"traceutil/trace.go:171","msg":"trace[820228366] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"254.451184ms","start":"2026-02-25T10:05:30.326117Z","end":"2026-02-25T10:05:30.580568Z","steps":["trace[820228366] 'process raft request' (duration: 135.463207ms)","trace[820228366] 'compare' (duration: 118.592668ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:30.581819Z","caller":"traceutil/trace.go:171","msg":"trace[503635278] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"222.158482ms","start":"2026-02-25T10:05:30.359643Z","end":"2026-02-25T10:05:30.581801Z","steps":["trace[503635278] 'process raft request' (duration: 222.018528ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:30.582149Z","caller":"traceutil/trace.go:171","msg":"trace[948713846] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"222.427607ms","start":"2026-02-25T10:05:30.359673Z","end":"2026-02-25T10:05:30.5821Z","steps":["trace[948713846] 'process raft request' (duration: 222.048819ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:30.582863Z","caller":"traceutil/trace.go:171","msg":"trace[1860041067] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"223.106314ms","start":"2026-02-25T10:05:30.359743Z","end":"2026-02-25T10:05:30.582849Z","steps":["trace[1860041067] 'process raft request' (duration: 222.005538ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:30.582877Z","caller":"traceutil/trace.go:171","msg":"trace[1789388700] linearizableReadLoop","detail":"{readStateIndex:477; appliedIndex:472; }","duration":"222.96404ms","start":"2026-02-25T10:05:30.359888Z","end":"2026-02-25T10:05:30.582852Z","steps":["trace[1789388700] 'read index received' (duration: 101.763651ms)","trace[1789388700] 'applied index is now lower than readState.Index' (duration: 121.199299ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:30.583013Z","caller":"traceutil/trace.go:171","msg":"trace[779246858] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"223.219486ms","start":"2026-02-25T10:05:30.359783Z","end":"2026-02-25T10:05:30.583003Z","steps":["trace[779246858] 'process raft request' (duration: 223.010632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:30.583305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.617898ms","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-25T10:05:30.583366Z","caller":"traceutil/trace.go:171","msg":"trace[1797621300] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:463; }","duration":"215.735631ms","start":"2026-02-25T10:05:30.367614Z","end":"2026-02-25T10:05:30.58335Z","steps":["trace[1797621300] 'agreement among raft nodes before linearized reading' (duration: 215.577967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:30.583406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.726654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T10:05:30.583466Z","caller":"traceutil/trace.go:171","msg":"trace[935251089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:463; }","duration":"158.810567ms","start":"2026-02-25T10:05:30.424641Z","end":"2026-02-25T10:05:30.583452Z","steps":["trace[935251089] 'agreement among raft nodes before linearized reading' (duration: 158.693494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:30.583588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.96078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T10:05:30.583637Z","caller":"traceutil/trace.go:171","msg":"trace[158131637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:463; }","duration":"159.025243ms","start":"2026-02-25T10:05:30.424597Z","end":"2026-02-25T10:05:30.583622Z","steps":["trace[158131637] 'agreement among raft nodes before linearized reading' (duration: 158.94919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:30.583654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.76497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4173"} {"level":"info","ts":"2026-02-25T10:05:30.583707Z","caller":"traceutil/trace.go:171","msg":"trace[422236035] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:463; }","duration":"223.844322ms","start":"2026-02-25T10:05:30.359853Z","end":"2026-02-25T10:05:30.583698Z","steps":["trace[422236035] 'agreement among raft nodes before linearized reading' (duration: 223.76389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:30.583804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.680078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T10:05:30.583847Z","caller":"traceutil/trace.go:171","msg":"trace[1402270571] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:463; }","duration":"173.74187ms","start":"2026-02-25T10:05:30.410092Z","end":"2026-02-25T10:05:30.583834Z","steps":["trace[1402270571] 'agreement among raft nodes before linearized reading' (duration: 173.672858ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:30.894607Z","caller":"traceutil/trace.go:171","msg":"trace[411996525] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"302.396845ms","start":"2026-02-25T10:05:30.59215Z","end":"2026-02-25T10:05:30.894547Z","steps":["trace[411996525] 'process raft request' (duration: 302.337934ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:30.894639Z","caller":"traceutil/trace.go:171","msg":"trace[389579526] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"303.882841ms","start":"2026-02-25T10:05:30.590729Z","end":"2026-02-25T10:05:30.894612Z","steps":["trace[389579526] 'process raft request' (duration: 303.714537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:30.894772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:30.59212Z","time spent":"302.57205ms","remote":"127.0.0.1:37698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:30.894786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:30.590692Z","time spent":"304.010005ms","remote":"127.0.0.1:37720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3818,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:30.894841Z","caller":"traceutil/trace.go:171","msg":"trace[1871257642] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"305.594041ms","start":"2026-02-25T10:05:30.589226Z","end":"2026-02-25T10:05:30.89482Z","steps":["trace[1871257642] 'process raft request' (duration: 305.17223ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:30.894854Z","caller":"traceutil/trace.go:171","msg":"trace[319627635] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"305.801507ms","start":"2026-02-25T10:05:30.589011Z","end":"2026-02-25T10:05:30.894812Z","steps":["trace[319627635] 'process raft request' (duration: 272.164494ms)","trace[319627635] 'compare' (duration: 33.11897ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:30.894961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:30.589218Z","time spent":"305.673463ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:30.895044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:30.589002Z","time spent":"305.93638ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:39.148122Z","caller":"traceutil/trace.go:171","msg":"trace[1092705813] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"152.932509ms","start":"2026-02-25T10:05:38.995164Z","end":"2026-02-25T10:05:39.148096Z","steps":["trace[1092705813] 'process raft request' (duration: 152.781015ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:42.038662Z","caller":"traceutil/trace.go:171","msg":"trace[949165259] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"404.789982ms","start":"2026-02-25T10:05:41.63383Z","end":"2026-02-25T10:05:42.03862Z","steps":["trace[949165259] 'process raft request' (duration: 404.30666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:42.038867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:41.633807Z","time spent":"404.963497ms","remote":"127.0.0.1:37366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317268,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:05:42.26481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.383773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:05:42.265074Z","caller":"traceutil/trace.go:171","msg":"trace[1779917625] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"200.732546ms","start":"2026-02-25T10:05:42.064312Z","end":"2026-02-25T10:05:42.265045Z","steps":["trace[1779917625] 'process raft request' (duration: 200.656835ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:42.265224Z","caller":"traceutil/trace.go:171","msg":"trace[695721178] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"200.957291ms","start":"2026-02-25T10:05:42.064256Z","end":"2026-02-25T10:05:42.265213Z","steps":["trace[695721178] 'process raft request' (duration: 43.021765ms)","trace[695721178] 'compare' (duration: 157.141498ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:42.634877Z","caller":"traceutil/trace.go:171","msg":"trace[367487764] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:512; }","duration":"219.95924ms","start":"2026-02-25T10:05:42.414885Z","end":"2026-02-25T10:05:42.634844Z","steps":["trace[367487764] 'read index received' (duration: 151.553916ms)","trace[367487764] 'applied index is now lower than readState.Index' (duration: 68.404004ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:42.634974Z","caller":"traceutil/trace.go:171","msg":"trace[392813218] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"351.949195ms","start":"2026-02-25T10:05:42.282987Z","end":"2026-02-25T10:05:42.634936Z","steps":["trace[392813218] 'process raft request' (duration: 283.391707ms)","trace[392813218] 'compare' (duration: 68.251231ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:42.635196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.288208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T10:05:42.635251Z","caller":"traceutil/trace.go:171","msg":"trace[872388604] range","detail":"{range_begin:/registry/masterleases/199.19.213.205; range_end:; response_count:1; response_revision:497; }","duration":"220.426932ms","start":"2026-02-25T10:05:42.414808Z","end":"2026-02-25T10:05:42.635235Z","steps":["trace[872388604] 'agreement among raft nodes before linearized reading' (duration: 220.175445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:42.635232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:42.282966Z","time spent":"352.125409ms","remote":"127.0.0.1:37370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6516,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:05:42.880728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.905379ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:05:42.88098Z","caller":"traceutil/trace.go:171","msg":"trace[408254334] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:517; }","duration":"161.540241ms","start":"2026-02-25T10:05:42.719415Z","end":"2026-02-25T10:05:42.880955Z","steps":["trace[408254334] 'read index received' (duration: 16.226163ms)","trace[408254334] 'applied index is now lower than readState.Index' (duration: 145.312428ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:42.880993Z","caller":"traceutil/trace.go:171","msg":"trace[1554985422] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"176.641608ms","start":"2026-02-25T10:05:42.704299Z","end":"2026-02-25T10:05:42.880941Z","steps":["trace[1554985422] 'process raft request' (duration: 31.415541ms)","trace[1554985422] 'compare' (duration: 144.705735ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:42.881059Z","caller":"traceutil/trace.go:171","msg":"trace[334502051] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"176.549206ms","start":"2026-02-25T10:05:42.704458Z","end":"2026-02-25T10:05:42.881008Z","steps":["trace[334502051] 'process raft request' (duration: 176.402512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:42.881222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.792557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-02-25T10:05:42.881284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.307419ms","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-25T10:05:42.882052Z","caller":"traceutil/trace.go:171","msg":"trace[518368348] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:502; }","duration":"162.590737ms","start":"2026-02-25T10:05:42.719408Z","end":"2026-02-25T10:05:42.881998Z","steps":["trace[518368348] 'agreement among raft nodes before linearized reading' (duration: 161.674604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:42.88133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.772114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T10:05:42.88226Z","caller":"traceutil/trace.go:171","msg":"trace[1043207882] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:502; }","duration":"149.238482ms","start":"2026-02-25T10:05:42.73289Z","end":"2026-02-25T10:05:42.882128Z","steps":["trace[1043207882] 'agreement among raft nodes before linearized reading' (duration: 148.284909ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:42.882303Z","caller":"traceutil/trace.go:171","msg":"trace[1184359772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:502; }","duration":"103.82851ms","start":"2026-02-25T10:05:42.778452Z","end":"2026-02-25T10:05:42.882281Z","steps":["trace[1184359772] 'agreement among raft nodes before linearized reading' (duration: 102.757794ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:43.184141Z","caller":"traceutil/trace.go:171","msg":"trace[39714988] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"138.839726ms","start":"2026-02-25T10:05:43.045265Z","end":"2026-02-25T10:05:43.184105Z","steps":["trace[39714988] 'process raft request' (duration: 124.971829ms)","trace[39714988] 'compare' (duration: 13.710983ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:43.209376Z","caller":"traceutil/trace.go:171","msg":"trace[1834544353] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"159.489623ms","start":"2026-02-25T10:05:43.049864Z","end":"2026-02-25T10:05:43.209353Z","steps":["trace[1834544353] 'process raft request' (duration: 159.186376ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:43.209433Z","caller":"traceutil/trace.go:171","msg":"trace[1726648445] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"156.684097ms","start":"2026-02-25T10:05:43.052724Z","end":"2026-02-25T10:05:43.209408Z","steps":["trace[1726648445] 'process raft request' (duration: 156.477911ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:43.338513Z","caller":"traceutil/trace.go:171","msg":"trace[574498421] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"122.690476ms","start":"2026-02-25T10:05:43.215797Z","end":"2026-02-25T10:05:43.338487Z","steps":["trace[574498421] 'read index received' (duration: 120.822462ms)","trace[574498421] 'applied index is now lower than readState.Index' (duration: 1.867014ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:43.338867Z","caller":"traceutil/trace.go:171","msg":"trace[886458836] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"123.221178ms","start":"2026-02-25T10:05:43.215565Z","end":"2026-02-25T10:05:43.338786Z","steps":["trace[886458836] 'process raft request' (duration: 121.135658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:43.338952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.112556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:43.339366Z","caller":"traceutil/trace.go:171","msg":"trace[1058745179] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:511; }","duration":"123.611537ms","start":"2026-02-25T10:05:43.215695Z","end":"2026-02-25T10:05:43.339307Z","steps":["trace[1058745179] 'agreement among raft nodes before linearized reading' (duration: 123.048664ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:43.518632Z","caller":"traceutil/trace.go:171","msg":"trace[1809781428] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"302.639692ms","start":"2026-02-25T10:05:43.215917Z","end":"2026-02-25T10:05:43.518556Z","steps":["trace[1809781428] 'process raft request' (duration: 238.821006ms)","trace[1809781428] 'compare' (duration: 63.436597ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:43.518838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:43.215909Z","time spent":"302.815826ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:43.51916Z","caller":"traceutil/trace.go:171","msg":"trace[231001248] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"226.507335ms","start":"2026-02-25T10:05:43.292617Z","end":"2026-02-25T10:05:43.519124Z","steps":["trace[231001248] 'process raft request' (duration: 225.974853ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:43.521355Z","caller":"traceutil/trace.go:171","msg":"trace[1162511980] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"173.574776ms","start":"2026-02-25T10:05:43.347748Z","end":"2026-02-25T10:05:43.521322Z","steps":["trace[1162511980] 'process raft request' (duration: 173.472594ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:43.52142Z","caller":"traceutil/trace.go:171","msg":"trace[805974221] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"177.226072ms","start":"2026-02-25T10:05:43.344171Z","end":"2026-02-25T10:05:43.521397Z","steps":["trace[805974221] 'process raft request' (duration: 176.944285ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:43.52165Z","caller":"traceutil/trace.go:171","msg":"trace[1484625359] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"177.915228ms","start":"2026-02-25T10:05:43.343724Z","end":"2026-02-25T10:05:43.521639Z","steps":["trace[1484625359] 'process raft request' (duration: 177.253353ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:43.521657Z","caller":"traceutil/trace.go:171","msg":"trace[495506753] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"176.840823ms","start":"2026-02-25T10:05:43.344802Z","end":"2026-02-25T10:05:43.521643Z","steps":["trace[495506753] 'process raft request' (duration: 176.357871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:44.139523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.202458ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:05:44.139926Z","caller":"traceutil/trace.go:171","msg":"trace[1122105566] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"610.20281ms","start":"2026-02-25T10:05:43.529673Z","end":"2026-02-25T10:05:44.139876Z","steps":["trace[1122105566] 'process raft request' (duration: 405.55289ms)","trace[1122105566] 'compare' (duration: 204.082055ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:44.140026Z","caller":"traceutil/trace.go:171","msg":"trace[452279651] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"609.989455ms","start":"2026-02-25T10:05:43.530012Z","end":"2026-02-25T10:05:44.140002Z","steps":["trace[452279651] 'process raft request' (duration: 609.620156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:44.140126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:43.530005Z","time spent":"610.075576ms","remote":"127.0.0.1:37446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14906,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:44.140166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:43.529663Z","time spent":"610.363393ms","remote":"127.0.0.1:37720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4272,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:44.140393Z","caller":"traceutil/trace.go:171","msg":"trace[2130960539] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:535; }","duration":"606.483682ms","start":"2026-02-25T10:05:43.533895Z","end":"2026-02-25T10:05:44.140379Z","steps":["trace[2130960539] 'read index received' (duration: 49.853307ms)","trace[2130960539] 'applied index is now lower than readState.Index' (duration: 556.629405ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:44.140592Z","caller":"traceutil/trace.go:171","msg":"trace[1440023832] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"606.81191ms","start":"2026-02-25T10:05:43.533749Z","end":"2026-02-25T10:05:44.140561Z","steps":["trace[1440023832] 'process raft request' (duration: 606.538583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:44.140663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.757238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4313"} {"level":"info","ts":"2026-02-25T10:05:44.140694Z","caller":"traceutil/trace.go:171","msg":"trace[646555093] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:523; }","duration":"606.83315ms","start":"2026-02-25T10:05:43.533851Z","end":"2026-02-25T10:05:44.140684Z","steps":["trace[646555093] 'agreement among raft nodes before linearized reading' (duration: 606.698617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:44.140732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:43.533835Z","time spent":"606.889701ms","remote":"127.0.0.1:37698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":4336,"request content":"key:\"/registry/deployments/kube-system/cilium-operator\" "} {"level":"info","ts":"2026-02-25T10:05:44.140944Z","caller":"traceutil/trace.go:171","msg":"trace[361217753] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"609.563365ms","start":"2026-02-25T10:05:43.53136Z","end":"2026-02-25T10:05:44.140923Z","steps":["trace[361217753] 'process raft request' (duration: 608.582462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:44.141036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:43.531352Z","time spent":"609.642687ms","remote":"127.0.0.1:37446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3600,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:44.14103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.716247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:44.141118Z","caller":"traceutil/trace.go:171","msg":"trace[412154574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"150.85006ms","start":"2026-02-25T10:05:43.990245Z","end":"2026-02-25T10:05:44.141095Z","steps":["trace[412154574] 'agreement among raft nodes before linearized reading' (duration: 150.707477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:44.141214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.586749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329932"} {"level":"info","ts":"2026-02-25T10:05:44.141288Z","caller":"traceutil/trace.go:171","msg":"trace[1094919647] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:523; }","duration":"538.709372ms","start":"2026-02-25T10:05:43.602551Z","end":"2026-02-25T10:05:44.141261Z","steps":["trace[1094919647] 'agreement among raft nodes before linearized reading' (duration: 538.057757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:44.14133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:43.602529Z","time spent":"538.792914ms","remote":"127.0.0.1:37366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329955,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-02-25T10:05:44.143436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:43.533738Z","time spent":"606.929732ms","remote":"127.0.0.1:37714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14294,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:44.295793Z","caller":"traceutil/trace.go:171","msg":"trace[1086710109] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:540; }","duration":"147.322675ms","start":"2026-02-25T10:05:44.148445Z","end":"2026-02-25T10:05:44.295768Z","steps":["trace[1086710109] 'read index received' (duration: 147.251604ms)","trace[1086710109] 'applied index is now lower than readState.Index' (duration: 69.791µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:44.295836Z","caller":"traceutil/trace.go:171","msg":"trace[848591216] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"148.932754ms","start":"2026-02-25T10:05:44.146851Z","end":"2026-02-25T10:05:44.295784Z","steps":["trace[848591216] 'process raft request' (duration: 148.726739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:44.296034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.562821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bcjbw\" ","response":"range_response_count:1 size:14921"} {"level":"info","ts":"2026-02-25T10:05:44.296068Z","caller":"traceutil/trace.go:171","msg":"trace[2146372334] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bcjbw; range_end:; response_count:1; response_revision:524; }","duration":"147.650823ms","start":"2026-02-25T10:05:44.148408Z","end":"2026-02-25T10:05:44.296059Z","steps":["trace[2146372334] 'agreement among raft nodes before linearized reading' (duration: 147.475569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:44.701264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.578563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:05:44.701442Z","caller":"traceutil/trace.go:171","msg":"trace[1323549398] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:541; }","duration":"405.55389ms","start":"2026-02-25T10:05:44.29586Z","end":"2026-02-25T10:05:44.701414Z","steps":["trace[1323549398] 'read index received' (duration: 129.618969ms)","trace[1323549398] 'applied index is now lower than readState.Index' (duration: 275.933311ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:44.701544Z","caller":"traceutil/trace.go:171","msg":"trace[851451445] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"546.833963ms","start":"2026-02-25T10:05:44.154688Z","end":"2026-02-25T10:05:44.701522Z","steps":["trace[851451445] 'process raft request' (duration: 270.848001ms)","trace[851451445] 'compare' (duration: 275.392969ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:44.701613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:44.154674Z","time spent":"546.904095ms","remote":"127.0.0.1:37698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4595,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:44.701675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.336062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14309"} {"level":"info","ts":"2026-02-25T10:05:44.701758Z","caller":"traceutil/trace.go:171","msg":"trace[1818880359] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:525; }","duration":"546.446224ms","start":"2026-02-25T10:05:44.155287Z","end":"2026-02-25T10:05:44.701734Z","steps":["trace[1818880359] 'agreement among raft nodes before linearized reading' (duration: 546.228249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:44.701801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:44.155278Z","time spent":"546.513606ms","remote":"127.0.0.1:37714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":14332,"request content":"key:\"/registry/daemonsets/kube-system/cilium\" "} {"level":"warn","ts":"2026-02-25T10:05:44.701822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.820832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/cilium-operator-7fcd8d6ffd\" ","response":"range_response_count:1 size:4287"} {"level":"info","ts":"2026-02-25T10:05:44.701913Z","caller":"traceutil/trace.go:171","msg":"trace[2144858901] range","detail":"{range_begin:/registry/replicasets/kube-system/cilium-operator-7fcd8d6ffd; range_end:; response_count:1; response_revision:525; }","duration":"551.945684ms","start":"2026-02-25T10:05:44.149943Z","end":"2026-02-25T10:05:44.701888Z","steps":["trace[2144858901] 'agreement among raft nodes before linearized reading' (duration: 551.753229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:44.701966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:44.149916Z","time spent":"552.041167ms","remote":"127.0.0.1:37720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":4310,"request content":"key:\"/registry/replicasets/kube-system/cilium-operator-7fcd8d6ffd\" "} {"level":"warn","ts":"2026-02-25T10:05:44.702963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.828546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329932"} {"level":"info","ts":"2026-02-25T10:05:44.703067Z","caller":"traceutil/trace.go:171","msg":"trace[776699213] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:525; }","duration":"455.97496ms","start":"2026-02-25T10:05:44.247069Z","end":"2026-02-25T10:05:44.703044Z","steps":["trace[776699213] 'agreement among raft nodes before linearized reading' (duration: 454.482904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:44.703115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:44.247047Z","time spent":"456.051962ms","remote":"127.0.0.1:37366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329955,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-02-25T10:05:44.75559Z","caller":"traceutil/trace.go:171","msg":"trace[1529229723] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"454.042944ms","start":"2026-02-25T10:05:44.301502Z","end":"2026-02-25T10:05:44.755545Z","steps":["trace[1529229723] 'process raft request' (duration: 453.776958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:44.755818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:44.301395Z","time spent":"454.299761ms","remote":"127.0.0.1:37342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:05:44.755866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.646374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"warn","ts":"2026-02-25T10:05:44.755919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.849821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-02-25T10:05:44.755935Z","caller":"traceutil/trace.go:171","msg":"trace[1810805584] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"444.393187ms","start":"2026-02-25T10:05:44.311486Z","end":"2026-02-25T10:05:44.75588Z","steps":["trace[1810805584] 'process raft request' (duration: 444.049548ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:44.756021Z","caller":"traceutil/trace.go:171","msg":"trace[1917307633] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:527; }","duration":"415.019485ms","start":"2026-02-25T10:05:44.340977Z","end":"2026-02-25T10:05:44.755997Z","steps":["trace[1917307633] 'agreement among raft nodes before linearized reading' (duration: 414.83532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:44.756073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:44.340947Z","time spent":"415.116466ms","remote":"127.0.0.1:37476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":543,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"warn","ts":"2026-02-25T10:05:44.756086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:44.311466Z","time spent":"444.551611ms","remote":"127.0.0.1:37446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16641,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:44.755955Z","caller":"traceutil/trace.go:171","msg":"trace[1988107207] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:527; }","duration":"414.829ms","start":"2026-02-25T10:05:44.341103Z","end":"2026-02-25T10:05:44.755932Z","steps":["trace[1988107207] 'agreement among raft nodes before linearized reading' (duration: 414.585864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:44.756148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:44.341071Z","time spent":"415.060285ms","remote":"127.0.0.1:37476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":561,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" "} {"level":"info","ts":"2026-02-25T10:05:45.036107Z","caller":"traceutil/trace.go:171","msg":"trace[521143413] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"271.309012ms","start":"2026-02-25T10:05:44.764772Z","end":"2026-02-25T10:05:45.036081Z","steps":["trace[521143413] 'process raft request' (duration: 271.111196ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:45.036133Z","caller":"traceutil/trace.go:171","msg":"trace[368788478] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:545; }","duration":"268.094137ms","start":"2026-02-25T10:05:44.767996Z","end":"2026-02-25T10:05:45.03609Z","steps":["trace[368788478] 'read index received' (duration: 268.074267ms)","trace[368788478] 'applied index is now lower than readState.Index' (duration: 16.1µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:45.036515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.482766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14309"} {"level":"info","ts":"2026-02-25T10:05:45.036544Z","caller":"traceutil/trace.go:171","msg":"trace[957608153] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:528; }","duration":"268.581028ms","start":"2026-02-25T10:05:44.767955Z","end":"2026-02-25T10:05:45.036536Z","steps":["trace[957608153] 'agreement among raft nodes before linearized reading' (duration: 268.312501ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:45.15254Z","caller":"traceutil/trace.go:171","msg":"trace[554174411] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"116.285614ms","start":"2026-02-25T10:05:45.036228Z","end":"2026-02-25T10:05:45.152514Z","steps":["trace[554174411] 'read index received' (duration: 90.588318ms)","trace[554174411] 'applied index is now lower than readState.Index' (duration: 25.695716ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:45.152614Z","caller":"traceutil/trace.go:171","msg":"trace[1237727259] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"382.911247ms","start":"2026-02-25T10:05:44.769678Z","end":"2026-02-25T10:05:45.15259Z","steps":["trace[1237727259] 'process raft request' (duration: 357.19923ms)","trace[1237727259] 'compare' (duration: 25.524562ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:45.152698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.228731ms","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-25T10:05:45.152734Z","caller":"traceutil/trace.go:171","msg":"trace[1265406259] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:529; }","duration":"185.299633ms","start":"2026-02-25T10:05:44.967423Z","end":"2026-02-25T10:05:45.152722Z","steps":["trace[1265406259] 'agreement among raft nodes before linearized reading' (duration: 185.19221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:45.152745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:44.769658Z","time spent":"383.008859ms","remote":"127.0.0.1:37446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4624,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:45.152871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.368602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:45.152927Z","caller":"traceutil/trace.go:171","msg":"trace[948585789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"162.457884ms","start":"2026-02-25T10:05:44.990458Z","end":"2026-02-25T10:05:45.152916Z","steps":["trace[948585789] 'agreement among raft nodes before linearized reading' (duration: 162.368882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:45.152989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.75701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T10:05:45.153042Z","caller":"traceutil/trace.go:171","msg":"trace[1651863220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"184.822162ms","start":"2026-02-25T10:05:44.968207Z","end":"2026-02-25T10:05:45.15303Z","steps":["trace[1651863220] 'agreement among raft nodes before linearized reading' (duration: 184.642648ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:45.1662Z","caller":"traceutil/trace.go:171","msg":"trace[2066190566] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"121.858136ms","start":"2026-02-25T10:05:45.044321Z","end":"2026-02-25T10:05:45.166179Z","steps":["trace[2066190566] 'process raft request' (duration: 121.708742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:47.701848Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3923370385976215966,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T10:05:47.882261Z","caller":"traceutil/trace.go:171","msg":"trace[2120630739] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"685.273001ms","start":"2026-02-25T10:05:47.196957Z","end":"2026-02-25T10:05:47.88223Z","steps":["trace[2120630739] 'process raft request' (duration: 685.102296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:47.882487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:47.196935Z","time spent":"685.418155ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:49.260196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.833533ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:05:49.260323Z","caller":"traceutil/trace.go:171","msg":"trace[599832114] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:555; }","duration":"2.05910155s","start":"2026-02-25T10:05:47.201201Z","end":"2026-02-25T10:05:49.260302Z","steps":["trace[599832114] 'read index received' (duration: 680.91904ms)","trace[599832114] 'applied index is now lower than readState.Index' (duration: 1.37818078s)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:49.260486Z","caller":"traceutil/trace.go:171","msg":"trace[276244088] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"2.062086051s","start":"2026-02-25T10:05:47.198355Z","end":"2026-02-25T10:05:49.260441Z","steps":["trace[276244088] 'process raft request' (duration: 1.642889479s)","trace[276244088] 'compare' (duration: 418.394353ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:49.260641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.059437379s","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-25T10:05:49.260686Z","caller":"traceutil/trace.go:171","msg":"trace[437821197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:540; }","duration":"2.05949948s","start":"2026-02-25T10:05:47.201171Z","end":"2026-02-25T10:05:49.26067Z","steps":["trace[437821197] 'agreement among raft nodes before linearized reading' (duration: 2.059304775s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:49.260681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:47.198332Z","time spent":"2.062243275s","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:49.26075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:47.201159Z","time spent":"2.059574012s","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T10:05:49.760708Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3923370385976215971,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T10:05:49.83981Z","caller":"traceutil/trace.go:171","msg":"trace[2085194338] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:557; }","duration":"579.417292ms","start":"2026-02-25T10:05:49.260371Z","end":"2026-02-25T10:05:49.839789Z","steps":["trace[2085194338] 'read index received' (duration: 571.389822ms)","trace[2085194338] 'applied index is now lower than readState.Index' (duration: 8.0266ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:49.839869Z","caller":"traceutil/trace.go:171","msg":"trace[1955465001] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"573.39558ms","start":"2026-02-25T10:05:49.266429Z","end":"2026-02-25T10:05:49.839824Z","steps":["trace[1955465001] 'process raft request' (duration: 573.283997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:49.839973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.849314669s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:49.840021Z","caller":"traceutil/trace.go:171","msg":"trace[859272859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:542; }","duration":"1.849459823s","start":"2026-02-25T10:05:47.990547Z","end":"2026-02-25T10:05:49.840007Z","steps":["trace[859272859] 'agreement among raft nodes before linearized reading' (duration: 1.849367941s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:49.840021Z","caller":"traceutil/trace.go:171","msg":"trace[1658936386] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.285117844s","start":"2026-02-25T10:05:48.554876Z","end":"2026-02-25T10:05:49.839994Z","steps":["trace[1658936386] 'process raft request' (duration: 1.284630493s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:49.840037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.473152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:05:49.840066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:47.990519Z","time spent":"1.849534844s","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:05:49.840137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.267772133s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T10:05:49.84015Z","caller":"traceutil/trace.go:171","msg":"trace[1234225229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:542; }","duration":"331.659626ms","start":"2026-02-25T10:05:49.50847Z","end":"2026-02-25T10:05:49.840129Z","steps":["trace[1234225229] 'agreement among raft nodes before linearized reading' (duration: 331.489042ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:49.840185Z","caller":"traceutil/trace.go:171","msg":"trace[1013294348] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:542; }","duration":"2.267862756s","start":"2026-02-25T10:05:47.572309Z","end":"2026-02-25T10:05:49.840172Z","steps":["trace[1013294348] 'agreement among raft nodes before linearized reading' (duration: 2.267674231s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:49.840156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:49.2664Z","time spent":"573.570154ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:49.840196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:49.508438Z","time spent":"331.748958ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:05:49.840202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:48.554851Z","time spent":"1.285218407s","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:49.840218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:47.572284Z","time spent":"2.267927097s","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:05:56.167099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.531006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:56.167208Z","caller":"traceutil/trace.go:171","msg":"trace[1652766203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"176.70174ms","start":"2026-02-25T10:05:55.990474Z","end":"2026-02-25T10:05:56.167176Z","steps":["trace[1652766203] 'range keys from in-memory index tree' (duration: 176.399222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:56.167223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.552106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T10:05:56.16733Z","caller":"traceutil/trace.go:171","msg":"trace[440721336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"226.72759ms","start":"2026-02-25T10:05:55.940575Z","end":"2026-02-25T10:05:56.167303Z","steps":["trace[440721336] 'range keys from in-memory index tree' (duration: 226.309251ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:56.895246Z","caller":"traceutil/trace.go:171","msg":"trace[196690839] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"293.259441ms","start":"2026-02-25T10:05:56.601961Z","end":"2026-02-25T10:05:56.895221Z","steps":["trace[196690839] 'process raft request' (duration: 293.095787ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:57.556502Z","caller":"traceutil/trace.go:171","msg":"trace[704215298] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"567.649835ms","start":"2026-02-25T10:05:56.988819Z","end":"2026-02-25T10:05:57.556469Z","steps":["trace[704215298] 'process raft request' (duration: 567.42167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:57.55667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:56.988796Z","time spent":"567.780288ms","remote":"127.0.0.1:37446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:57.556835Z","caller":"traceutil/trace.go:171","msg":"trace[441373309] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:580; }","duration":"246.810603ms","start":"2026-02-25T10:05:57.309613Z","end":"2026-02-25T10:05:57.556424Z","steps":["trace[441373309] 'read index received' (duration: 246.445675ms)","trace[441373309] 'applied index is now lower than readState.Index' (duration: 363.638µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:57.556921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.281765ms","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-25T10:05:57.557259Z","caller":"traceutil/trace.go:171","msg":"trace[2021913779] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:562; }","duration":"247.679894ms","start":"2026-02-25T10:05:57.309552Z","end":"2026-02-25T10:05:57.557232Z","steps":["trace[2021913779] 'agreement among raft nodes before linearized reading' (duration: 247.136231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:58.402389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.47229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:58.402464Z","caller":"traceutil/trace.go:171","msg":"trace[1268737450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"411.590463ms","start":"2026-02-25T10:05:57.990858Z","end":"2026-02-25T10:05:58.402449Z","steps":["trace[1268737450] 'range keys from in-memory index tree' (duration: 411.347907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:58.402498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:57.990847Z","time spent":"411.644914ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:05:58.402603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.721345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T10:05:58.402679Z","caller":"traceutil/trace.go:171","msg":"trace[1469482382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:563; }","duration":"522.89837ms","start":"2026-02-25T10:05:57.879763Z","end":"2026-02-25T10:05:58.402661Z","steps":["trace[1469482382] 'range keys from in-memory index tree' (duration: 522.530641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:58.402723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:57.879737Z","time spent":"522.976512ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T10:05:58.403044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.821572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T10:05:58.403122Z","caller":"traceutil/trace.go:171","msg":"trace[229675721] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:563; }","duration":"515.926095ms","start":"2026-02-25T10:05:57.887177Z","end":"2026-02-25T10:05:58.403103Z","steps":["trace[229675721] 'range keys from in-memory index tree' (duration: 515.170147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:58.403164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:57.887167Z","time spent":"515.984876ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:05:58.724303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.500164ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T10:05:58.724604Z","caller":"traceutil/trace.go:171","msg":"trace[1115178292] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:582; }","duration":"547.375127ms","start":"2026-02-25T10:05:58.177178Z","end":"2026-02-25T10:05:58.724553Z","steps":["trace[1115178292] 'read index received' (duration: 223.251248ms)","trace[1115178292] 'applied index is now lower than readState.Index' (duration: 324.121759ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:58.724791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.605112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T10:05:58.724861Z","caller":"traceutil/trace.go:171","msg":"trace[380174224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:563; }","duration":"547.718745ms","start":"2026-02-25T10:05:58.177126Z","end":"2026-02-25T10:05:58.724845Z","steps":["trace[380174224] 'agreement among raft nodes before linearized reading' (duration: 547.528831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:58.724898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:58.177096Z","time spent":"547.796417ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T10:05:58.808897Z","caller":"traceutil/trace.go:171","msg":"trace[599358454] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"402.409776ms","start":"2026-02-25T10:05:58.406434Z","end":"2026-02-25T10:05:58.808844Z","steps":["trace[599358454] 'process raft request' (duration: 401.915295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:58.809069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:58.406413Z","time spent":"402.557489ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:58.810892Z","caller":"traceutil/trace.go:171","msg":"trace[1340515301] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"403.360858ms","start":"2026-02-25T10:05:58.407489Z","end":"2026-02-25T10:05:58.81085Z","steps":["trace[1340515301] 'process raft request' (duration: 402.63234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:58.811033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:58.407473Z","time spent":"403.477371ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:58.811592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.613275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:58.811693Z","caller":"traceutil/trace.go:171","msg":"trace[561491649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"406.734668ms","start":"2026-02-25T10:05:58.404919Z","end":"2026-02-25T10:05:58.811653Z","steps":["trace[561491649] 'agreement among raft nodes before linearized reading' (duration: 406.234696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:58.811741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:58.404897Z","time spent":"406.831759ms","remote":"127.0.0.1:37246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:06:00.448266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.354116ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:06:00.448438Z","caller":"traceutil/trace.go:171","msg":"trace[1660074598] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:576; }","duration":"244.551721ms","start":"2026-02-25T10:06:00.203861Z","end":"2026-02-25T10:06:00.448413Z","steps":["trace[1660074598] 'range keys from in-memory index tree' (duration: 244.330416ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:03.013985Z","caller":"traceutil/trace.go:171","msg":"trace[133090630] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"135.185229ms","start":"2026-02-25T10:06:02.878767Z","end":"2026-02-25T10:06:03.013952Z","steps":["trace[133090630] 'process raft request' (duration: 135.073007ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:03.01403Z","caller":"traceutil/trace.go:171","msg":"trace[43873971] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"135.528878ms","start":"2026-02-25T10:06:02.878435Z","end":"2026-02-25T10:06:03.013964Z","steps":["trace[43873971] 'process raft request' (duration: 55.017608ms)","trace[43873971] 'compare' (duration: 80.205403ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:06:03.014268Z","caller":"traceutil/trace.go:171","msg":"trace[985112284] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"135.128758ms","start":"2026-02-25T10:06:02.879109Z","end":"2026-02-25T10:06:03.014237Z","steps":["trace[985112284] 'process raft request' (duration: 134.78788ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:03.130119Z","caller":"traceutil/trace.go:171","msg":"trace[2092818792] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"102.93439ms","start":"2026-02-25T10:06:03.027157Z","end":"2026-02-25T10:06:03.130091Z","steps":["trace[2092818792] 'process raft request' (duration: 102.796165ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:03.141827Z","caller":"traceutil/trace.go:171","msg":"trace[1713413096] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"103.340679ms","start":"2026-02-25T10:06:03.038452Z","end":"2026-02-25T10:06:03.141793Z","steps":["trace[1713413096] 'process raft request' (duration: 103.172585ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:09.870107Z","caller":"traceutil/trace.go:171","msg":"trace[854975551] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:712; }","duration":"167.01844ms","start":"2026-02-25T10:06:09.703058Z","end":"2026-02-25T10:06:09.870077Z","steps":["trace[854975551] 'read index received' (duration: 166.730684ms)","trace[854975551] 'applied index is now lower than readState.Index' (duration: 286.336µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:06:09.870263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.183415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:06:09.870232Z","caller":"traceutil/trace.go:171","msg":"trace[1127547218] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"175.545023ms","start":"2026-02-25T10:06:09.694625Z","end":"2026-02-25T10:06:09.87017Z","steps":["trace[1127547218] 'process raft request' (duration: 175.276845ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:09.87031Z","caller":"traceutil/trace.go:171","msg":"trace[162813404] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:690; }","duration":"167.297797ms","start":"2026-02-25T10:06:09.702993Z","end":"2026-02-25T10:06:09.870291Z","steps":["trace[162813404] 'agreement among raft nodes before linearized reading' (duration: 167.209335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:10.167015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.793663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:06:10.167092Z","caller":"traceutil/trace.go:171","msg":"trace[461286519] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:690; }","duration":"118.926727ms","start":"2026-02-25T10:06:10.048145Z","end":"2026-02-25T10:06:10.167072Z","steps":["trace[461286519] 'count revisions from in-memory index tree' (duration: 118.70779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:10.167141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.241706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:06:10.167233Z","caller":"traceutil/trace.go:171","msg":"trace[1063724312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:690; }","duration":"175.350498ms","start":"2026-02-25T10:06:09.991862Z","end":"2026-02-25T10:06:10.167212Z","steps":["trace[1063724312] 'range keys from in-memory index tree' (duration: 175.126893ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:11.257806Z","caller":"traceutil/trace.go:171","msg":"trace[2127870026] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"100.101672ms","start":"2026-02-25T10:06:11.157678Z","end":"2026-02-25T10:06:11.25778Z","steps":["trace[2127870026] 'process raft request' (duration: 99.964949ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:12.387461Z","caller":"traceutil/trace.go:171","msg":"trace[725954747] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:732; }","duration":"188.899217ms","start":"2026-02-25T10:06:12.198535Z","end":"2026-02-25T10:06:12.387434Z","steps":["trace[725954747] 'read index received' (duration: 186.91431ms)","trace[725954747] 'applied index is now lower than readState.Index' (duration: 1.983767ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:06:12.387622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.074261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-9p4jp\" ","response":"range_response_count:1 size:5020"} {"level":"info","ts":"2026-02-25T10:06:12.387668Z","caller":"traceutil/trace.go:171","msg":"trace[1305883841] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-9p4jp; range_end:; response_count:1; response_revision:710; }","duration":"189.172034ms","start":"2026-02-25T10:06:12.19849Z","end":"2026-02-25T10:06:12.387662Z","steps":["trace[1305883841] 'agreement among raft nodes before linearized reading' (duration: 189.02745ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:12.387495Z","caller":"traceutil/trace.go:171","msg":"trace[1930669819] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"189.924002ms","start":"2026-02-25T10:06:12.197547Z","end":"2026-02-25T10:06:12.387471Z","steps":["trace[1930669819] 'process raft request' (duration: 187.791291ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:12.585815Z","caller":"traceutil/trace.go:171","msg":"trace[1937981600] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"387.024973ms","start":"2026-02-25T10:06:12.198758Z","end":"2026-02-25T10:06:12.585783Z","steps":["trace[1937981600] 'process raft request' (duration: 385.701911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:12.585975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:12.198744Z","time spent":"387.150206ms","remote":"127.0.0.1:37532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1448,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:06:12.585908Z","caller":"traceutil/trace.go:171","msg":"trace[704040119] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"386.897469ms","start":"2026-02-25T10:06:12.198984Z","end":"2026-02-25T10:06:12.585882Z","steps":["trace[704040119] 'process raft request' (duration: 386.821657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:12.586182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:12.198974Z","time spent":"387.088724ms","remote":"127.0.0.1:37720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3830,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:06:12.741437Z","caller":"traceutil/trace.go:171","msg":"trace[609010473] linearizableReadLoop","detail":"{readStateIndex:736; appliedIndex:733; }","duration":"324.312402ms","start":"2026-02-25T10:06:12.417102Z","end":"2026-02-25T10:06:12.741415Z","steps":["trace[609010473] 'read index received' (duration: 167.414129ms)","trace[609010473] 'applied index is now lower than readState.Index' (duration: 156.897113ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:06:12.741657Z","caller":"traceutil/trace.go:171","msg":"trace[993085921] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"347.402108ms","start":"2026-02-25T10:06:12.394234Z","end":"2026-02-25T10:06:12.741636Z","steps":["trace[993085921] 'process raft request' (duration: 347.052229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:12.741823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.699682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T10:06:12.741854Z","caller":"traceutil/trace.go:171","msg":"trace[703116198] range","detail":"{range_begin:/registry/masterleases/199.19.213.205; range_end:; response_count:1; response_revision:713; }","duration":"324.796754ms","start":"2026-02-25T10:06:12.41705Z","end":"2026-02-25T10:06:12.741847Z","steps":["trace[703116198] 'agreement among raft nodes before linearized reading' (duration: 324.659501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:12.741898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:12.417032Z","time spent":"324.835596ms","remote":"127.0.0.1:37270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.205\" "} {"level":"warn","ts":"2026-02-25T10:06:12.74203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:12.394217Z","time spent":"347.621043ms","remote":"127.0.0.1:37446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4827,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:06:12.750808Z","caller":"traceutil/trace.go:171","msg":"trace[1765381837] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"155.741395ms","start":"2026-02-25T10:06:12.595039Z","end":"2026-02-25T10:06:12.750781Z","steps":["trace[1765381837] 'process raft request' (duration: 155.50559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:15.688039Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3923370385976216364,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T10:06:15.914324Z","caller":"traceutil/trace.go:171","msg":"trace[1039142265] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"799.69592ms","start":"2026-02-25T10:06:15.114602Z","end":"2026-02-25T10:06:15.914298Z","steps":["trace[1039142265] 'process raft request' (duration: 799.519446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:15.914507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:15.114581Z","time spent":"799.827973ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:06:16.782605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.135591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:06:16.782902Z","caller":"traceutil/trace.go:171","msg":"trace[462929492] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:766; }","duration":"1.595342556s","start":"2026-02-25T10:06:15.187549Z","end":"2026-02-25T10:06:16.782892Z","steps":["trace[462929492] 'read index received' (duration: 726.721839ms)","trace[462929492] 'applied index is now lower than readState.Index' (duration: 868.619987ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:06:16.783093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.200846ms","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-25T10:06:16.783089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.59553322s","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-25T10:06:16.783116Z","caller":"traceutil/trace.go:171","msg":"trace[879336638] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:743; }","duration":"890.224047ms","start":"2026-02-25T10:06:15.892884Z","end":"2026-02-25T10:06:16.783108Z","steps":["trace[879336638] 'agreement among raft nodes before linearized reading' (duration: 890.154645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:16.783136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:15.89281Z","time spent":"890.320729ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T10:06:16.783144Z","caller":"traceutil/trace.go:171","msg":"trace[986254457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:743; }","duration":"1.595627692s","start":"2026-02-25T10:06:15.187499Z","end":"2026-02-25T10:06:16.783127Z","steps":["trace[986254457] 'agreement among raft nodes before linearized reading' (duration: 1.595477299s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:16.78313Z","caller":"traceutil/trace.go:171","msg":"trace[1479693957] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"1.666080815s","start":"2026-02-25T10:06:15.116999Z","end":"2026-02-25T10:06:16.783079Z","steps":["trace[1479693957] 'process raft request' (duration: 1.357385091s)","trace[1479693957] 'compare' (duration: 307.677111ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:06:16.783143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.683859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:06:16.783178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:15.187473Z","time spent":"1.595697363s","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T10:06:16.783153Z","caller":"traceutil/trace.go:171","msg":"trace[1289101333] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"1.605638939s","start":"2026-02-25T10:06:15.177471Z","end":"2026-02-25T10:06:16.78311Z","steps":["trace[1289101333] 'process raft request' (duration: 1.605376233s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:16.783223Z","caller":"traceutil/trace.go:171","msg":"trace[1475098135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:743; }","duration":"793.786091ms","start":"2026-02-25T10:06:15.989421Z","end":"2026-02-25T10:06:16.783207Z","steps":["trace[1475098135] 'agreement among raft nodes before linearized reading' (duration: 793.629628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:16.78326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:15.989352Z","time spent":"793.900723ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:06:16.783304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:15.177438Z","time spent":"1.605805443s","remote":"127.0.0.1:37342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":811,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:06:16.783308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:15.116962Z","time spent":"1.666250999s","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:06:20.491196Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3923370385976216393,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T10:06:20.497696Z","caller":"traceutil/trace.go:171","msg":"trace[1395314191] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"563.737472ms","start":"2026-02-25T10:06:19.933933Z","end":"2026-02-25T10:06:20.49767Z","steps":["trace[1395314191] 'process raft request' (duration: 563.447986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:20.498014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:19.933897Z","time spent":"563.866216ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:06:20.550383Z","caller":"traceutil/trace.go:171","msg":"trace[1594182523] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:779; }","duration":"559.533964ms","start":"2026-02-25T10:06:19.990826Z","end":"2026-02-25T10:06:20.55036Z","steps":["trace[1594182523] 'read index received' (duration: 506.728848ms)","trace[1594182523] 'applied index is now lower than readState.Index' (duration: 52.804076ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:06:20.550494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.660787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:06:20.550473Z","caller":"traceutil/trace.go:171","msg":"trace[192265604] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"177.098669ms","start":"2026-02-25T10:06:20.373337Z","end":"2026-02-25T10:06:20.550435Z","steps":["trace[192265604] 'process raft request' (duration: 176.923475ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:20.550769Z","caller":"traceutil/trace.go:171","msg":"trace[1347088175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"559.730879ms","start":"2026-02-25T10:06:19.990789Z","end":"2026-02-25T10:06:20.550519Z","steps":["trace[1347088175] 'agreement among raft nodes before linearized reading' (duration: 559.664057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:20.550827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:19.990773Z","time spent":"560.042306ms","remote":"127.0.0.1:37246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:06:43.411954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.514126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:06:43.412069Z","caller":"traceutil/trace.go:171","msg":"trace[1367930099] linearizableReadLoop","detail":"{readStateIndex:852; appliedIndex:851; }","duration":"741.025216ms","start":"2026-02-25T10:06:42.67101Z","end":"2026-02-25T10:06:43.412035Z","steps":["trace[1367930099] 'read index received' (duration: 263.357137ms)","trace[1367930099] 'applied index is now lower than readState.Index' (duration: 477.667209ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:06:43.412148Z","caller":"traceutil/trace.go:171","msg":"trace[532598874] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"847.599983ms","start":"2026-02-25T10:06:42.564535Z","end":"2026-02-25T10:06:43.412135Z","steps":["trace[532598874] 'process raft request' (duration: 369.814612ms)","trace[532598874] 'compare' (duration: 477.007823ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:06:43.412193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:42.564508Z","time spent":"847.661884ms","remote":"127.0.0.1:37270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:06:43.412405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.405554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T10:06:43.412437Z","caller":"traceutil/trace.go:171","msg":"trace[35944227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:821; }","duration":"741.475246ms","start":"2026-02-25T10:06:42.670953Z","end":"2026-02-25T10:06:43.412429Z","steps":["trace[35944227] 'agreement among raft nodes before linearized reading' (duration: 741.362323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:43.412459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:42.67093Z","time spent":"741.525517ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T10:06:43.412608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.57459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:06:43.412626Z","caller":"traceutil/trace.go:171","msg":"trace[990939342] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:821; }","duration":"421.62501ms","start":"2026-02-25T10:06:42.990996Z","end":"2026-02-25T10:06:43.412621Z","steps":["trace[990939342] 'agreement among raft nodes before linearized reading' (duration: 421.591169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:43.412643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:42.990978Z","time spent":"421.661251ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:06:43.412769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.060253ms","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-25T10:06:43.412792Z","caller":"traceutil/trace.go:171","msg":"trace[1673645249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:821; }","duration":"169.112355ms","start":"2026-02-25T10:06:43.243675Z","end":"2026-02-25T10:06:43.412787Z","steps":["trace[1673645249] 'agreement among raft nodes before linearized reading' (duration: 169.054563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:43.412913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.709204ms","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-25T10:06:43.412937Z","caller":"traceutil/trace.go:171","msg":"trace[1400129355] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:821; }","duration":"250.766625ms","start":"2026-02-25T10:06:43.162165Z","end":"2026-02-25T10:06:43.412931Z","steps":["trace[1400129355] 'agreement among raft nodes before linearized reading' (duration: 250.706344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:43.413049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.275298ms","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-25T10:06:43.413074Z","caller":"traceutil/trace.go:171","msg":"trace[1520788994] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:821; }","duration":"337.349529ms","start":"2026-02-25T10:06:43.075719Z","end":"2026-02-25T10:06:43.413069Z","steps":["trace[1520788994] 'agreement among raft nodes before linearized reading' (duration: 337.291578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:43.413092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:43.07568Z","time spent":"337.406191ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:06:43.414292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.177553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T10:06:43.414483Z","caller":"traceutil/trace.go:171","msg":"trace[1895145258] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:821; }","duration":"165.384189ms","start":"2026-02-25T10:06:43.249075Z","end":"2026-02-25T10:06:43.414459Z","steps":["trace[1895145258] 'agreement among raft nodes before linearized reading' (duration: 164.084988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:43.715351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.562553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:06:43.715509Z","caller":"traceutil/trace.go:171","msg":"trace[936496836] linearizableReadLoop","detail":"{readStateIndex:853; appliedIndex:852; }","duration":"297.315472ms","start":"2026-02-25T10:06:43.41817Z","end":"2026-02-25T10:06:43.715486Z","steps":["trace[936496836] 'read index received' (duration: 188.586845ms)","trace[936496836] 'applied index is now lower than readState.Index' (duration: 108.727297ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:06:43.715632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.454005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-25T10:06:43.715626Z","caller":"traceutil/trace.go:171","msg":"trace[1212699598] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"298.578421ms","start":"2026-02-25T10:06:43.416999Z","end":"2026-02-25T10:06:43.715577Z","steps":["trace[1212699598] 'process raft request' (duration: 189.65458ms)","trace[1212699598] 'compare' (duration: 108.380149ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:06:43.715678Z","caller":"traceutil/trace.go:171","msg":"trace[1548121710] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:822; }","duration":"297.509497ms","start":"2026-02-25T10:06:43.418154Z","end":"2026-02-25T10:06:43.715663Z","steps":["trace[1548121710] 'agreement among raft nodes before linearized reading' (duration: 297.393245ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:43.746968Z","caller":"traceutil/trace.go:171","msg":"trace[871646743] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"328.799851ms","start":"2026-02-25T10:06:43.418146Z","end":"2026-02-25T10:06:43.746946Z","steps":["trace[871646743] 'process raft request' (duration: 328.613417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:43.747143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:43.418117Z","time spent":"328.925854ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:06:43.74732Z","caller":"traceutil/trace.go:171","msg":"trace[1651474063] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"329.045788ms","start":"2026-02-25T10:06:43.41824Z","end":"2026-02-25T10:06:43.747286Z","steps":["trace[1651474063] 'process raft request' (duration: 328.658928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:43.747488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:43.41823Z","time spent":"329.170541ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:06:43.748256Z","caller":"traceutil/trace.go:171","msg":"trace[1102410867] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"329.931868ms","start":"2026-02-25T10:06:43.41831Z","end":"2026-02-25T10:06:43.748242Z","steps":["trace[1102410867] 'process raft request' (duration: 328.876334ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:43.748283Z","caller":"traceutil/trace.go:171","msg":"trace[1969736517] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"329.916127ms","start":"2026-02-25T10:06:43.418342Z","end":"2026-02-25T10:06:43.748258Z","steps":["trace[1969736517] 'process raft request' (duration: 329.809975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:43.748485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:43.418333Z","time spent":"330.047961ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:06:43.748321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:43.418295Z","time spent":"329.994069ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:06:45.997815Z","caller":"traceutil/trace.go:171","msg":"trace[1551958993] linearizableReadLoop","detail":"{readStateIndex:858; appliedIndex:857; }","duration":"246.65519ms","start":"2026-02-25T10:06:45.751137Z","end":"2026-02-25T10:06:45.997792Z","steps":["trace[1551958993] 'read index received' (duration: 246.461886ms)","trace[1551958993] 'applied index is now lower than readState.Index' (duration: 192.214µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:06:45.997929Z","caller":"traceutil/trace.go:171","msg":"trace[1865288088] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"274.7031ms","start":"2026-02-25T10:06:45.723216Z","end":"2026-02-25T10:06:45.997919Z","steps":["trace[1865288088] 'process raft request' (duration: 274.445064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:45.998129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.989858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T10:06:45.998184Z","caller":"traceutil/trace.go:171","msg":"trace[553389300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:827; }","duration":"247.088221ms","start":"2026-02-25T10:06:45.751072Z","end":"2026-02-25T10:06:45.99816Z","steps":["trace[553389300] 'agreement among raft nodes before linearized reading' (duration: 246.962878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:45.998329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.853182ms","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-25T10:06:45.998446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.915504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-25T10:06:45.998327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.230597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T10:06:45.998493Z","caller":"traceutil/trace.go:171","msg":"trace[101621093] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:827; }","duration":"245.456232ms","start":"2026-02-25T10:06:45.753028Z","end":"2026-02-25T10:06:45.998484Z","steps":["trace[101621093] 'agreement among raft nodes before linearized reading' (duration: 245.150225ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:45.998496Z","caller":"traceutil/trace.go:171","msg":"trace[100966099] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:827; }","duration":"245.984715ms","start":"2026-02-25T10:06:45.752496Z","end":"2026-02-25T10:06:45.998481Z","steps":["trace[100966099] 'agreement among raft nodes before linearized reading' (duration: 245.895473ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:45.998402Z","caller":"traceutil/trace.go:171","msg":"trace[1116752167] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:827; }","duration":"245.952875ms","start":"2026-02-25T10:06:45.752433Z","end":"2026-02-25T10:06:45.998386Z","steps":["trace[1116752167] 'agreement among raft nodes before linearized reading' (duration: 245.73663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:46.365232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.520691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:06:46.365537Z","caller":"traceutil/trace.go:171","msg":"trace[1999795345] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"362.189065ms","start":"2026-02-25T10:06:46.003331Z","end":"2026-02-25T10:06:46.36552Z","steps":["trace[1999795345] 'process raft request' (duration: 362.077122ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:46.36557Z","caller":"traceutil/trace.go:171","msg":"trace[1812987162] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"362.272657ms","start":"2026-02-25T10:06:46.003244Z","end":"2026-02-25T10:06:46.365517Z","steps":["trace[1812987162] 'process raft request' (duration: 206.06052ms)","trace[1812987162] 'compare' (duration: 155.170683ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:06:46.365744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:46.00331Z","time spent":"362.311297ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:06:46.365776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:46.003217Z","time spent":"362.45711ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:06:46.365795Z","caller":"traceutil/trace.go:171","msg":"trace[1565940208] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"361.058458ms","start":"2026-02-25T10:06:46.004708Z","end":"2026-02-25T10:06:46.365766Z","steps":["trace[1565940208] 'process raft request' (duration: 360.766292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:46.36606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:46.004699Z","time spent":"361.234723ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:06:46.366203Z","caller":"traceutil/trace.go:171","msg":"trace[1386527191] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"360.660759ms","start":"2026-02-25T10:06:46.005516Z","end":"2026-02-25T10:06:46.366177Z","steps":["trace[1386527191] 'process raft request' (duration: 360.542146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:46.366325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:46.005508Z","time spent":"360.753611ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:06:46.692685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.288894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T10:06:46.692814Z","caller":"traceutil/trace.go:171","msg":"trace[1551573264] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:831; }","duration":"174.496889ms","start":"2026-02-25T10:06:46.518293Z","end":"2026-02-25T10:06:46.69279Z","steps":["trace[1551573264] 'count revisions from in-memory index tree' (duration: 174.206613ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:48.338616Z","caller":"traceutil/trace.go:171","msg":"trace[482332180] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"616.35192ms","start":"2026-02-25T10:06:47.72218Z","end":"2026-02-25T10:06:48.338532Z","steps":["trace[482332180] 'process raft request' (duration: 616.179596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:48.33943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:47.722153Z","time spent":"616.546314ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:06:49.02557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.653675ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T10:06:49.025674Z","caller":"traceutil/trace.go:171","msg":"trace[125392436] linearizableReadLoop","detail":"{readStateIndex:864; appliedIndex:862; }","duration":"1.035876641s","start":"2026-02-25T10:06:47.989776Z","end":"2026-02-25T10:06:49.025653Z","steps":["trace[125392436] 'read index received' (duration: 348.6161ms)","trace[125392436] 'applied index is now lower than readState.Index' (duration: 687.259051ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:06:49.025839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036059135s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:06:49.025884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02245351s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T10:06:49.025932Z","caller":"traceutil/trace.go:171","msg":"trace[1609355565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:832; }","duration":"1.022525662s","start":"2026-02-25T10:06:48.003393Z","end":"2026-02-25T10:06:49.025919Z","steps":["trace[1609355565] 'agreement among raft nodes before linearized reading' (duration: 1.022367099s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:49.025941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.401402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T10:06:49.025996Z","caller":"traceutil/trace.go:171","msg":"trace[1329187411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:832; }","duration":"1.036194798s","start":"2026-02-25T10:06:47.989717Z","end":"2026-02-25T10:06:49.025912Z","steps":["trace[1329187411] 'agreement among raft nodes before linearized reading' (duration: 1.036041485s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:49.026039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.757275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T10:06:49.026135Z","caller":"traceutil/trace.go:171","msg":"trace[1275108732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:832; }","duration":"656.853427ms","start":"2026-02-25T10:06:48.369258Z","end":"2026-02-25T10:06:49.026111Z","steps":["trace[1275108732] 'agreement among raft nodes before linearized reading' (duration: 656.617231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:49.026041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.148516ms","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-25T10:06:49.026188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:48.369198Z","time spent":"656.980119ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T10:06:49.02621Z","caller":"traceutil/trace.go:171","msg":"trace[1719047945] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:832; }","duration":"655.347082ms","start":"2026-02-25T10:06:48.370846Z","end":"2026-02-25T10:06:49.026193Z","steps":["trace[1719047945] 'agreement among raft nodes before linearized reading' (duration: 655.011454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:49.026247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:48.370839Z","time spent":"655.400253ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T10:06:49.025971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:48.003355Z","time spent":"1.022607533s","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T10:06:49.026317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:47.989698Z","time spent":"1.036391563s","remote":"127.0.0.1:37246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T10:06:49.026037Z","caller":"traceutil/trace.go:171","msg":"trace[625600715] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:832; }","duration":"655.523886ms","start":"2026-02-25T10:06:48.370494Z","end":"2026-02-25T10:06:49.026018Z","steps":["trace[625600715] 'agreement among raft nodes before linearized reading' (duration: 655.2722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:49.02645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:48.370486Z","time spent":"655.957295ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T10:06:49.026397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.057867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T10:06:49.026805Z","caller":"traceutil/trace.go:171","msg":"trace[1694002384] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:832; }","duration":"656.506178ms","start":"2026-02-25T10:06:48.370284Z","end":"2026-02-25T10:06:49.02679Z","steps":["trace[1694002384] 'agreement among raft nodes before linearized reading' (duration: 655.839593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:49.026852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:48.370262Z","time spent":"656.577139ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:06:51.613332Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3923370385976216642,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T10:06:51.695349Z","caller":"traceutil/trace.go:171","msg":"trace[847008585] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"601.314081ms","start":"2026-02-25T10:06:51.093997Z","end":"2026-02-25T10:06:51.695311Z","steps":["trace[847008585] 'process raft request' (duration: 601.080276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:51.695586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:51.093973Z","time spent":"601.488075ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:06:51.973033Z","caller":"traceutil/trace.go:171","msg":"trace[613840912] linearizableReadLoop","detail":"{readStateIndex:871; appliedIndex:869; }","duration":"860.251854ms","start":"2026-02-25T10:06:51.112749Z","end":"2026-02-25T10:06:51.973001Z","steps":["trace[613840912] 'read index received' (duration: 582.390322ms)","trace[613840912] 'applied index is now lower than readState.Index' (duration: 277.859672ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:06:51.973065Z","caller":"traceutil/trace.go:171","msg":"trace[1601484843] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"751.859416ms","start":"2026-02-25T10:06:51.221168Z","end":"2026-02-25T10:06:51.973027Z","steps":["trace[1601484843] 'process raft request' (duration: 697.090408ms)","trace[1601484843] 'compare' (duration: 54.478831ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:06:51.973265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:51.221149Z","time spent":"752.004209ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:06:51.973415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.632303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T10:06:51.973457Z","caller":"traceutil/trace.go:171","msg":"trace[25184503] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:839; }","duration":"860.720525ms","start":"2026-02-25T10:06:51.112724Z","end":"2026-02-25T10:06:51.973444Z","steps":["trace[25184503] 'agreement among raft nodes before linearized reading' (duration: 860.428019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:51.973428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.027139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-25T10:06:51.973499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:51.112713Z","time spent":"860.775246ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T10:06:51.973544Z","caller":"traceutil/trace.go:171","msg":"trace[1802859941] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:839; }","duration":"860.172393ms","start":"2026-02-25T10:06:51.113345Z","end":"2026-02-25T10:06:51.973518Z","steps":["trace[1802859941] 'agreement among raft nodes before linearized reading' (duration: 859.890997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:51.973594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:51.113336Z","time spent":"860.248485ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T10:06:51.973708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.304226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T10:06:51.973746Z","caller":"traceutil/trace.go:171","msg":"trace[1027238966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:839; }","duration":"860.352257ms","start":"2026-02-25T10:06:51.113383Z","end":"2026-02-25T10:06:51.973735Z","steps":["trace[1027238966] 'agreement among raft nodes before linearized reading' (duration: 860.258315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:51.973755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.101601ms","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-25T10:06:51.973773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:51.113378Z","time spent":"860.388468ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T10:06:51.973796Z","caller":"traceutil/trace.go:171","msg":"trace[665446723] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:839; }","duration":"860.184533ms","start":"2026-02-25T10:06:51.1136Z","end":"2026-02-25T10:06:51.973784Z","steps":["trace[665446723] 'agreement among raft nodes before linearized reading' (duration: 860.02762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:51.973834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:51.113576Z","time spent":"860.251475ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T10:06:52.354922Z","caller":"traceutil/trace.go:171","msg":"trace[2086083224] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"376.158618ms","start":"2026-02-25T10:06:51.978728Z","end":"2026-02-25T10:06:52.354887Z","steps":["trace[2086083224] 'process raft request' (duration: 375.925703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:52.355195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:51.978698Z","time spent":"376.387063ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:06:52.355273Z","caller":"traceutil/trace.go:171","msg":"trace[1144673885] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"376.26438ms","start":"2026-02-25T10:06:51.978977Z","end":"2026-02-25T10:06:52.355241Z","steps":["trace[1144673885] 'process raft request' (duration: 375.862941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:52.355457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:51.978949Z","time spent":"376.401354ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:06:52.355814Z","caller":"traceutil/trace.go:171","msg":"trace[823093323] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"376.678471ms","start":"2026-02-25T10:06:51.979118Z","end":"2026-02-25T10:06:52.355796Z","steps":["trace[823093323] 'process raft request' (duration: 375.956463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:52.355939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:51.979103Z","time spent":"376.776673ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:06:52.572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.900738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T10:06:52.572116Z","caller":"traceutil/trace.go:171","msg":"trace[1589372895] range","detail":"{range_begin:/registry/masterleases/199.19.213.205; range_end:; response_count:1; response_revision:843; }","duration":"152.077401ms","start":"2026-02-25T10:06:52.420011Z","end":"2026-02-25T10:06:52.572088Z","steps":["trace[1589372895] 'range keys from in-memory index tree' (duration: 150.687549ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:53.041742Z","caller":"traceutil/trace.go:171","msg":"trace[296517996] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"179.411303ms","start":"2026-02-25T10:06:52.862302Z","end":"2026-02-25T10:06:53.041713Z","steps":["trace[296517996] 'process raft request' (duration: 178.348239ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:59.855327Z","caller":"traceutil/trace.go:171","msg":"trace[2027483624] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"110.705043ms","start":"2026-02-25T10:06:59.744596Z","end":"2026-02-25T10:06:59.855301Z","steps":["trace[2027483624] 'process raft request' (duration: 110.56488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:04.154729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.468604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:04.154795Z","caller":"traceutil/trace.go:171","msg":"trace[1377112875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:924; }","duration":"166.578275ms","start":"2026-02-25T10:07:03.988203Z","end":"2026-02-25T10:07:04.154782Z","steps":["trace[1377112875] 'range keys from in-memory index tree' (duration: 166.411272ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:04.666672Z","caller":"traceutil/trace.go:171","msg":"trace[1385191528] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"221.673962ms","start":"2026-02-25T10:07:04.444975Z","end":"2026-02-25T10:07:04.666649Z","steps":["trace[1385191528] 'read index received' (duration: 221.394915ms)","trace[1385191528] 'applied index is now lower than readState.Index' (duration: 278.037µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:04.666759Z","caller":"traceutil/trace.go:171","msg":"trace[1200069244] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"225.207174ms","start":"2026-02-25T10:07:04.44153Z","end":"2026-02-25T10:07:04.666737Z","steps":["trace[1200069244] 'process raft request' (duration: 224.937588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:04.66689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.893407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T10:07:04.666934Z","caller":"traceutil/trace.go:171","msg":"trace[843160312] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:926; }","duration":"221.963988ms","start":"2026-02-25T10:07:04.444958Z","end":"2026-02-25T10:07:04.666922Z","steps":["trace[843160312] 'agreement among raft nodes before linearized reading' (duration: 221.820305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:04.666978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.915797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T10:07:04.667016Z","caller":"traceutil/trace.go:171","msg":"trace[1531275715] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:926; }","duration":"221.965888ms","start":"2026-02-25T10:07:04.44504Z","end":"2026-02-25T10:07:04.667006Z","steps":["trace[1531275715] 'agreement among raft nodes before linearized reading' (duration: 221.872016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:07.175298Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3923370385976216849,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:07:07.676107Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3923370385976216849,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:07:07.731864Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.547847183s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T10:07:07.732229Z","caller":"traceutil/trace.go:171","msg":"trace[649759746] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"1.057840429s","start":"2026-02-25T10:07:06.674368Z","end":"2026-02-25T10:07:07.732208Z","steps":["trace[649759746] 'read index received' (duration: 1.057653635s)","trace[649759746] 'applied index is now lower than readState.Index' (duration: 185.854µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:07.732358Z","caller":"traceutil/trace.go:171","msg":"trace[1646045626] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"1.548403806s","start":"2026-02-25T10:07:06.183918Z","end":"2026-02-25T10:07:07.732322Z","steps":["trace[1646045626] 'process raft request' (duration: 1.54815274s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:07.732461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058071884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T10:07:07.732494Z","caller":"traceutil/trace.go:171","msg":"trace[1003308049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:930; }","duration":"1.058153297s","start":"2026-02-25T10:07:06.674331Z","end":"2026-02-25T10:07:07.732484Z","steps":["trace[1003308049] 'agreement among raft nodes before linearized reading' (duration: 1.057969442s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:07.732528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:06.674316Z","time spent":"1.058204838s","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T10:07:07.732538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:06.183899Z","time spent":"1.548533669s","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:07.732543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050636414s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T10:07:07.732616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.610756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T10:07:07.732653Z","caller":"traceutil/trace.go:171","msg":"trace[2092806435] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:930; }","duration":"775.689898ms","start":"2026-02-25T10:07:06.956954Z","end":"2026-02-25T10:07:07.732644Z","steps":["trace[2092806435] 'agreement among raft nodes before linearized reading' (duration: 775.580676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:07.732681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:06.956935Z","time spent":"775.739199ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":109,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:07:07.732726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.864305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:07.732769Z","caller":"traceutil/trace.go:171","msg":"trace[590747027] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:930; }","duration":"741.940887ms","start":"2026-02-25T10:07:06.990815Z","end":"2026-02-25T10:07:07.732756Z","steps":["trace[590747027] 'agreement among raft nodes before linearized reading' (duration: 741.863035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:07.732793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:06.990798Z","time spent":"741.990897ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:07:07.732985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023073476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T10:07:07.733155Z","caller":"traceutil/trace.go:171","msg":"trace[268650903] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:930; }","duration":"1.023298901s","start":"2026-02-25T10:07:06.709808Z","end":"2026-02-25T10:07:07.733107Z","steps":["trace[268650903] 'agreement among raft nodes before linearized reading' (duration: 1.02282262s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:07.733231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:06.709797Z","time spent":"1.023420213s","remote":"127.0.0.1:37602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:07:07.733582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029678779s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T10:07:07.733669Z","caller":"traceutil/trace.go:171","msg":"trace[881271766] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:930; }","duration":"1.03064109s","start":"2026-02-25T10:07:06.703007Z","end":"2026-02-25T10:07:07.733648Z","steps":["trace[881271766] 'agreement among raft nodes before linearized reading' (duration: 1.029638047s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:07.733726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:06.702986Z","time spent":"1.030720562s","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T10:07:07.732613Z","caller":"traceutil/trace.go:171","msg":"trace[2143842763] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:930; }","duration":"1.050744897s","start":"2026-02-25T10:07:06.681849Z","end":"2026-02-25T10:07:07.732593Z","steps":["trace[2143842763] 'agreement among raft nodes before linearized reading' (duration: 1.050554142s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:07.734215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:06.681834Z","time spent":"1.052363413s","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:07:24.293646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.665866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T10:07:24.293864Z","caller":"traceutil/trace.go:171","msg":"trace[245891551] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:991; }","duration":"419.981652ms","start":"2026-02-25T10:07:23.873848Z","end":"2026-02-25T10:07:24.29383Z","steps":["trace[245891551] 'count revisions from in-memory index tree' (duration: 419.502601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:24.293937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.282384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T10:07:24.294048Z","caller":"traceutil/trace.go:171","msg":"trace[1865713531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:991; }","duration":"301.425578ms","start":"2026-02-25T10:07:23.992597Z","end":"2026-02-25T10:07:24.294023Z","steps":["trace[1865713531] 'range keys from in-memory index tree' (duration: 301.068589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:24.294103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:23.99259Z","time spent":"301.503519ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T10:07:24.294092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.55777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T10:07:24.294174Z","caller":"traceutil/trace.go:171","msg":"trace[973998303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:991; }","duration":"456.685783ms","start":"2026-02-25T10:07:23.837467Z","end":"2026-02-25T10:07:24.294153Z","steps":["trace[973998303] 'range keys from in-memory index tree' (duration: 456.360084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:24.294223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:23.837446Z","time spent":"456.767765ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T10:07:24.294248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.359176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-qm67r\" ","response":"range_response_count:1 size:10928"} {"level":"info","ts":"2026-02-25T10:07:24.294292Z","caller":"traceutil/trace.go:171","msg":"trace[927755025] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-qm67r; range_end:; response_count:1; response_revision:991; }","duration":"945.431398ms","start":"2026-02-25T10:07:23.348846Z","end":"2026-02-25T10:07:24.294277Z","steps":["trace[927755025] 'range keys from in-memory index tree' (duration: 945.160091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:24.294333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:23.348827Z","time spent":"945.498469ms","remote":"127.0.0.1:37446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":10951,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-qm67r\" "} {"level":"warn","ts":"2026-02-25T10:07:24.293976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:23.873818Z","time spent":"420.138506ms","remote":"127.0.0.1:39472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:07:24.294587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.259387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T10:07:24.29642Z","caller":"traceutil/trace.go:171","msg":"trace[879537959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:991; }","duration":"456.1242ms","start":"2026-02-25T10:07:23.84028Z","end":"2026-02-25T10:07:24.296404Z","steps":["trace[879537959] 'range keys from in-memory index tree' (duration: 453.856037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:24.29463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.055302ms","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-25T10:07:24.29482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.461987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T10:07:24.293736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.020541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:07:24.294943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.098197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T10:07:24.296808Z","caller":"traceutil/trace.go:171","msg":"trace[665560112] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:991; }","duration":"454.00384ms","start":"2026-02-25T10:07:23.84279Z","end":"2026-02-25T10:07:24.296794Z","steps":["trace[665560112] 'range keys from in-memory index tree' (duration: 451.953884ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:24.296847Z","caller":"traceutil/trace.go:171","msg":"trace[126387745] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:991; }","duration":"488.316444ms","start":"2026-02-25T10:07:23.808511Z","end":"2026-02-25T10:07:24.296827Z","steps":["trace[126387745] 'range keys from in-memory index tree' (duration: 485.878408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:24.296878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:23.84027Z","time spent":"456.586901ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:07:24.296916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:23.808482Z","time spent":"488.414997ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T10:07:24.296884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:23.842784Z","time spent":"454.075303ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T10:07:24.297007Z","caller":"traceutil/trace.go:171","msg":"trace[1197698199] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:991; }","duration":"426.670597ms","start":"2026-02-25T10:07:23.870311Z","end":"2026-02-25T10:07:24.296982Z","steps":["trace[1197698199] 'count revisions from in-memory index tree' (duration: 424.396835ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:24.29701Z","caller":"traceutil/trace.go:171","msg":"trace[120353604] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:991; }","duration":"305.324818ms","start":"2026-02-25T10:07:23.991665Z","end":"2026-02-25T10:07:24.29699Z","steps":["trace[120353604] 'range keys from in-memory index tree' (duration: 301.95481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:24.297066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:23.870295Z","time spent":"426.75744ms","remote":"127.0.0.1:39500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:07:24.297071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:23.991648Z","time spent":"305.406561ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T10:07:28.243568Z","caller":"traceutil/trace.go:171","msg":"trace[2103150431] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1046; }","duration":"252.241319ms","start":"2026-02-25T10:07:27.991284Z","end":"2026-02-25T10:07:28.243525Z","steps":["trace[2103150431] 'read index received' (duration: 190.601362ms)","trace[2103150431] 'applied index is now lower than readState.Index' (duration: 61.637947ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:28.243826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.488635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:28.243972Z","caller":"traceutil/trace.go:171","msg":"trace[170749773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1005; }","duration":"252.660609ms","start":"2026-02-25T10:07:27.991278Z","end":"2026-02-25T10:07:28.243938Z","steps":["trace[170749773] 'agreement among raft nodes before linearized reading' (duration: 252.394703ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:28.546487Z","caller":"traceutil/trace.go:171","msg":"trace[114404817] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"152.146132ms","start":"2026-02-25T10:07:28.394296Z","end":"2026-02-25T10:07:28.546442Z","steps":["trace[114404817] 'process raft request' (duration: 151.838765ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:38.517918Z","caller":"traceutil/trace.go:171","msg":"trace[1401810977] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"397.206501ms","start":"2026-02-25T10:07:38.120686Z","end":"2026-02-25T10:07:38.517893Z","steps":["trace[1401810977] 'process raft request' (duration: 397.044048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:38.518077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:38.12066Z","time spent":"397.332513ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:39.147934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.367533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T10:07:39.148065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.660813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T10:07:39.148123Z","caller":"traceutil/trace.go:171","msg":"trace[1549775396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1077; }","duration":"539.733795ms","start":"2026-02-25T10:07:38.608365Z","end":"2026-02-25T10:07:39.148099Z","steps":["trace[1549775396] 'range keys from in-memory index tree' (duration: 539.479149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:39.148175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:38.608354Z","time spent":"539.810887ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T10:07:39.148058Z","caller":"traceutil/trace.go:171","msg":"trace[1472918997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1077; }","duration":"538.522957ms","start":"2026-02-25T10:07:38.609506Z","end":"2026-02-25T10:07:39.148028Z","steps":["trace[1472918997] 'range keys from in-memory index tree' (duration: 538.126378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:39.148423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:38.609493Z","time spent":"538.869665ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T10:07:39.147948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.439462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T10:07:39.148535Z","caller":"traceutil/trace.go:171","msg":"trace[634316705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1077; }","duration":"548.087436ms","start":"2026-02-25T10:07:38.600406Z","end":"2026-02-25T10:07:39.148493Z","steps":["trace[634316705] 'range keys from in-memory index tree' (duration: 547.249697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:39.147957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.334881ms","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-25T10:07:39.148689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:38.600367Z","time spent":"548.28001ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:07:39.148188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.20157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:39.148689Z","caller":"traceutil/trace.go:171","msg":"trace[616239155] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1077; }","duration":"542.053868ms","start":"2026-02-25T10:07:38.606599Z","end":"2026-02-25T10:07:39.148653Z","steps":["trace[616239155] 'range keys from in-memory index tree' (duration: 541.076515ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:39.14877Z","caller":"traceutil/trace.go:171","msg":"trace[1662068281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1077; }","duration":"157.815334ms","start":"2026-02-25T10:07:38.990933Z","end":"2026-02-25T10:07:39.148748Z","steps":["trace[1662068281] 'range keys from in-memory index tree' (duration: 157.105238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:39.148802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:38.606485Z","time spent":"542.275502ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T10:07:39.148269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.817567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T10:07:39.148855Z","caller":"traceutil/trace.go:171","msg":"trace[542302470] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1077; }","duration":"540.464232ms","start":"2026-02-25T10:07:38.608379Z","end":"2026-02-25T10:07:39.148843Z","steps":["trace[542302470] 'range keys from in-memory index tree' (duration: 539.541941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:39.14889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:38.60834Z","time spent":"540.540534ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T10:07:39.566795Z","caller":"traceutil/trace.go:171","msg":"trace[1685976951] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"410.469883ms","start":"2026-02-25T10:07:39.156296Z","end":"2026-02-25T10:07:39.566766Z","steps":["trace[1685976951] 'process raft request' (duration: 410.385631ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:39.566809Z","caller":"traceutil/trace.go:171","msg":"trace[47499925] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"410.733819ms","start":"2026-02-25T10:07:39.156033Z","end":"2026-02-25T10:07:39.566767Z","steps":["trace[47499925] 'process raft request' (duration: 389.431153ms)","trace[47499925] 'compare' (duration: 21.02668ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:39.567133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:39.156281Z","time spent":"410.601146ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:39.567162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:39.155991Z","time spent":"411.118168ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:39.56731Z","caller":"traceutil/trace.go:171","msg":"trace[2086295002] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"410.915313ms","start":"2026-02-25T10:07:39.156364Z","end":"2026-02-25T10:07:39.567279Z","steps":["trace[2086295002] 'process raft request' (duration: 410.36257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:39.567701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:39.156337Z","time spent":"411.228501ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:39.567805Z","caller":"traceutil/trace.go:171","msg":"trace[560897455] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1122; }","duration":"279.466772ms","start":"2026-02-25T10:07:39.28833Z","end":"2026-02-25T10:07:39.567797Z","steps":["trace[560897455] 'read index received' (duration: 257.060461ms)","trace[560897455] 'applied index is now lower than readState.Index' (duration: 22.405301ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:39.567712Z","caller":"traceutil/trace.go:171","msg":"trace[1542191556] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"410.843752ms","start":"2026-02-25T10:07:39.156856Z","end":"2026-02-25T10:07:39.567699Z","steps":["trace[1542191556] 'process raft request' (duration: 410.313979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:39.567882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:39.156832Z","time spent":"411.016726ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:39.567949Z","caller":"traceutil/trace.go:171","msg":"trace[1010745998] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"410.687688ms","start":"2026-02-25T10:07:39.157251Z","end":"2026-02-25T10:07:39.567939Z","steps":["trace[1010745998] 'process raft request' (duration: 410.406082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:39.568038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:39.157233Z","time spent":"410.770251ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:39.568094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.769079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T10:07:39.568203Z","caller":"traceutil/trace.go:171","msg":"trace[1370578430] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1083; }","duration":"279.914992ms","start":"2026-02-25T10:07:39.288271Z","end":"2026-02-25T10:07:39.568186Z","steps":["trace[1370578430] 'agreement among raft nodes before linearized reading' (duration: 279.781659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:39.568266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.107024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-25T10:07:39.568387Z","caller":"traceutil/trace.go:171","msg":"trace[1578024431] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1083; }","duration":"212.267717ms","start":"2026-02-25T10:07:39.356089Z","end":"2026-02-25T10:07:39.568357Z","steps":["trace[1578024431] 'agreement among raft nodes before linearized reading' (duration: 211.9395ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:11:24.881597Z","caller":"traceutil/trace.go:171","msg":"trace[63318445] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"107.957329ms","start":"2026-02-25T10:11:24.773607Z","end":"2026-02-25T10:11:24.881565Z","steps":["trace[63318445] 'process raft request' (duration: 72.249663ms)","trace[63318445] 'compare' (duration: 35.047111ms)"],"step_count":2}