{"level":"warn","ts":"2026-02-04T01:47:00.347608Z","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-04T01:47:00.347706Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.212: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://162.253.55.212:2380","--initial-cluster=instance=https://162.253.55.212:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.212:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.212: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-04T01:47:00.348057Z","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-04T01:47:00.348076Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-02-04T01:47:00.348227Z","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-04T01:47:00.349104Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"]} {"level":"info","ts":"2026-02-04T01:47:00.349274Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.212: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-04T01:47:00.38478Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"35.106248ms"} {"level":"info","ts":"2026-02-04T01:47:00.488984Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d078b1e39c21939c","cluster-id":"cdc9ba8de57e3589"} {"level":"info","ts":"2026-02-04T01:47:00.489145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=()"} {"level":"info","ts":"2026-02-04T01:47:00.489223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 0"} {"level":"info","ts":"2026-02-04T01:47:00.48924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d078b1e39c21939c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-04T01:47:00.489291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 1"} {"level":"info","ts":"2026-02-04T01:47:00.489385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"warn","ts":"2026-02-04T01:47:00.536902Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-04T01:47:00.559954Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-04T01:47:00.582896Z","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-04T01:47:00.611174Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d078b1e39c21939c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-04T01:47:00.612097Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d078b1e39c21939c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-04T01:47:00.613395Z","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-04T01:47:00.613709Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d078b1e39c21939c","initial-advertise-peer-urls":["https://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-04T01:47:00.613757Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-04T01:47:00.613733Z","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-04T01:47:00.618112Z","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-04T01:47:00.618135Z","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-04T01:47:00.613423Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-02-04T01:47:00.619717Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-02-04T01:47:00.652865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"info","ts":"2026-02-04T01:47:00.652969Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","added-peer-id":"d078b1e39c21939c","added-peer-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-02-04T01:47:00.914203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c is starting a new election at term 1"} {"level":"info","ts":"2026-02-04T01:47:00.914291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became pre-candidate at term 1"} {"level":"info","ts":"2026-02-04T01:47:00.914324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgPreVoteResp from d078b1e39c21939c at term 1"} {"level":"info","ts":"2026-02-04T01:47:00.91435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became candidate at term 2"} {"level":"info","ts":"2026-02-04T01:47:00.914394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgVoteResp from d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-02-04T01:47:00.914418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became leader at term 2"} {"level":"info","ts":"2026-02-04T01:47:00.914449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d078b1e39c21939c elected leader d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-02-04T01:47:00.922341Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d078b1e39c21939c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.212:2379]}","request-path":"/0/members/d078b1e39c21939c/attributes","cluster-id":"cdc9ba8de57e3589","publish-timeout":"7s"} {"level":"info","ts":"2026-02-04T01:47:00.922478Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T01:47:00.922868Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:47:00.926179Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T01:47:00.927787Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.212:2379"} {"level":"info","ts":"2026-02-04T01:47:00.928078Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:47:00.928157Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:47:00.928199Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:47:00.929692Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-04T01:47:00.929771Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-04T01:47:00.929791Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-04T01:47:03.128233Z","caller":"traceutil/trace.go:171","msg":"trace[1381120539] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"119.668577ms","start":"2026-02-04T01:47:03.008524Z","end":"2026-02-04T01:47:03.128193Z","steps":["trace[1381120539] 'process raft request' (duration: 101.176993ms)","trace[1381120539] 'compare' (duration: 18.323702ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:03.128675Z","caller":"traceutil/trace.go:171","msg":"trace[1079404087] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"111.93724ms","start":"2026-02-04T01:47:03.016729Z","end":"2026-02-04T01:47:03.128666Z","steps":["trace[1079404087] 'process raft request' (duration: 111.567954ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:03.129022Z","caller":"traceutil/trace.go:171","msg":"trace[829458993] linearizableReadLoop","detail":"{readStateIndex:11; appliedIndex:5; }","duration":"103.695415ms","start":"2026-02-04T01:47:03.02531Z","end":"2026-02-04T01:47:03.129005Z","steps":["trace[829458993] 'read index received' (duration: 23.637748ms)","trace[829458993] 'applied index is now lower than readState.Index' (duration: 80.056627ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:03.129194Z","caller":"traceutil/trace.go:171","msg":"trace[958965915] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"112.595642ms","start":"2026-02-04T01:47:03.016585Z","end":"2026-02-04T01:47:03.129181Z","steps":["trace[958965915] 'process raft request' (duration: 111.628446ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:03.129424Z","caller":"traceutil/trace.go:171","msg":"trace[2034426036] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"112.724983ms","start":"2026-02-04T01:47:03.016685Z","end":"2026-02-04T01:47:03.12941Z","steps":["trace[2034426036] 'process raft request' (duration: 111.551854ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:03.129636Z","caller":"traceutil/trace.go:171","msg":"trace[200602505] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"112.921367ms","start":"2026-02-04T01:47:03.016708Z","end":"2026-02-04T01:47:03.129629Z","steps":["trace[200602505] 'process raft request' (duration: 111.569525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:03.132837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.326465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-04T01:47:03.132883Z","caller":"traceutil/trace.go:171","msg":"trace[953935299] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"107.579698ms","start":"2026-02-04T01:47:03.025292Z","end":"2026-02-04T01:47:03.132872Z","steps":["trace[953935299] 'agreement among raft nodes before linearized reading' (duration: 104.238443ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:03.129049Z","caller":"traceutil/trace.go:171","msg":"trace[701333485] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"116.429854ms","start":"2026-02-04T01:47:03.01261Z","end":"2026-02-04T01:47:03.12904Z","steps":["trace[701333485] 'process raft request' (duration: 115.511849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:03.53223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.711887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-04T01:47:03.53262Z","caller":"traceutil/trace.go:171","msg":"trace[684917815] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"191.650332ms","start":"2026-02-04T01:47:03.340918Z","end":"2026-02-04T01:47:03.532568Z","steps":["trace[684917815] 'process raft request' (duration: 47.974349ms)","trace[684917815] 'compare' (duration: 142.566665ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:03.544312Z","caller":"traceutil/trace.go:171","msg":"trace[733062893] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"202.869265ms","start":"2026-02-04T01:47:03.341414Z","end":"2026-02-04T01:47:03.544283Z","steps":["trace[733062893] 'process raft request' (duration: 202.155893ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:03.545213Z","caller":"traceutil/trace.go:171","msg":"trace[1688200762] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"202.451879ms","start":"2026-02-04T01:47:03.342746Z","end":"2026-02-04T01:47:03.545198Z","steps":["trace[1688200762] 'process raft request' (duration: 200.964085ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:03.798514Z","caller":"traceutil/trace.go:171","msg":"trace[883155084] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"100.926929ms","start":"2026-02-04T01:47:03.697559Z","end":"2026-02-04T01:47:03.798486Z","steps":["trace[883155084] 'process raft request' (duration: 51.444705ms)","trace[883155084] 'compare' (duration: 49.384542ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:04.186224Z","caller":"traceutil/trace.go:171","msg":"trace[1293420145] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"190.838941ms","start":"2026-02-04T01:47:03.995366Z","end":"2026-02-04T01:47:04.186205Z","steps":["trace[1293420145] 'process raft request' (duration: 133.165258ms)","trace[1293420145] 'compare' (duration: 57.410509ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:04.589524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.508741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-04T01:47:04.59011Z","caller":"traceutil/trace.go:171","msg":"trace[1476107331] transaction","detail":"{read_only:false; response_revision:82; number_of_response:1; }","duration":"135.600786ms","start":"2026-02-04T01:47:04.454477Z","end":"2026-02-04T01:47:04.590078Z","steps":["trace[1476107331] 'process raft request' (duration: 20.416944ms)","trace[1476107331] 'compare' (duration: 114.036344ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:05.332525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.47839ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-04T01:47:05.333962Z","caller":"traceutil/trace.go:171","msg":"trace[1167500514] transaction","detail":"{read_only:false; response_revision:98; number_of_response:1; }","duration":"253.926197ms","start":"2026-02-04T01:47:05.080009Z","end":"2026-02-04T01:47:05.333935Z","steps":["trace[1167500514] 'process raft request' (duration: 126.906692ms)","trace[1167500514] 'compare' (duration: 125.315128ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:05.335212Z","caller":"traceutil/trace.go:171","msg":"trace[1749177444] linearizableReadLoop","detail":"{readStateIndex:103; appliedIndex:101; }","duration":"165.600992ms","start":"2026-02-04T01:47:05.169595Z","end":"2026-02-04T01:47:05.335196Z","steps":["trace[1749177444] 'read index received' (duration: 37.342827ms)","trace[1749177444] 'applied index is now lower than readState.Index' (duration: 128.256865ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:05.335833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.223222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-04T01:47:05.335992Z","caller":"traceutil/trace.go:171","msg":"trace[949736703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:98; }","duration":"166.371775ms","start":"2026-02-04T01:47:05.169562Z","end":"2026-02-04T01:47:05.335934Z","steps":["trace[949736703] 'agreement among raft nodes before linearized reading' (duration: 166.156581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:06.333801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.02985ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:47:06.333926Z","caller":"traceutil/trace.go:171","msg":"trace[1016389297] transaction","detail":"{read_only:false; response_revision:201; number_of_response:1; }","duration":"154.157021ms","start":"2026-02-04T01:47:06.179749Z","end":"2026-02-04T01:47:06.333907Z","steps":["trace[1016389297] 'process raft request' (duration: 12.768666ms)","trace[1016389297] 'compare' (duration: 140.838097ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:07.112111Z","caller":"traceutil/trace.go:171","msg":"trace[602249213] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"113.78525ms","start":"2026-02-04T01:47:06.998296Z","end":"2026-02-04T01:47:07.112081Z","steps":["trace[602249213] 'process raft request' (duration: 102.327245ms)","trace[602249213] 'compare' (duration: 11.123139ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:07.514468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.599982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-04T01:47:07.514858Z","caller":"traceutil/trace.go:171","msg":"trace[11143208] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:244; }","duration":"166.02239ms","start":"2026-02-04T01:47:07.348822Z","end":"2026-02-04T01:47:07.514844Z","steps":["trace[11143208] 'range keys from in-memory index tree' (duration: 165.524091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:12.706102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.607943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-04T01:47:12.70624Z","caller":"traceutil/trace.go:171","msg":"trace[926525033] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:363; }","duration":"122.778215ms","start":"2026-02-04T01:47:12.583448Z","end":"2026-02-04T01:47:12.706227Z","steps":["trace[926525033] 'range keys from in-memory index tree' (duration: 122.49973ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:13.090932Z","caller":"traceutil/trace.go:171","msg":"trace[2087415717] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"217.362352ms","start":"2026-02-04T01:47:12.873552Z","end":"2026-02-04T01:47:13.090915Z","steps":["trace[2087415717] 'process raft request' (duration: 192.339451ms)","trace[2087415717] 'compare' (duration: 24.95835ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:13.327742Z","caller":"traceutil/trace.go:171","msg":"trace[633396143] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:390; }","duration":"347.190069ms","start":"2026-02-04T01:47:12.980528Z","end":"2026-02-04T01:47:13.327718Z","steps":["trace[633396143] 'read index received' (duration: 85.324942ms)","trace[633396143] 'applied index is now lower than readState.Index' (duration: 261.864177ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:13.328204Z","caller":"traceutil/trace.go:171","msg":"trace[679312041] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"451.269432ms","start":"2026-02-04T01:47:12.876917Z","end":"2026-02-04T01:47:13.328187Z","steps":["trace[679312041] 'process raft request' (duration: 380.762651ms)","trace[679312041] 'compare' (duration: 69.956152ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:13.328451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:12.876903Z","time spent":"451.337203ms","remote":"127.0.0.1:57388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3505,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T01:47:13.328859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.325878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-04T01:47:13.328903Z","caller":"traceutil/trace.go:171","msg":"trace[212785475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:380; }","duration":"348.403629ms","start":"2026-02-04T01:47:12.980487Z","end":"2026-02-04T01:47:13.328891Z","steps":["trace[212785475] 'agreement among raft nodes before linearized reading' (duration: 348.301537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:13.329097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:12.980468Z","time spent":"348.616242ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T01:47:13.333873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.036616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-02-04T01:47:13.333927Z","caller":"traceutil/trace.go:171","msg":"trace[905476573] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:380; }","duration":"322.119137ms","start":"2026-02-04T01:47:13.011792Z","end":"2026-02-04T01:47:13.333911Z","steps":["trace[905476573] 'agreement among raft nodes before linearized reading' (duration: 322.023566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:13.334251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:13.011776Z","time spent":"322.356162ms","remote":"127.0.0.1:57398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":209,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-02-04T01:47:13.568277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.665816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:47:13.568739Z","caller":"traceutil/trace.go:171","msg":"trace[1681532260] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"222.780808ms","start":"2026-02-04T01:47:13.34594Z","end":"2026-02-04T01:47:13.568721Z","steps":["trace[1681532260] 'process raft request' (duration: 222.740227ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:13.569135Z","caller":"traceutil/trace.go:171","msg":"trace[277300323] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"233.87154ms","start":"2026-02-04T01:47:13.335248Z","end":"2026-02-04T01:47:13.569119Z","steps":["trace[277300323] 'process raft request' (duration: 101.291701ms)","trace[277300323] 'compare' (duration: 131.469662ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:13.569248Z","caller":"traceutil/trace.go:171","msg":"trace[2125680150] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"224.789609ms","start":"2026-02-04T01:47:13.344445Z","end":"2026-02-04T01:47:13.569235Z","steps":["trace[2125680150] 'process raft request' (duration: 224.133028ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:13.569309Z","caller":"traceutil/trace.go:171","msg":"trace[470579275] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:392; }","duration":"231.31639ms","start":"2026-02-04T01:47:13.337982Z","end":"2026-02-04T01:47:13.569298Z","steps":["trace[470579275] 'read index received' (duration: 98.586618ms)","trace[470579275] 'applied index is now lower than readState.Index' (duration: 132.702461ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:13.569403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.408381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-2rtkq\" ","response":"range_response_count:1 size:4421"} {"level":"info","ts":"2026-02-04T01:47:13.570651Z","caller":"traceutil/trace.go:171","msg":"trace[1066972334] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-2rtkq; range_end:; response_count:1; response_revision:386; }","duration":"232.680072ms","start":"2026-02-04T01:47:13.337957Z","end":"2026-02-04T01:47:13.570637Z","steps":["trace[1066972334] 'agreement among raft nodes before linearized reading' (duration: 231.373351ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:13.569605Z","caller":"traceutil/trace.go:171","msg":"trace[1287253235] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"227.291978ms","start":"2026-02-04T01:47:13.342293Z","end":"2026-02-04T01:47:13.569585Z","steps":["trace[1287253235] 'process raft request' (duration: 226.176921ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:13.569827Z","caller":"traceutil/trace.go:171","msg":"trace[701796030] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"225.282567ms","start":"2026-02-04T01:47:13.344535Z","end":"2026-02-04T01:47:13.569817Z","steps":["trace[701796030] 'process raft request' (duration: 224.120029ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:13.569874Z","caller":"traceutil/trace.go:171","msg":"trace[803937337] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"225.363917ms","start":"2026-02-04T01:47:13.344492Z","end":"2026-02-04T01:47:13.569856Z","steps":["trace[803937337] 'process raft request' (duration: 224.119288ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:13.745665Z","caller":"traceutil/trace.go:171","msg":"trace[1947848400] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"152.888306ms","start":"2026-02-04T01:47:13.592749Z","end":"2026-02-04T01:47:13.745638Z","steps":["trace[1947848400] 'process raft request' (duration: 101.145469ms)","trace[1947848400] 'compare' (duration: 51.542444ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:13.745914Z","caller":"traceutil/trace.go:171","msg":"trace[532348718] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"140.459602ms","start":"2026-02-04T01:47:13.605438Z","end":"2026-02-04T01:47:13.745897Z","steps":["trace[532348718] 'process raft request' (duration: 140.424002ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:13.746085Z","caller":"traceutil/trace.go:171","msg":"trace[337976140] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"143.010042ms","start":"2026-02-04T01:47:13.603068Z","end":"2026-02-04T01:47:13.746078Z","steps":["trace[337976140] 'process raft request' (duration: 142.520214ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:13.746722Z","caller":"traceutil/trace.go:171","msg":"trace[1703443539] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"141.501279ms","start":"2026-02-04T01:47:13.605205Z","end":"2026-02-04T01:47:13.746706Z","steps":["trace[1703443539] 'process raft request' (duration: 140.596575ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:13.752455Z","caller":"traceutil/trace.go:171","msg":"trace[2029539497] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"108.669096ms","start":"2026-02-04T01:47:13.643777Z","end":"2026-02-04T01:47:13.752446Z","steps":["trace[2029539497] 'process raft request' (duration: 108.564725ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:13.820611Z","caller":"traceutil/trace.go:171","msg":"trace[1031434273] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"112.8604ms","start":"2026-02-04T01:47:13.707731Z","end":"2026-02-04T01:47:13.820592Z","steps":["trace[1031434273] 'process raft request' (duration: 112.766139ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:14.068239Z","caller":"traceutil/trace.go:171","msg":"trace[934280647] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"312.116666ms","start":"2026-02-04T01:47:13.756085Z","end":"2026-02-04T01:47:14.068202Z","steps":["trace[934280647] 'process raft request' (duration: 249.091496ms)","trace[934280647] 'compare' (duration: 62.848047ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:14.068721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:13.756066Z","time spent":"312.263908ms","remote":"127.0.0.1:57682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:47:14.422234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.303456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:47:14.423026Z","caller":"traceutil/trace.go:171","msg":"trace[1518955298] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"345.313706ms","start":"2026-02-04T01:47:14.07769Z","end":"2026-02-04T01:47:14.423004Z","steps":["trace[1518955298] 'process raft request' (duration: 129.025495ms)","trace[1518955298] 'compare' (duration: 215.164294ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:14.423272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:14.077664Z","time spent":"345.537691ms","remote":"127.0.0.1:57642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4019,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:47:14.825068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.925534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:47:14.825149Z","caller":"traceutil/trace.go:171","msg":"trace[1984267896] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:407; }","duration":"251.62893ms","start":"2026-02-04T01:47:14.573506Z","end":"2026-02-04T01:47:14.825135Z","steps":["trace[1984267896] 'read index received' (duration: 55.554954ms)","trace[1984267896] 'applied index is now lower than readState.Index' (duration: 196.073266ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:14.825203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.700971ms","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-04T01:47:14.825219Z","caller":"traceutil/trace.go:171","msg":"trace[1510516633] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:396; }","duration":"251.774872ms","start":"2026-02-04T01:47:14.573438Z","end":"2026-02-04T01:47:14.825213Z","steps":["trace[1510516633] 'agreement among raft nodes before linearized reading' (duration: 251.730571ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:14.825319Z","caller":"traceutil/trace.go:171","msg":"trace[550316594] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"392.697352ms","start":"2026-02-04T01:47:14.432612Z","end":"2026-02-04T01:47:14.82531Z","steps":["trace[550316594] 'process raft request' (duration: 196.459273ms)","trace[550316594] 'compare' (duration: 195.812842ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:14.825354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:14.432595Z","time spent":"392.738313ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:47:18.127594Z","caller":"traceutil/trace.go:171","msg":"trace[1015674670] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:422; }","duration":"454.344264ms","start":"2026-02-04T01:47:17.673224Z","end":"2026-02-04T01:47:18.127568Z","steps":["trace[1015674670] 'read index received' (duration: 454.10524ms)","trace[1015674670] 'applied index is now lower than readState.Index' (duration: 237.694µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:18.127946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.13025ms","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-04T01:47:18.127985Z","caller":"traceutil/trace.go:171","msg":"trace[1449942809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:409; }","duration":"351.208672ms","start":"2026-02-04T01:47:17.776763Z","end":"2026-02-04T01:47:18.127972Z","steps":["trace[1449942809] 'agreement among raft nodes before linearized reading' (duration: 351.120081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:18.128019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:17.776747Z","time spent":"351.263552ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T01:47:18.128007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.7488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-04T01:47:18.128093Z","caller":"traceutil/trace.go:171","msg":"trace[1161801665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:409; }","duration":"454.882001ms","start":"2026-02-04T01:47:17.673188Z","end":"2026-02-04T01:47:18.12807Z","steps":["trace[1161801665] 'agreement among raft nodes before linearized reading' (duration: 454.599987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:18.128132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:17.673171Z","time spent":"454.952643ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-04T01:47:18.128373Z","caller":"traceutil/trace.go:171","msg":"trace[781236967] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"755.106107ms","start":"2026-02-04T01:47:17.373255Z","end":"2026-02-04T01:47:18.128361Z","steps":["trace[781236967] 'process raft request' (duration: 754.00694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:18.128462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:17.373239Z","time spent":"755.164617ms","remote":"127.0.0.1:57388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5255,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:47:24.244091Z","caller":"traceutil/trace.go:171","msg":"trace[155269468] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:444; }","duration":"206.340534ms","start":"2026-02-04T01:47:24.037726Z","end":"2026-02-04T01:47:24.244066Z","steps":["trace[155269468] 'read index received' (duration: 206.095531ms)","trace[155269468] 'applied index is now lower than readState.Index' (duration: 243.953µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:24.244281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.526818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-04T01:47:24.244332Z","caller":"traceutil/trace.go:171","msg":"trace[1649383538] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:430; }","duration":"206.632999ms","start":"2026-02-04T01:47:24.037683Z","end":"2026-02-04T01:47:24.244316Z","steps":["trace[1649383538] 'agreement among raft nodes before linearized reading' (duration: 206.491607ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:24.244468Z","caller":"traceutil/trace.go:171","msg":"trace[1812000996] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"343.348867ms","start":"2026-02-04T01:47:23.9011Z","end":"2026-02-04T01:47:24.244449Z","steps":["trace[1812000996] 'process raft request' (duration: 342.786049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:24.244604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:23.901075Z","time spent":"343.441749ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:47:24.382663Z","caller":"traceutil/trace.go:171","msg":"trace[117025032] linearizableReadLoop","detail":"{readStateIndex:446; appliedIndex:445; }","duration":"117.623429ms","start":"2026-02-04T01:47:24.26501Z","end":"2026-02-04T01:47:24.382633Z","steps":["trace[117025032] 'read index received' (duration: 105.502608ms)","trace[117025032] 'applied index is now lower than readState.Index' (duration: 12.119621ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:24.383276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.240739ms","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-04T01:47:24.383594Z","caller":"traceutil/trace.go:171","msg":"trace[1885114116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:431; }","duration":"118.586463ms","start":"2026-02-04T01:47:24.264986Z","end":"2026-02-04T01:47:24.383572Z","steps":["trace[1885114116] 'agreement among raft nodes before linearized reading' (duration: 118.075096ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:24.384117Z","caller":"traceutil/trace.go:171","msg":"trace[370558084] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"125.876922ms","start":"2026-02-04T01:47:24.25822Z","end":"2026-02-04T01:47:24.384097Z","steps":["trace[370558084] 'process raft request' (duration: 112.244259ms)","trace[370558084] 'compare' (duration: 11.964028ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:24.383276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.779262ms","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-04T01:47:24.384936Z","caller":"traceutil/trace.go:171","msg":"trace[944812734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:431; }","duration":"119.443666ms","start":"2026-02-04T01:47:24.265457Z","end":"2026-02-04T01:47:24.384901Z","steps":["trace[944812734] 'agreement among raft nodes before linearized reading' (duration: 117.752991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:24.679547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.015951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:47:24.679687Z","caller":"traceutil/trace.go:171","msg":"trace[951464220] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"191.38428ms","start":"2026-02-04T01:47:24.488288Z","end":"2026-02-04T01:47:24.679673Z","steps":["trace[951464220] 'process raft request' (duration: 15.196676ms)","trace[951464220] 'compare' (duration: 175.833228ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:24.680059Z","caller":"traceutil/trace.go:171","msg":"trace[868841460] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"190.924943ms","start":"2026-02-04T01:47:24.489128Z","end":"2026-02-04T01:47:24.680053Z","steps":["trace[868841460] 'process raft request' (duration: 190.507107ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:24.685372Z","caller":"traceutil/trace.go:171","msg":"trace[618340829] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"196.07156ms","start":"2026-02-04T01:47:24.489265Z","end":"2026-02-04T01:47:24.685337Z","steps":["trace[618340829] 'process raft request' (duration: 190.72027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:25.214927Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548108388409289,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:47:25.430456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.520109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:47:25.430618Z","caller":"traceutil/trace.go:171","msg":"trace[297355399] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"732.03586ms","start":"2026-02-04T01:47:24.698552Z","end":"2026-02-04T01:47:25.430588Z","steps":["trace[297355399] 'process raft request' (duration: 594.233227ms)","trace[297355399] 'compare' (duration: 137.274685ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:25.430728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:24.69853Z","time spent":"732.159822ms","remote":"127.0.0.1:57682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:47:25.46563Z","caller":"traceutil/trace.go:171","msg":"trace[1652398157] linearizableReadLoop","detail":"{readStateIndex:457; appliedIndex:452; }","duration":"750.818378ms","start":"2026-02-04T01:47:24.71479Z","end":"2026-02-04T01:47:25.465609Z","steps":["trace[1652398157] 'read index received' (duration: 577.884242ms)","trace[1652398157] 'applied index is now lower than readState.Index' (duration: 172.933076ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:25.465736Z","caller":"traceutil/trace.go:171","msg":"trace[1200969165] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"766.910009ms","start":"2026-02-04T01:47:24.698817Z","end":"2026-02-04T01:47:25.465727Z","steps":["trace[1200969165] 'process raft request' (duration: 766.553423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:25.465827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:24.698811Z","time spent":"766.946009ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:47:25.466196Z","caller":"traceutil/trace.go:171","msg":"trace[934484357] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"765.762742ms","start":"2026-02-04T01:47:24.700395Z","end":"2026-02-04T01:47:25.466158Z","steps":["trace[934484357] 'process raft request' (duration: 765.090402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:25.46627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:24.700388Z","time spent":"765.829353ms","remote":"127.0.0.1:57388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4015,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:47:25.466394Z","caller":"traceutil/trace.go:171","msg":"trace[605835751] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"765.194484ms","start":"2026-02-04T01:47:24.701192Z","end":"2026-02-04T01:47:25.466386Z","steps":["trace[605835751] 'process raft request' (duration: 764.350661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:25.466436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:24.701185Z","time spent":"765.232494ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:47:25.466607Z","caller":"traceutil/trace.go:171","msg":"trace[1769430025] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"763.346696ms","start":"2026-02-04T01:47:24.703251Z","end":"2026-02-04T01:47:25.466598Z","steps":["trace[1769430025] 'process raft request' (duration: 762.32006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:25.466657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:24.703241Z","time spent":"763.390316ms","remote":"127.0.0.1:57682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3773,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:47:25.466846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.026787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:47:25.466981Z","caller":"traceutil/trace.go:171","msg":"trace[721911082] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:442; }","duration":"752.200499ms","start":"2026-02-04T01:47:24.714753Z","end":"2026-02-04T01:47:25.466953Z","steps":["trace[721911082] 'agreement among raft nodes before linearized reading' (duration: 752.006346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:25.467107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:24.714731Z","time spent":"752.365721ms","remote":"127.0.0.1:57164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:47:25.48914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.855814ms","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-04T01:47:25.489185Z","caller":"traceutil/trace.go:171","msg":"trace[1740810445] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:443; }","duration":"535.928786ms","start":"2026-02-04T01:47:24.953246Z","end":"2026-02-04T01:47:25.489174Z","steps":["trace[1740810445] 'agreement among raft nodes before linearized reading' (duration: 535.866365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:25.489203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:24.953234Z","time spent":"535.964376ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T01:47:25.489275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.937321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T01:47:25.48929Z","caller":"traceutil/trace.go:171","msg":"trace[824819275] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:443; }","duration":"542.983071ms","start":"2026-02-04T01:47:24.946301Z","end":"2026-02-04T01:47:25.489284Z","steps":["trace[824819275] 'agreement among raft nodes before linearized reading' (duration: 542.96046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:25.489304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:24.946282Z","time spent":"543.018871ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-04T01:47:25.615611Z","caller":"traceutil/trace.go:171","msg":"trace[1081974166] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"135.378827ms","start":"2026-02-04T01:47:25.480223Z","end":"2026-02-04T01:47:25.615602Z","steps":["trace[1081974166] 'process raft request' (duration: 124.606196ms)","trace[1081974166] 'compare' (duration: 10.460396ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:25.615435Z","caller":"traceutil/trace.go:171","msg":"trace[506943158] linearizableReadLoop","detail":"{readStateIndex:459; appliedIndex:458; }","duration":"126.463514ms","start":"2026-02-04T01:47:25.488954Z","end":"2026-02-04T01:47:25.615418Z","steps":["trace[506943158] 'read index received' (duration: 115.960867ms)","trace[506943158] 'applied index is now lower than readState.Index' (duration: 10.499637ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:25.615886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.140872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:47:25.615911Z","caller":"traceutil/trace.go:171","msg":"trace[919563438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:444; }","duration":"143.226784ms","start":"2026-02-04T01:47:25.472678Z","end":"2026-02-04T01:47:25.615905Z","steps":["trace[919563438] 'agreement among raft nodes before linearized reading' (duration: 143.161733ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:25.618143Z","caller":"traceutil/trace.go:171","msg":"trace[950875308] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"121.343318ms","start":"2026-02-04T01:47:25.496782Z","end":"2026-02-04T01:47:25.618125Z","steps":["trace[950875308] 'process raft request' (duration: 121.313388ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:25.618522Z","caller":"traceutil/trace.go:171","msg":"trace[1930100085] transaction","detail":"{read_only:false; number_of_response:1; response_revision:445; }","duration":"121.906866ms","start":"2026-02-04T01:47:25.496604Z","end":"2026-02-04T01:47:25.618511Z","steps":["trace[1930100085] 'process raft request' (duration: 121.407078ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:25.618824Z","caller":"traceutil/trace.go:171","msg":"trace[1967695353] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"122.066718ms","start":"2026-02-04T01:47:25.49675Z","end":"2026-02-04T01:47:25.618817Z","steps":["trace[1967695353] 'process raft request' (duration: 121.292997ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:25.619038Z","caller":"traceutil/trace.go:171","msg":"trace[2084354004] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"126.82567ms","start":"2026-02-04T01:47:25.492207Z","end":"2026-02-04T01:47:25.619033Z","steps":["trace[2084354004] 'process raft request' (duration: 125.719613ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:25.876503Z","caller":"traceutil/trace.go:171","msg":"trace[964677401] linearizableReadLoop","detail":"{readStateIndex:478; appliedIndex:477; }","duration":"178.888985ms","start":"2026-02-04T01:47:25.697594Z","end":"2026-02-04T01:47:25.876482Z","steps":["trace[964677401] 'read index received' (duration: 9.012295ms)","trace[964677401] 'applied index is now lower than readState.Index' (duration: 169.87576ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:25.876985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.367642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" ","response":"range_response_count:1 size:3833"} {"level":"info","ts":"2026-02-04T01:47:25.877324Z","caller":"traceutil/trace.go:171","msg":"trace[87789226] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-77cccfdc44; range_end:; response_count:1; response_revision:461; }","duration":"179.727517ms","start":"2026-02-04T01:47:25.697583Z","end":"2026-02-04T01:47:25.87731Z","steps":["trace[87789226] 'agreement among raft nodes before linearized reading' (duration: 179.25155ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:25.877031Z","caller":"traceutil/trace.go:171","msg":"trace[1436765957] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"180.142103ms","start":"2026-02-04T01:47:25.696876Z","end":"2026-02-04T01:47:25.877018Z","steps":["trace[1436765957] 'process raft request' (duration: 110.174501ms)","trace[1436765957] 'compare' (duration: 69.337183ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:25.912706Z","caller":"traceutil/trace.go:171","msg":"trace[682238391] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"196.088701ms","start":"2026-02-04T01:47:25.7166Z","end":"2026-02-04T01:47:25.912688Z","steps":["trace[682238391] 'process raft request' (duration: 195.973189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:25.913664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.920829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:47:25.91392Z","caller":"traceutil/trace.go:171","msg":"trace[152888417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:462; }","duration":"196.180543ms","start":"2026-02-04T01:47:25.717724Z","end":"2026-02-04T01:47:25.913905Z","steps":["trace[152888417] 'agreement among raft nodes before linearized reading' (duration: 195.901898ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:26.022211Z","caller":"traceutil/trace.go:171","msg":"trace[788323119] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"135.169353ms","start":"2026-02-04T01:47:25.887025Z","end":"2026-02-04T01:47:26.022194Z","steps":["trace[788323119] 'process raft request' (duration: 92.075942ms)","trace[788323119] 'compare' (duration: 43.02046ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:27.73835Z","caller":"traceutil/trace.go:171","msg":"trace[313665658] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"109.813176ms","start":"2026-02-04T01:47:27.62852Z","end":"2026-02-04T01:47:27.738333Z","steps":["trace[313665658] 'process raft request' (duration: 109.551162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:32.869685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.606821ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-04T01:47:32.870258Z","caller":"traceutil/trace.go:171","msg":"trace[93120525] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"282.806206ms","start":"2026-02-04T01:47:32.58744Z","end":"2026-02-04T01:47:32.870247Z","steps":["trace[93120525] 'process raft request' (duration: 282.368549ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:32.86998Z","caller":"traceutil/trace.go:171","msg":"trace[1931797335] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:497; }","duration":"155.126573ms","start":"2026-02-04T01:47:32.714836Z","end":"2026-02-04T01:47:32.869963Z","steps":["trace[1931797335] 'read index received' (duration: 26.443075ms)","trace[1931797335] 'applied index is now lower than readState.Index' (duration: 128.682658ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:32.870753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.910445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:47:32.870781Z","caller":"traceutil/trace.go:171","msg":"trace[1207934965] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:480; }","duration":"155.944506ms","start":"2026-02-04T01:47:32.714827Z","end":"2026-02-04T01:47:32.870771Z","steps":["trace[1207934965] 'agreement among raft nodes before linearized reading' (duration: 155.868864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:33.962433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.589004ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:47:33.962528Z","caller":"traceutil/trace.go:171","msg":"trace[736367890] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"143.152433ms","start":"2026-02-04T01:47:33.819359Z","end":"2026-02-04T01:47:33.962511Z","steps":["trace[736367890] 'process raft request' (duration: 42.412347ms)","trace[736367890] 'compare' (duration: 100.294729ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:37.72868Z","caller":"traceutil/trace.go:171","msg":"trace[180668976] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"224.248442ms","start":"2026-02-04T01:47:37.504418Z","end":"2026-02-04T01:47:37.728667Z","steps":["trace[180668976] 'process raft request' (duration: 223.949207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:38.129092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.946348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T01:47:38.129143Z","caller":"traceutil/trace.go:171","msg":"trace[361479951] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:526; }","duration":"295.037599ms","start":"2026-02-04T01:47:37.834092Z","end":"2026-02-04T01:47:38.12913Z","steps":["trace[361479951] 'range keys from in-memory index tree' (duration: 294.808366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:38.129233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.47648ms","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-04T01:47:38.129275Z","caller":"traceutil/trace.go:171","msg":"trace[713506363] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:526; }","duration":"158.548231ms","start":"2026-02-04T01:47:37.970713Z","end":"2026-02-04T01:47:38.129261Z","steps":["trace[713506363] 'range keys from in-memory index tree' (duration: 158.208697ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:39.006405Z","caller":"traceutil/trace.go:171","msg":"trace[1620885964] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"106.081645ms","start":"2026-02-04T01:47:38.900306Z","end":"2026-02-04T01:47:39.006387Z","steps":["trace[1620885964] 'process raft request' (duration: 105.939423ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:40.696345Z","caller":"traceutil/trace.go:171","msg":"trace[1353038730] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"105.205176ms","start":"2026-02-04T01:47:40.591123Z","end":"2026-02-04T01:47:40.696328Z","steps":["trace[1353038730] 'process raft request' (duration: 105.096705ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:44.511476Z","caller":"traceutil/trace.go:171","msg":"trace[2119598974] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"124.477918ms","start":"2026-02-04T01:47:44.386979Z","end":"2026-02-04T01:47:44.511457Z","steps":["trace[2119598974] 'process raft request' (duration: 103.373768ms)","trace[2119598974] 'compare' (duration: 20.846856ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:45.525144Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548108388409547,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T01:47:45.573369Z","caller":"traceutil/trace.go:171","msg":"trace[162964132] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"548.807591ms","start":"2026-02-04T01:47:45.024538Z","end":"2026-02-04T01:47:45.573346Z","steps":["trace[162964132] 'read index received' (duration: 548.649849ms)","trace[162964132] 'applied index is now lower than readState.Index' (duration: 156.272µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:45.573562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.998034ms","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-04T01:47:45.573594Z","caller":"traceutil/trace.go:171","msg":"trace[373555429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:565; }","duration":"549.071685ms","start":"2026-02-04T01:47:45.024511Z","end":"2026-02-04T01:47:45.573583Z","steps":["trace[373555429] 'agreement among raft nodes before linearized reading' (duration: 548.923903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:45.57363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:45.024498Z","time spent":"549.124455ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-04T01:47:45.573807Z","caller":"traceutil/trace.go:171","msg":"trace[1775939333] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"820.278686ms","start":"2026-02-04T01:47:44.753455Z","end":"2026-02-04T01:47:45.573734Z","steps":["trace[1775939333] 'process raft request' (duration: 819.745068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:45.57415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:44.753446Z","time spent":"820.487419ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:47:46.733073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.483646ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:47:46.733143Z","caller":"traceutil/trace.go:171","msg":"trace[925324939] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:586; }","duration":"1.015832703s","start":"2026-02-04T01:47:45.717299Z","end":"2026-02-04T01:47:46.733132Z","steps":["trace[925324939] 'read index received' (duration: 223.149894ms)","trace[925324939] 'applied index is now lower than readState.Index' (duration: 792.682009ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:46.733298Z","caller":"traceutil/trace.go:171","msg":"trace[1760989582] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.153770199s","start":"2026-02-04T01:47:45.579517Z","end":"2026-02-04T01:47:46.733287Z","steps":["trace[1760989582] 'process raft request' (duration: 361.015539ms)","trace[1760989582] 'compare' (duration: 792.277304ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:46.733348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:45.5795Z","time spent":"1.15382849s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:47:46.733467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016182308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:47:46.733489Z","caller":"traceutil/trace.go:171","msg":"trace[1154804215] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"1.016271179s","start":"2026-02-04T01:47:45.717212Z","end":"2026-02-04T01:47:46.733483Z","steps":["trace[1154804215] 'agreement among raft nodes before linearized reading' (duration: 1.016235729s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:46.733507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:45.71718Z","time spent":"1.0163236s","remote":"127.0.0.1:57164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:47:46.733671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.995407ms","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-04T01:47:46.733694Z","caller":"traceutil/trace.go:171","msg":"trace[1565509710] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:566; }","duration":"457.018327ms","start":"2026-02-04T01:47:46.27667Z","end":"2026-02-04T01:47:46.733688Z","steps":["trace[1565509710] 'agreement among raft nodes before linearized reading' (duration: 456.919006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:46.733711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:46.276627Z","time spent":"457.080658ms","remote":"127.0.0.1:57470","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-04T01:47:46.733862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.476153ms","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-04T01:47:46.733886Z","caller":"traceutil/trace.go:171","msg":"trace[2105752547] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:566; }","duration":"423.522914ms","start":"2026-02-04T01:47:46.310358Z","end":"2026-02-04T01:47:46.73388Z","steps":["trace[2105752547] 'agreement among raft nodes before linearized reading' (duration: 423.463213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:46.733907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:46.310345Z","time spent":"423.558004ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T01:47:48.083837Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548108388409569,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T01:47:48.409676Z","caller":"traceutil/trace.go:171","msg":"trace[2061571424] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"826.421365ms","start":"2026-02-04T01:47:47.583239Z","end":"2026-02-04T01:47:48.409661Z","steps":["trace[2061571424] 'read index received' (duration: 826.290753ms)","trace[2061571424] 'applied index is now lower than readState.Index' (duration: 130.142µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:48.40982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.562287ms","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-04T01:47:48.409842Z","caller":"traceutil/trace.go:171","msg":"trace[844979848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:573; }","duration":"826.625149ms","start":"2026-02-04T01:47:47.583209Z","end":"2026-02-04T01:47:48.409834Z","steps":["trace[844979848] 'agreement among raft nodes before linearized reading' (duration: 826.514197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:48.409864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:47.583194Z","time spent":"826.663899ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-04T01:47:48.410147Z","caller":"traceutil/trace.go:171","msg":"trace[70196461] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"959.498144ms","start":"2026-02-04T01:47:47.450639Z","end":"2026-02-04T01:47:48.410137Z","steps":["trace[70196461] 'process raft request' (duration: 958.944706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:48.410222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:47.450627Z","time spent":"959.560256ms","remote":"127.0.0.1:57388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:47:50.08719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.214050685s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:47:50.087255Z","caller":"traceutil/trace.go:171","msg":"trace[2100289458] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"1.372318768s","start":"2026-02-04T01:47:48.714924Z","end":"2026-02-04T01:47:50.087243Z","steps":["trace[2100289458] 'read index received' (duration: 158.11604ms)","trace[2100289458] 'applied index is now lower than readState.Index' (duration: 1.214202018s)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:50.087401Z","caller":"traceutil/trace.go:171","msg":"trace[1104566906] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"1.668856269s","start":"2026-02-04T01:47:48.418537Z","end":"2026-02-04T01:47:50.087393Z","steps":["trace[1104566906] 'process raft request' (duration: 454.54678ms)","trace[1104566906] 'compare' (duration: 1.213532118s)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:50.087439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:48.418514Z","time spent":"1.66890549s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:47:50.087549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.372632242s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:47:50.087566Z","caller":"traceutil/trace.go:171","msg":"trace[1678145037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"1.372648462s","start":"2026-02-04T01:47:48.714912Z","end":"2026-02-04T01:47:50.08756Z","steps":["trace[1678145037] 'agreement among raft nodes before linearized reading' (duration: 1.372619142s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:50.087581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:48.714851Z","time spent":"1.372727493s","remote":"127.0.0.1:57164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:47:50.087728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.348529621s","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-04T01:47:50.087745Z","caller":"traceutil/trace.go:171","msg":"trace[907269694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:574; }","duration":"1.348569373s","start":"2026-02-04T01:47:48.739171Z","end":"2026-02-04T01:47:50.08774Z","steps":["trace[907269694] 'agreement among raft nodes before linearized reading' (duration: 1.348503912s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:50.087761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:48.739158Z","time spent":"1.348599843s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T01:47:50.087892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.339596986s","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-04T01:47:50.087908Z","caller":"traceutil/trace.go:171","msg":"trace[1810959924] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"1.339619546s","start":"2026-02-04T01:47:48.748284Z","end":"2026-02-04T01:47:50.087903Z","steps":["trace[1810959924] 'agreement among raft nodes before linearized reading' (duration: 1.339573235s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:50.087926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:48.748281Z","time spent":"1.339639297s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T01:47:50.088084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.339789609s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T01:47:50.088129Z","caller":"traceutil/trace.go:171","msg":"trace[349136728] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:574; }","duration":"1.33987511s","start":"2026-02-04T01:47:48.748242Z","end":"2026-02-04T01:47:50.088117Z","steps":["trace[349136728] 'agreement among raft nodes before linearized reading' (duration: 1.339715658s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:50.088155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:48.748229Z","time spent":"1.33992079s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T01:47:52.217935Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548108388409576,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:47:52.718731Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548108388409576,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:47:53.219488Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548108388409576,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:47:53.717694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00017192s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-04T01:47:53.717769Z","caller":"traceutil/trace.go:171","msg":"trace[1953810273] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000304423s","start":"2026-02-04T01:47:51.717449Z","end":"2026-02-04T01:47:53.717754Z","steps":["trace[1953810273] 'agreement among raft nodes before linearized reading' (duration: 2.000203001s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:53.717808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:51.717431Z","time spent":"2.000368153s","remote":"127.0.0.1:57164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/04 01:47:53 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-04T01:47:53.720076Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548108388409576,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T01:47:54.181437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.700712055s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:47:54.181508Z","caller":"traceutil/trace.go:171","msg":"trace[1098693006] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"4.083144731s","start":"2026-02-04T01:47:50.098351Z","end":"2026-02-04T01:47:54.181496Z","steps":["trace[1098693006] 'process raft request' (duration: 382.320645ms)","trace[1098693006] 'compare' (duration: 3.700324079s)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:54.181541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:50.098341Z","time spent":"4.083184792s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:47:54.184335Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.703667036s","expected-duration":"1s"} {"level":"info","ts":"2026-02-04T01:47:54.185118Z","caller":"traceutil/trace.go:171","msg":"trace[1258310305] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"4.08659852s","start":"2026-02-04T01:47:50.098502Z","end":"2026-02-04T01:47:54.1851Z","steps":["trace[1258310305] 'process raft request' (duration: 4.086296176s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:54.18522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:50.098495Z","time spent":"4.086693391s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:47:54.185413Z","caller":"traceutil/trace.go:171","msg":"trace[165389165] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"3.714269866s","start":"2026-02-04T01:47:50.471089Z","end":"2026-02-04T01:47:54.185358Z","steps":["trace[165389165] 'process raft request' (duration: 3.713933941s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:54.185736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:50.471069Z","time spent":"3.714443548s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:47:54.185747Z","caller":"traceutil/trace.go:171","msg":"trace[1589137710] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"4.086513249s","start":"2026-02-04T01:47:50.099227Z","end":"2026-02-04T01:47:54.18574Z","steps":["trace[1589137710] 'process raft request' (duration: 4.085727788s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:54.186786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:50.099221Z","time spent":"4.087544093s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:47:54.39803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.591433ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-04T01:47:54.398126Z","caller":"traceutil/trace.go:171","msg":"trace[961882514] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:597; }","duration":"2.680617106s","start":"2026-02-04T01:47:51.717489Z","end":"2026-02-04T01:47:54.398106Z","steps":["trace[961882514] 'read index received' (duration: 2.467269664s)","trace[961882514] 'applied index is now lower than readState.Index' (duration: 213.345952ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:54.398298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.252762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:47:54.398329Z","caller":"traceutil/trace.go:171","msg":"trace[1511958595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"680.323884ms","start":"2026-02-04T01:47:53.717993Z","end":"2026-02-04T01:47:54.398317Z","steps":["trace[1511958595] 'agreement among raft nodes before linearized reading' (duration: 680.260763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:54.398365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:53.717975Z","time spent":"680.382564ms","remote":"127.0.0.1:57180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:47:54.398362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.304304959s","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-04T01:47:54.398408Z","caller":"traceutil/trace.go:171","msg":"trace[1874035077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"2.30436042s","start":"2026-02-04T01:47:52.094036Z","end":"2026-02-04T01:47:54.398396Z","steps":["trace[1874035077] 'agreement among raft nodes before linearized reading' (duration: 2.304218998s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:54.398431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:52.093982Z","time spent":"2.30444503s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-04T01:47:55.683709Z","caller":"traceutil/trace.go:171","msg":"trace[1068131304] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"217.601997ms","start":"2026-02-04T01:47:55.46609Z","end":"2026-02-04T01:47:55.683692Z","steps":["trace[1068131304] 'process raft request' (duration: 217.484015ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:55.691452Z","caller":"traceutil/trace.go:171","msg":"trace[1053209088] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"217.397554ms","start":"2026-02-04T01:47:55.474043Z","end":"2026-02-04T01:47:55.69144Z","steps":["trace[1053209088] 'process raft request' (duration: 217.328284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:56.380984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.07798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:47:56.381131Z","caller":"traceutil/trace.go:171","msg":"trace[2143741785] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"164.039066ms","start":"2026-02-04T01:47:56.217049Z","end":"2026-02-04T01:47:56.381088Z","steps":["trace[2143741785] 'process raft request' (duration: 54.761383ms)","trace[2143741785] 'compare' (duration: 108.903118ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:56.540454Z","caller":"traceutil/trace.go:171","msg":"trace[1131615875] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"103.187168ms","start":"2026-02-04T01:47:56.437247Z","end":"2026-02-04T01:47:56.540434Z","steps":["trace[1131615875] 'process raft request' (duration: 61.78635ms)","trace[1131615875] 'compare' (duration: 40.916901ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:57.923819Z","caller":"traceutil/trace.go:171","msg":"trace[1883716808] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"207.285743ms","start":"2026-02-04T01:47:57.716511Z","end":"2026-02-04T01:47:57.923796Z","steps":["trace[1883716808] 'read index received' (duration: 207.10265ms)","trace[1883716808] 'applied index is now lower than readState.Index' (duration: 182.213µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:57.923945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.421615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:47:57.923976Z","caller":"traceutil/trace.go:171","msg":"trace[745831515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"207.503816ms","start":"2026-02-04T01:47:57.716462Z","end":"2026-02-04T01:47:57.923966Z","steps":["trace[745831515] 'agreement among raft nodes before linearized reading' (duration: 207.439185ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:57.924172Z","caller":"traceutil/trace.go:171","msg":"trace[11730824] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"430.173093ms","start":"2026-02-04T01:47:57.493983Z","end":"2026-02-04T01:47:57.924156Z","steps":["trace[11730824] 'process raft request' (duration: 429.687685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:57.924269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:57.493965Z","time spent":"430.243695ms","remote":"127.0.0.1:57388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17400,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:47:58.683108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.602288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:47:58.683409Z","caller":"traceutil/trace.go:171","msg":"trace[477381559] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"295.472863ms","start":"2026-02-04T01:47:58.387728Z","end":"2026-02-04T01:47:58.683201Z","steps":["trace[477381559] 'read index received' (duration: 117.72006ms)","trace[477381559] 'applied index is now lower than readState.Index' (duration: 177.751433ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:47:58.683701Z","caller":"traceutil/trace.go:171","msg":"trace[1553266966] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"415.069001ms","start":"2026-02-04T01:47:58.268612Z","end":"2026-02-04T01:47:58.683681Z","steps":["trace[1553266966] 'process raft request' (duration: 236.7962ms)","trace[1553266966] 'compare' (duration: 177.387529ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:58.684012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:47:58.268587Z","time spent":"415.162595ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:47:58.68444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.465138ms","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-04T01:47:58.684685Z","caller":"traceutil/trace.go:171","msg":"trace[1824857095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:598; }","duration":"139.742748ms","start":"2026-02-04T01:47:58.544928Z","end":"2026-02-04T01:47:58.684671Z","steps":["trace[1824857095] 'agreement among raft nodes before linearized reading' (duration: 138.798703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:47:58.685133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.397474ms","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-04T01:47:58.685192Z","caller":"traceutil/trace.go:171","msg":"trace[115420214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:598; }","duration":"297.490587ms","start":"2026-02-04T01:47:58.387688Z","end":"2026-02-04T01:47:58.685178Z","steps":["trace[115420214] 'agreement among raft nodes before linearized reading' (duration: 297.29233ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:58.866957Z","caller":"traceutil/trace.go:171","msg":"trace[2144988350] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"149.512547ms","start":"2026-02-04T01:47:58.717424Z","end":"2026-02-04T01:47:58.866936Z","steps":["trace[2144988350] 'read index received' (duration: 21.825311ms)","trace[2144988350] 'applied index is now lower than readState.Index' (duration: 127.686126ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:47:58.867175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.742326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:47:58.867247Z","caller":"traceutil/trace.go:171","msg":"trace[1681099686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"149.86529ms","start":"2026-02-04T01:47:58.71737Z","end":"2026-02-04T01:47:58.867235Z","steps":["trace[1681099686] 'agreement among raft nodes before linearized reading' (duration: 149.737225ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:47:58.867527Z","caller":"traceutil/trace.go:171","msg":"trace[504354220] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"178.514611ms","start":"2026-02-04T01:47:58.688997Z","end":"2026-02-04T01:47:58.867512Z","steps":["trace[504354220] 'process raft request' (duration: 176.254798ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:00.526364Z","caller":"traceutil/trace.go:171","msg":"trace[1095075526] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"256.676904ms","start":"2026-02-04T01:48:00.269673Z","end":"2026-02-04T01:48:00.52635Z","steps":["trace[1095075526] 'read index received' (duration: 256.55326ms)","trace[1095075526] 'applied index is now lower than readState.Index' (duration: 123.054µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:00.526505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.81544ms","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-04T01:48:00.526523Z","caller":"traceutil/trace.go:171","msg":"trace[756976547] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:601; }","duration":"256.872932ms","start":"2026-02-04T01:48:00.269643Z","end":"2026-02-04T01:48:00.526516Z","steps":["trace[756976547] 'agreement among raft nodes before linearized reading' (duration: 256.767839ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:00.526691Z","caller":"traceutil/trace.go:171","msg":"trace[1780341866] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"270.336188ms","start":"2026-02-04T01:48:00.25635Z","end":"2026-02-04T01:48:00.526686Z","steps":["trace[1780341866] 'process raft request' (duration: 269.916512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:00.799696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.50609ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:48:00.799758Z","caller":"traceutil/trace.go:171","msg":"trace[1889558773] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"111.975597ms","start":"2026-02-04T01:48:00.687772Z","end":"2026-02-04T01:48:00.799748Z","steps":["trace[1889558773] 'read index received' (duration: 39.531µs)","trace[1889558773] 'applied index is now lower than readState.Index' (duration: 111.935356ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:48:00.799878Z","caller":"traceutil/trace.go:171","msg":"trace[37970646] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"268.44442ms","start":"2026-02-04T01:48:00.531426Z","end":"2026-02-04T01:48:00.79987Z","steps":["trace[37970646] 'process raft request' (duration: 77.712973ms)","trace[37970646] 'compare' (duration: 190.441906ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:00.800017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.257048ms","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-04T01:48:00.800041Z","caller":"traceutil/trace.go:171","msg":"trace[1237129761] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:602; }","duration":"112.31202ms","start":"2026-02-04T01:48:00.687718Z","end":"2026-02-04T01:48:00.80003Z","steps":["trace[1237129761] 'agreement among raft nodes before linearized reading' (duration: 112.245027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:02.809083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.998106461s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-04T01:48:02.809161Z","caller":"traceutil/trace.go:171","msg":"trace[890763656] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.998212294s","start":"2026-02-04T01:48:00.81093Z","end":"2026-02-04T01:48:02.809143Z","steps":["trace[890763656] 'agreement among raft nodes before linearized reading' (duration: 1.998117771s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:02.809208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:00.810923Z","time spent":"1.998275617s","remote":"127.0.0.1:57180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/04 01:48:02 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-04T01:48:03.393041Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.385922332s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-04T01:48:03.445511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.438316403s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:48:03.447454Z","caller":"traceutil/trace.go:171","msg":"trace[1163434466] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"2.636677573s","start":"2026-02-04T01:48:00.810746Z","end":"2026-02-04T01:48:03.447423Z","steps":["trace[1163434466] 'process raft request' (duration: 196.37073ms)","trace[1163434466] 'compare' (duration: 2.437819986s)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:03.447601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:00.810731Z","time spent":"2.636797578s","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:48:03.448308Z","caller":"traceutil/trace.go:171","msg":"trace[348372578] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"2.637344447s","start":"2026-02-04T01:48:00.81095Z","end":"2026-02-04T01:48:03.448294Z","steps":["trace[348372578] 'read index received' (duration: 196.179333ms)","trace[348372578] 'applied index is now lower than readState.Index' (duration: 2.441163294s)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:48:03.448801Z","caller":"traceutil/trace.go:171","msg":"trace[1945497675] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"2.636225946s","start":"2026-02-04T01:48:00.812555Z","end":"2026-02-04T01:48:03.448781Z","steps":["trace[1945497675] 'process raft request' (duration: 2.633084546s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:03.448898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:00.812549Z","time spent":"2.636294788s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:48:03.449078Z","caller":"traceutil/trace.go:171","msg":"trace[1236714000] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"2.635117556s","start":"2026-02-04T01:48:00.813953Z","end":"2026-02-04T01:48:03.449071Z","steps":["trace[1236714000] 'process raft request' (duration: 2.631770899s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:03.449115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:00.813947Z","time spent":"2.635144397s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:48:03.551027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.258077ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-04T01:48:03.551095Z","caller":"traceutil/trace.go:171","msg":"trace[1095991332] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"102.738699ms","start":"2026-02-04T01:48:03.448343Z","end":"2026-02-04T01:48:03.551082Z","steps":["trace[1095991332] 'read index received' (duration: 206.977µs)","trace[1095991332] 'applied index is now lower than readState.Index' (duration: 102.530852ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:03.551387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.570021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:48:03.551409Z","caller":"traceutil/trace.go:171","msg":"trace[1773883773] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:605; }","duration":"160.635933ms","start":"2026-02-04T01:48:03.390768Z","end":"2026-02-04T01:48:03.551404Z","steps":["trace[1773883773] 'agreement among raft nodes before linearized reading' (duration: 160.566161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:03.551514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.961502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:48:03.551533Z","caller":"traceutil/trace.go:171","msg":"trace[1882104620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"735.990242ms","start":"2026-02-04T01:48:02.815539Z","end":"2026-02-04T01:48:03.551529Z","steps":["trace[1882104620] 'agreement among raft nodes before linearized reading' (duration: 735.962881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:03.551545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:02.815536Z","time spent":"736.006113ms","remote":"127.0.0.1:57164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:48:03.551648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.172338ms","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-04T01:48:03.551666Z","caller":"traceutil/trace.go:171","msg":"trace[113877700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"736.220661ms","start":"2026-02-04T01:48:02.815441Z","end":"2026-02-04T01:48:03.551662Z","steps":["trace[113877700] 'agreement among raft nodes before linearized reading' (duration: 736.160359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:03.551677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:02.815429Z","time spent":"736.245851ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T01:48:03.551743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.441256203s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:48:03.551764Z","caller":"traceutil/trace.go:171","msg":"trace[1260454707] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:605; }","duration":"1.441309344s","start":"2026-02-04T01:48:02.110449Z","end":"2026-02-04T01:48:03.551759Z","steps":["trace[1260454707] 'agreement among raft nodes before linearized reading' (duration: 1.441277753s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:03.551777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:02.110431Z","time spent":"1.441342496s","remote":"127.0.0.1:57608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-04T01:48:03.551865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.740480285s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-v5qk5\" ","response":"range_response_count:1 size:17415"} {"level":"info","ts":"2026-02-04T01:48:03.551882Z","caller":"traceutil/trace.go:171","msg":"trace[1022822526] range","detail":"{range_begin:/registry/pods/kube-system/cilium-v5qk5; range_end:; response_count:1; response_revision:605; }","duration":"1.740531057s","start":"2026-02-04T01:48:01.811347Z","end":"2026-02-04T01:48:03.551879Z","steps":["trace[1022822526] 'agreement among raft nodes before linearized reading' (duration: 1.740484155s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:03.551894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:01.81133Z","time spent":"1.740561578s","remote":"127.0.0.1:57388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17439,"request content":"key:\"/registry/pods/kube-system/cilium-v5qk5\" "} {"level":"warn","ts":"2026-02-04T01:48:03.551981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.68004446s","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-04T01:48:03.551998Z","caller":"traceutil/trace.go:171","msg":"trace[205100211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"2.680091192s","start":"2026-02-04T01:48:00.871903Z","end":"2026-02-04T01:48:03.551995Z","steps":["trace[205100211] 'agreement among raft nodes before linearized reading' (duration: 2.68005131s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:03.552009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:00.871884Z","time spent":"2.680122333s","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-04T01:48:03.897964Z","caller":"traceutil/trace.go:171","msg":"trace[414263284] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"333.448994ms","start":"2026-02-04T01:48:03.564495Z","end":"2026-02-04T01:48:03.897944Z","steps":["trace[414263284] 'process raft request' (duration: 333.351201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:03.898062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:03.564484Z","time spent":"333.527606ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:48:04.033698Z","caller":"traceutil/trace.go:171","msg":"trace[614945103] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"467.392221ms","start":"2026-02-04T01:48:03.566275Z","end":"2026-02-04T01:48:04.033667Z","steps":["trace[614945103] 'process raft request' (duration: 462.580633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:04.033881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:03.566268Z","time spent":"467.508025ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:48:04.035102Z","caller":"traceutil/trace.go:171","msg":"trace[1653315020] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"465.246665ms","start":"2026-02-04T01:48:03.569845Z","end":"2026-02-04T01:48:04.035091Z","steps":["trace[1653315020] 'process raft request' (duration: 463.534926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:04.035245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:03.569838Z","time spent":"465.35519ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:48:04.035547Z","caller":"traceutil/trace.go:171","msg":"trace[1756044104] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"462.077864ms","start":"2026-02-04T01:48:03.573459Z","end":"2026-02-04T01:48:04.035537Z","steps":["trace[1756044104] 'process raft request' (duration: 461.092949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:04.035959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:03.573452Z","time spent":"462.474487ms","remote":"127.0.0.1:57388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17562,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:48:04.078498Z","caller":"traceutil/trace.go:171","msg":"trace[1226364424] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"176.395655ms","start":"2026-02-04T01:48:03.902073Z","end":"2026-02-04T01:48:04.078469Z","steps":["trace[1226364424] 'process raft request' (duration: 176.25744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:04.379149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.729243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:48:04.379269Z","caller":"traceutil/trace.go:171","msg":"trace[1062091472] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"288.677714ms","start":"2026-02-04T01:48:04.090565Z","end":"2026-02-04T01:48:04.379242Z","steps":["trace[1062091472] 'process raft request' (duration: 66.775985ms)","trace[1062091472] 'compare' (duration: 221.587118ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:48:04.382747Z","caller":"traceutil/trace.go:171","msg":"trace[1285314034] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"285.069539ms","start":"2026-02-04T01:48:04.097665Z","end":"2026-02-04T01:48:04.382735Z","steps":["trace[1285314034] 'process raft request' (duration: 284.978275ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:04.745275Z","caller":"traceutil/trace.go:171","msg":"trace[277920646] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"104.744738ms","start":"2026-02-04T01:48:04.640501Z","end":"2026-02-04T01:48:04.745246Z","steps":["trace[277920646] 'process raft request' (duration: 98.263592ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:05.096672Z","caller":"traceutil/trace.go:171","msg":"trace[1695726698] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"159.757347ms","start":"2026-02-04T01:48:04.936898Z","end":"2026-02-04T01:48:05.096655Z","steps":["trace[1695726698] 'read index received' (duration: 152.642332ms)","trace[1695726698] 'applied index is now lower than readState.Index' (duration: 7.114305ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:48:05.097174Z","caller":"traceutil/trace.go:171","msg":"trace[1438777335] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"164.039506ms","start":"2026-02-04T01:48:04.933125Z","end":"2026-02-04T01:48:05.097164Z","steps":["trace[1438777335] 'process raft request' (duration: 156.405272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:05.097402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.490793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:48:05.097435Z","caller":"traceutil/trace.go:171","msg":"trace[1448009730] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:621; }","duration":"160.572246ms","start":"2026-02-04T01:48:04.936853Z","end":"2026-02-04T01:48:05.097426Z","steps":["trace[1448009730] 'agreement among raft nodes before linearized reading' (duration: 160.512154ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:05.134709Z","caller":"traceutil/trace.go:171","msg":"trace[1721323117] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"148.216114ms","start":"2026-02-04T01:48:04.986473Z","end":"2026-02-04T01:48:05.134689Z","steps":["trace[1721323117] 'process raft request' (duration: 148.164412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:05.135351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.709607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:48:05.135436Z","caller":"traceutil/trace.go:171","msg":"trace[1538644199] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:623; }","duration":"172.844911ms","start":"2026-02-04T01:48:04.962546Z","end":"2026-02-04T01:48:05.135391Z","steps":["trace[1538644199] 'agreement among raft nodes before linearized reading' (duration: 172.563152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:05.136332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.498322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:48:05.136416Z","caller":"traceutil/trace.go:171","msg":"trace[1804879392] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:623; }","duration":"185.595065ms","start":"2026-02-04T01:48:04.950808Z","end":"2026-02-04T01:48:05.136403Z","steps":["trace[1804879392] 'agreement among raft nodes before linearized reading' (duration: 185.379148ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:05.136386Z","caller":"traceutil/trace.go:171","msg":"trace[1194203852] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"171.581598ms","start":"2026-02-04T01:48:04.96475Z","end":"2026-02-04T01:48:05.136332Z","steps":["trace[1194203852] 'process raft request' (duration: 169.794686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:05.137097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.326361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:48:05.137133Z","caller":"traceutil/trace.go:171","msg":"trace[674286432] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:623; }","duration":"186.389543ms","start":"2026-02-04T01:48:04.950731Z","end":"2026-02-04T01:48:05.13712Z","steps":["trace[674286432] 'agreement among raft nodes before linearized reading' (duration: 186.2989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:05.137237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.434545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:48:05.137258Z","caller":"traceutil/trace.go:171","msg":"trace[1867014684] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:623; }","duration":"186.461466ms","start":"2026-02-04T01:48:04.95079Z","end":"2026-02-04T01:48:05.137251Z","steps":["trace[1867014684] 'agreement among raft nodes before linearized reading' (duration: 186.426004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:05.137344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.557499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:48:05.137362Z","caller":"traceutil/trace.go:171","msg":"trace[2130525156] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:623; }","duration":"186.5848ms","start":"2026-02-04T01:48:04.95077Z","end":"2026-02-04T01:48:05.137355Z","steps":["trace[2130525156] 'agreement among raft nodes before linearized reading' (duration: 186.556709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:05.137633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.718658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:48:05.13766Z","caller":"traceutil/trace.go:171","msg":"trace[1969850998] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:623; }","duration":"197.75652ms","start":"2026-02-04T01:48:04.939896Z","end":"2026-02-04T01:48:05.137652Z","steps":["trace[1969850998] 'agreement among raft nodes before linearized reading' (duration: 197.716528ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:05.396674Z","caller":"traceutil/trace.go:171","msg":"trace[1345156497] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"249.809937ms","start":"2026-02-04T01:48:05.146842Z","end":"2026-02-04T01:48:05.396652Z","steps":["trace[1345156497] 'process raft request' (duration: 249.747495ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:05.396934Z","caller":"traceutil/trace.go:171","msg":"trace[1994289586] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"251.663151ms","start":"2026-02-04T01:48:05.145258Z","end":"2026-02-04T01:48:05.396921Z","steps":["trace[1994289586] 'process raft request' (duration: 183.700904ms)","trace[1994289586] 'compare' (duration: 67.209141ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:48:05.405013Z","caller":"traceutil/trace.go:171","msg":"trace[825790072] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"206.258473ms","start":"2026-02-04T01:48:05.198739Z","end":"2026-02-04T01:48:05.404998Z","steps":["trace[825790072] 'process raft request' (duration: 205.797487ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:05.405221Z","caller":"traceutil/trace.go:171","msg":"trace[391939291] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"204.459441ms","start":"2026-02-04T01:48:05.200738Z","end":"2026-02-04T01:48:05.405197Z","steps":["trace[391939291] 'process raft request' (duration: 203.865571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:05.681021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.073936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:48:05.681287Z","caller":"traceutil/trace.go:171","msg":"trace[2099849152] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:654; }","duration":"218.062781ms","start":"2026-02-04T01:48:05.463208Z","end":"2026-02-04T01:48:05.681271Z","steps":["trace[2099849152] 'read index received' (duration: 33.880451ms)","trace[2099849152] 'applied index is now lower than readState.Index' (duration: 184.18143ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:48:05.681377Z","caller":"traceutil/trace.go:171","msg":"trace[1137913328] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"274.947214ms","start":"2026-02-04T01:48:05.406419Z","end":"2026-02-04T01:48:05.681366Z","steps":["trace[1137913328] 'process raft request' (duration: 90.447803ms)","trace[1137913328] 'compare' (duration: 183.933042ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:05.681765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.554588ms","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-04T01:48:05.681797Z","caller":"traceutil/trace.go:171","msg":"trace[707250880] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"218.60966ms","start":"2026-02-04T01:48:05.463177Z","end":"2026-02-04T01:48:05.681787Z","steps":["trace[707250880] 'agreement among raft nodes before linearized reading' (duration: 218.464915ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:05.681895Z","caller":"traceutil/trace.go:171","msg":"trace[1389698690] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"262.846056ms","start":"2026-02-04T01:48:05.419011Z","end":"2026-02-04T01:48:05.681857Z","steps":["trace[1389698690] 'process raft request' (duration: 262.107351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:05.682019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.00211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-04T01:48:05.68205Z","caller":"traceutil/trace.go:171","msg":"trace[1346135624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"214.067632ms","start":"2026-02-04T01:48:05.467973Z","end":"2026-02-04T01:48:05.682041Z","steps":["trace[1346135624] 'agreement among raft nodes before linearized reading' (duration: 213.98366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:05.68262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.15518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:48:05.682673Z","caller":"traceutil/trace.go:171","msg":"trace[200237155] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:631; }","duration":"160.270485ms","start":"2026-02-04T01:48:05.522385Z","end":"2026-02-04T01:48:05.682655Z","steps":["trace[200237155] 'agreement among raft nodes before linearized reading' (duration: 160.168061ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:06.156077Z","caller":"traceutil/trace.go:171","msg":"trace[917636812] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"468.429624ms","start":"2026-02-04T01:48:05.687631Z","end":"2026-02-04T01:48:06.15606Z","steps":["trace[917636812] 'process raft request' (duration: 465.864136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:06.156162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:05.687594Z","time spent":"468.527768ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:48:06.160815Z","caller":"traceutil/trace.go:171","msg":"trace[1142711918] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"464.825618ms","start":"2026-02-04T01:48:05.695975Z","end":"2026-02-04T01:48:06.1608Z","steps":["trace[1142711918] 'process raft request' (duration: 464.709214ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:06.160898Z","caller":"traceutil/trace.go:171","msg":"trace[54559164] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:657; }","duration":"276.885468ms","start":"2026-02-04T01:48:05.884002Z","end":"2026-02-04T01:48:06.160888Z","steps":["trace[54559164] 'read index received' (duration: 269.505305ms)","trace[54559164] 'applied index is now lower than readState.Index' (duration: 7.379143ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:06.16091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:05.695962Z","time spent":"464.905051ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:48:06.161065Z","caller":"traceutil/trace.go:171","msg":"trace[1029107843] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"464.933502ms","start":"2026-02-04T01:48:05.696125Z","end":"2026-02-04T01:48:06.161058Z","steps":["trace[1029107843] 'process raft request' (duration: 464.631271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:06.161106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:05.696118Z","time spent":"464.964092ms","remote":"127.0.0.1:57642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:48:06.161157Z","caller":"traceutil/trace.go:171","msg":"trace[1125620426] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"439.719331ms","start":"2026-02-04T01:48:05.72143Z","end":"2026-02-04T01:48:06.161149Z","steps":["trace[1125620426] 'process raft request' (duration: 439.40151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:06.161189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:05.721401Z","time spent":"439.772933ms","remote":"127.0.0.1:57220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T01:48:06.161198Z","caller":"traceutil/trace.go:171","msg":"trace[900009216] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"239.945291ms","start":"2026-02-04T01:48:05.921244Z","end":"2026-02-04T01:48:06.161189Z","steps":["trace[900009216] 'process raft request' (duration: 239.60772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:06.161357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.352074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-v5qk5\" ","response":"range_response_count:1 size:17918"} {"level":"info","ts":"2026-02-04T01:48:06.16138Z","caller":"traceutil/trace.go:171","msg":"trace[2058950368] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"459.98923ms","start":"2026-02-04T01:48:05.701386Z","end":"2026-02-04T01:48:06.161375Z","steps":["trace[2058950368] 'process raft request' (duration: 459.426691ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:06.161381Z","caller":"traceutil/trace.go:171","msg":"trace[2085002034] range","detail":"{range_begin:/registry/pods/kube-system/cilium-v5qk5; range_end:; response_count:1; response_revision:639; }","duration":"277.415825ms","start":"2026-02-04T01:48:05.883959Z","end":"2026-02-04T01:48:06.161375Z","steps":["trace[2085002034] 'agreement among raft nodes before linearized reading' (duration: 277.312312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:06.161412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:05.701378Z","time spent":"460.015912ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:48:06.16136Z","caller":"traceutil/trace.go:171","msg":"trace[210788009] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"463.231023ms","start":"2026-02-04T01:48:05.698122Z","end":"2026-02-04T01:48:06.161353Z","steps":["trace[210788009] 'process raft request' (duration: 462.662043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:06.161532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:05.698114Z","time spent":"463.396958ms","remote":"127.0.0.1:57220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5571,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:48:06.166573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.239116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T01:48:06.166611Z","caller":"traceutil/trace.go:171","msg":"trace[60450962] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"129.309808ms","start":"2026-02-04T01:48:06.037292Z","end":"2026-02-04T01:48:06.166602Z","steps":["trace[60450962] 'agreement among raft nodes before linearized reading' (duration: 129.182353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:06.16687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.218584ms","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-04T01:48:06.166892Z","caller":"traceutil/trace.go:171","msg":"trace[1129585586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"124.260755ms","start":"2026-02-04T01:48:06.042624Z","end":"2026-02-04T01:48:06.166885Z","steps":["trace[1129585586] 'agreement among raft nodes before linearized reading' (duration: 124.207054ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:07.56824Z","caller":"traceutil/trace.go:171","msg":"trace[1151308227] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"109.526339ms","start":"2026-02-04T01:48:07.458695Z","end":"2026-02-04T01:48:07.568221Z","steps":["trace[1151308227] 'process raft request' (duration: 109.272431ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:07.77835Z","caller":"traceutil/trace.go:171","msg":"trace[1692179298] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"224.090791ms","start":"2026-02-04T01:48:07.55424Z","end":"2026-02-04T01:48:07.778331Z","steps":["trace[1692179298] 'process raft request' (duration: 159.154915ms)","trace[1692179298] 'compare' (duration: 64.121728ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:07.782189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.567003ms","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-04T01:48:07.78225Z","caller":"traceutil/trace.go:171","msg":"trace[1782306338] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:671; }","duration":"203.683537ms","start":"2026-02-04T01:48:07.578558Z","end":"2026-02-04T01:48:07.782242Z","steps":["trace[1782306338] 'agreement among raft nodes before linearized reading' (duration: 203.48903ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:07.782488Z","caller":"traceutil/trace.go:171","msg":"trace[1402873742] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:697; }","duration":"199.274247ms","start":"2026-02-04T01:48:07.578577Z","end":"2026-02-04T01:48:07.777851Z","steps":["trace[1402873742] 'read index received' (duration: 134.747106ms)","trace[1402873742] 'applied index is now lower than readState.Index' (duration: 64.526471ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:07.782996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.223158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:48:07.783067Z","caller":"traceutil/trace.go:171","msg":"trace[792379126] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:671; }","duration":"129.297851ms","start":"2026-02-04T01:48:07.653761Z","end":"2026-02-04T01:48:07.783059Z","steps":["trace[792379126] 'agreement among raft nodes before linearized reading' (duration: 128.826895ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:08.000629Z","caller":"traceutil/trace.go:171","msg":"trace[118693238] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"107.435249ms","start":"2026-02-04T01:48:07.893176Z","end":"2026-02-04T01:48:08.000611Z","steps":["trace[118693238] 'process raft request' (duration: 106.153095ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:14.486301Z","caller":"traceutil/trace.go:171","msg":"trace[1519677893] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"113.958386ms","start":"2026-02-04T01:48:14.37233Z","end":"2026-02-04T01:48:14.486288Z","steps":["trace[1519677893] 'read index received' (duration: 113.825132ms)","trace[1519677893] 'applied index is now lower than readState.Index' (duration: 132.704µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:14.486439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.09978ms","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-04T01:48:14.486458Z","caller":"traceutil/trace.go:171","msg":"trace[288013357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:743; }","duration":"114.145082ms","start":"2026-02-04T01:48:14.372305Z","end":"2026-02-04T01:48:14.486451Z","steps":["trace[288013357] 'agreement among raft nodes before linearized reading' (duration: 114.040169ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:14.486723Z","caller":"traceutil/trace.go:171","msg":"trace[586986672] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"123.765081ms","start":"2026-02-04T01:48:14.362951Z","end":"2026-02-04T01:48:14.486716Z","steps":["trace[586986672] 'process raft request' (duration: 123.263635ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:15.417059Z","caller":"traceutil/trace.go:171","msg":"trace[428476065] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"134.951826ms","start":"2026-02-04T01:48:15.282093Z","end":"2026-02-04T01:48:15.417045Z","steps":["trace[428476065] 'process raft request' (duration: 128.933614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:15.625357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.902866ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:48:15.625515Z","caller":"traceutil/trace.go:171","msg":"trace[1754478734] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:785; }","duration":"208.476175ms","start":"2026-02-04T01:48:15.417027Z","end":"2026-02-04T01:48:15.625503Z","steps":["trace[1754478734] 'read index received' (duration: 61.455106ms)","trace[1754478734] 'applied index is now lower than readState.Index' (duration: 147.020549ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:15.625607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.689573ms","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-04T01:48:15.625619Z","caller":"traceutil/trace.go:171","msg":"trace[320578501] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:759; }","duration":"208.728563ms","start":"2026-02-04T01:48:15.416887Z","end":"2026-02-04T01:48:15.625615Z","steps":["trace[320578501] 'agreement among raft nodes before linearized reading' (duration: 208.64461ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:15.625787Z","caller":"traceutil/trace.go:171","msg":"trace[1431366684] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"265.964405ms","start":"2026-02-04T01:48:15.359815Z","end":"2026-02-04T01:48:15.625779Z","steps":["trace[1431366684] 'process raft request' (duration: 118.580644ms)","trace[1431366684] 'compare' (duration: 146.72439ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:48:15.62586Z","caller":"traceutil/trace.go:171","msg":"trace[761535206] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"263.533648ms","start":"2026-02-04T01:48:15.362321Z","end":"2026-02-04T01:48:15.625854Z","steps":["trace[761535206] 'process raft request' (duration: 263.155295ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:15.626131Z","caller":"traceutil/trace.go:171","msg":"trace[1161198504] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"266.182382ms","start":"2026-02-04T01:48:15.359923Z","end":"2026-02-04T01:48:15.626105Z","steps":["trace[1161198504] 'process raft request' (duration: 265.4916ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:15.777486Z","caller":"traceutil/trace.go:171","msg":"trace[2051574961] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"350.00775ms","start":"2026-02-04T01:48:15.427432Z","end":"2026-02-04T01:48:15.777439Z","steps":["trace[2051574961] 'process raft request' (duration: 312.449625ms)","trace[2051574961] 'compare' (duration: 37.463292ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:15.777641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:15.427413Z","time spent":"350.138474ms","remote":"127.0.0.1:57388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4833,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:48:15.833261Z","caller":"traceutil/trace.go:171","msg":"trace[550109250] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"196.205585ms","start":"2026-02-04T01:48:15.637034Z","end":"2026-02-04T01:48:15.83324Z","steps":["trace[550109250] 'process raft request' (duration: 195.890385ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:15.833398Z","caller":"traceutil/trace.go:171","msg":"trace[1487246450] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:788; }","duration":"118.910705ms","start":"2026-02-04T01:48:15.714469Z","end":"2026-02-04T01:48:15.83338Z","steps":["trace[1487246450] 'read index received' (duration: 25.419819ms)","trace[1487246450] 'applied index is now lower than readState.Index' (duration: 93.490086ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:15.833477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.998397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:48:15.841104Z","caller":"traceutil/trace.go:171","msg":"trace[1873315362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:762; }","duration":"126.653861ms","start":"2026-02-04T01:48:15.714438Z","end":"2026-02-04T01:48:15.841092Z","steps":["trace[1873315362] 'agreement among raft nodes before linearized reading' (duration: 118.992637ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:15.833581Z","caller":"traceutil/trace.go:171","msg":"trace[920010726] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"196.236086ms","start":"2026-02-04T01:48:15.637339Z","end":"2026-02-04T01:48:15.833575Z","steps":["trace[920010726] 'process raft request' (duration: 195.812892ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:15.911957Z","caller":"traceutil/trace.go:171","msg":"trace[211870968] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"125.735372ms","start":"2026-02-04T01:48:15.786202Z","end":"2026-02-04T01:48:15.911938Z","steps":["trace[211870968] 'process raft request' (duration: 113.443081ms)","trace[211870968] 'compare' (duration: 11.030321ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:48:15.918922Z","caller":"traceutil/trace.go:171","msg":"trace[1322940464] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"132.124175ms","start":"2026-02-04T01:48:15.78679Z","end":"2026-02-04T01:48:15.918914Z","steps":["trace[1322940464] 'process raft request' (duration: 123.998796ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:15.919052Z","caller":"traceutil/trace.go:171","msg":"trace[1199340449] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"131.046901ms","start":"2026-02-04T01:48:15.788Z","end":"2026-02-04T01:48:15.919047Z","steps":["trace[1199340449] 'process raft request' (duration: 130.727981ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:16.268899Z","caller":"traceutil/trace.go:171","msg":"trace[2047672655] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:800; }","duration":"187.13938ms","start":"2026-02-04T01:48:16.081735Z","end":"2026-02-04T01:48:16.268874Z","steps":["trace[2047672655] 'read index received' (duration: 186.484949ms)","trace[2047672655] 'applied index is now lower than readState.Index' (duration: 653.251µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:16.269327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.593364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:48:16.269374Z","caller":"traceutil/trace.go:171","msg":"trace[1740787444] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:773; }","duration":"187.684797ms","start":"2026-02-04T01:48:16.081678Z","end":"2026-02-04T01:48:16.269362Z","steps":["trace[1740787444] 'agreement among raft nodes before linearized reading' (duration: 187.547703ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:16.269725Z","caller":"traceutil/trace.go:171","msg":"trace[1788608254] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"323.104938ms","start":"2026-02-04T01:48:15.946604Z","end":"2026-02-04T01:48:16.269709Z","steps":["trace[1788608254] 'process raft request' (duration: 321.687893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:16.269817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:15.946597Z","time spent":"323.159579ms","remote":"127.0.0.1:57476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:48:16.27005Z","caller":"traceutil/trace.go:171","msg":"trace[1409318895] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"321.61906ms","start":"2026-02-04T01:48:15.948418Z","end":"2026-02-04T01:48:16.270037Z","steps":["trace[1409318895] 'process raft request' (duration: 320.385581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:16.27014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:15.948411Z","time spent":"321.665522ms","remote":"127.0.0.1:57682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3830,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:48:16.428856Z","caller":"traceutil/trace.go:171","msg":"trace[951450691] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"152.850737ms","start":"2026-02-04T01:48:16.275989Z","end":"2026-02-04T01:48:16.428839Z","steps":["trace[951450691] 'process raft request' (duration: 109.295482ms)","trace[951450691] 'compare' (duration: 43.192714ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:48:16.428642Z","caller":"traceutil/trace.go:171","msg":"trace[444489855] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:802; }","duration":"122.155878ms","start":"2026-02-04T01:48:16.306468Z","end":"2026-02-04T01:48:16.428624Z","steps":["trace[444489855] 'read index received' (duration: 78.766957ms)","trace[444489855] 'applied index is now lower than readState.Index' (duration: 43.388251ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:16.429218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.735986ms","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-04T01:48:16.42924Z","caller":"traceutil/trace.go:171","msg":"trace[1074081848] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:774; }","duration":"122.796038ms","start":"2026-02-04T01:48:16.306437Z","end":"2026-02-04T01:48:16.429233Z","steps":["trace[1074081848] 'agreement among raft nodes before linearized reading' (duration: 122.695105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:16.433089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.577709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T01:48:16.433122Z","caller":"traceutil/trace.go:171","msg":"trace[69317016] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:774; }","duration":"114.64395ms","start":"2026-02-04T01:48:16.318471Z","end":"2026-02-04T01:48:16.433114Z","steps":["trace[69317016] 'agreement among raft nodes before linearized reading' (duration: 114.495296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:48:17.618857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.631238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T01:48:17.6193Z","caller":"traceutil/trace.go:171","msg":"trace[1890707156] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:810; }","duration":"138.908092ms","start":"2026-02-04T01:48:17.480308Z","end":"2026-02-04T01:48:17.619217Z","steps":["trace[1890707156] 'read index received' (duration: 205.366µs)","trace[1890707156] 'applied index is now lower than readState.Index' (duration: 138.700266ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:17.619629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.310494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T01:48:17.61975Z","caller":"traceutil/trace.go:171","msg":"trace[392041601] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:781; }","duration":"139.47635ms","start":"2026-02-04T01:48:17.480221Z","end":"2026-02-04T01:48:17.619697Z","steps":["trace[392041601] 'agreement among raft nodes before linearized reading' (duration: 139.249293ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:17.620172Z","caller":"traceutil/trace.go:171","msg":"trace[1485092096] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"439.73804ms","start":"2026-02-04T01:48:17.180419Z","end":"2026-02-04T01:48:17.620157Z","steps":["trace[1485092096] 'process raft request' (duration: 274.683358ms)","trace[1485092096] 'compare' (duration: 162.919625ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:48:17.620775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:48:17.180406Z","time spent":"440.267606ms","remote":"127.0.0.1:57206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:48:18.76788Z","caller":"traceutil/trace.go:171","msg":"trace[315840538] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"237.829565ms","start":"2026-02-04T01:48:18.530023Z","end":"2026-02-04T01:48:18.767853Z","steps":["trace[315840538] 'process raft request' (duration: 237.737542ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:18.787555Z","caller":"traceutil/trace.go:171","msg":"trace[280643778] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"257.101095ms","start":"2026-02-04T01:48:18.530438Z","end":"2026-02-04T01:48:18.787539Z","steps":["trace[280643778] 'process raft request' (duration: 256.719373ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:18.787649Z","caller":"traceutil/trace.go:171","msg":"trace[389663666] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"255.9676ms","start":"2026-02-04T01:48:18.531677Z","end":"2026-02-04T01:48:18.787645Z","steps":["trace[389663666] 'process raft request' (duration: 255.586048ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:48:20.250894Z","caller":"traceutil/trace.go:171","msg":"trace[1837063741] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"148.968862ms","start":"2026-02-04T01:48:20.101907Z","end":"2026-02-04T01:48:20.250876Z","steps":["trace[1837063741] 'process raft request' (duration: 130.59408ms)","trace[1837063741] 'compare' (duration: 18.30564ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:48:35.120032Z","caller":"traceutil/trace.go:171","msg":"trace[1601431743] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"185.979366ms","start":"2026-02-04T01:48:34.934034Z","end":"2026-02-04T01:48:35.120013Z","steps":["trace[1601431743] 'process raft request' (duration: 160.280617ms)","trace[1601431743] 'compare' (duration: 25.462192ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:49:10.72196Z","caller":"traceutil/trace.go:171","msg":"trace[700708681] transaction","detail":"{read_only:false; number_of_response:1; response_revision:958; }","duration":"148.422909ms","start":"2026-02-04T01:49:10.573526Z","end":"2026-02-04T01:49:10.721948Z","steps":["trace[700708681] 'process raft request' (duration: 147.787535ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:11.290168Z","caller":"traceutil/trace.go:171","msg":"trace[1623180387] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"157.950548ms","start":"2026-02-04T01:49:11.132187Z","end":"2026-02-04T01:49:11.290138Z","steps":["trace[1623180387] 'read index received' (duration: 152.542026ms)","trace[1623180387] 'applied index is now lower than readState.Index' (duration: 5.407662ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:49:11.290621Z","caller":"traceutil/trace.go:171","msg":"trace[1672828889] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"161.11831ms","start":"2026-02-04T01:49:11.129486Z","end":"2026-02-04T01:49:11.290605Z","steps":["trace[1672828889] 'process raft request' (duration: 155.271998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:11.292348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.146148ms","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-04T01:49:11.292527Z","caller":"traceutil/trace.go:171","msg":"trace[796782608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:961; }","duration":"160.22677ms","start":"2026-02-04T01:49:11.132161Z","end":"2026-02-04T01:49:11.292387Z","steps":["trace[796782608] 'agreement among raft nodes before linearized reading' (duration: 158.099112ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:16.484558Z","caller":"traceutil/trace.go:171","msg":"trace[128997236] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1033; }","duration":"113.370537ms","start":"2026-02-04T01:49:16.371163Z","end":"2026-02-04T01:49:16.484534Z","steps":["trace[128997236] 'read index received' (duration: 113.063281ms)","trace[128997236] 'applied index is now lower than readState.Index' (duration: 306.316µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:49:16.485092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.864088ms","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-04T01:49:16.48534Z","caller":"traceutil/trace.go:171","msg":"trace[759011991] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:990; }","duration":"114.184935ms","start":"2026-02-04T01:49:16.371116Z","end":"2026-02-04T01:49:16.485301Z","steps":["trace[759011991] 'agreement among raft nodes before linearized reading' (duration: 113.723045ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:16.485857Z","caller":"traceutil/trace.go:171","msg":"trace[245080771] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"163.592474ms","start":"2026-02-04T01:49:16.32225Z","end":"2026-02-04T01:49:16.485843Z","steps":["trace[245080771] 'process raft request' (duration: 162.101811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:23.217497Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548108388410598,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T01:49:23.233868Z","caller":"traceutil/trace.go:171","msg":"trace[698590197] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1058; }","duration":"516.606749ms","start":"2026-02-04T01:49:22.717236Z","end":"2026-02-04T01:49:23.233843Z","steps":["trace[698590197] 'read index received' (duration: 516.312853ms)","trace[698590197] 'applied index is now lower than readState.Index' (duration: 292.776µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:49:23.234288Z","caller":"traceutil/trace.go:171","msg":"trace[871671939] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"691.774652ms","start":"2026-02-04T01:49:22.542494Z","end":"2026-02-04T01:49:23.234268Z","steps":["trace[871671939] 'process raft request' (duration: 691.19403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:23.235124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:22.542477Z","time spent":"691.854755ms","remote":"127.0.0.1:57470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:49:23.235407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.163102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:49:23.235441Z","caller":"traceutil/trace.go:171","msg":"trace[735210950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1013; }","duration":"518.234314ms","start":"2026-02-04T01:49:22.717197Z","end":"2026-02-04T01:49:23.235431Z","steps":["trace[735210950] 'agreement among raft nodes before linearized reading' (duration: 518.178703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:23.23601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:49:22.717181Z","time spent":"518.816936ms","remote":"127.0.0.1:57164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T01:49:23.235787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.995814ms","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-04T01:49:23.237322Z","caller":"traceutil/trace.go:171","msg":"trace[782160032] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1013; }","duration":"204.580858ms","start":"2026-02-04T01:49:23.032728Z","end":"2026-02-04T01:49:23.237309Z","steps":["trace[782160032] 'agreement among raft nodes before linearized reading' (duration: 202.895822ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:25.366187Z","caller":"traceutil/trace.go:171","msg":"trace[1120797832] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"123.37978ms","start":"2026-02-04T01:49:25.242762Z","end":"2026-02-04T01:49:25.366141Z","steps":["trace[1120797832] 'process raft request' (duration: 123.079643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:37.803339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.298078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node-proxier\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-02-04T01:49:37.803427Z","caller":"traceutil/trace.go:171","msg":"trace[74507596] range","detail":"{range_begin:/registry/clusterrolebindings/system:node-proxier; range_end:; response_count:1; response_revision:1062; }","duration":"100.424111ms","start":"2026-02-04T01:49:37.702983Z","end":"2026-02-04T01:49:37.803408Z","steps":["trace[74507596] 'range keys from in-memory index tree' (duration: 100.214066ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:42.133966Z","caller":"traceutil/trace.go:171","msg":"trace[991472030] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"119.419506ms","start":"2026-02-04T01:49:42.014521Z","end":"2026-02-04T01:49:42.13394Z","steps":["trace[991472030] 'process raft request' (duration: 118.839235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:42.434677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.761474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-node-lease/\" range_end:\"/registry/persistentvolumeclaims/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:49:42.435016Z","caller":"traceutil/trace.go:171","msg":"trace[519307070] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-node-lease/; range_end:/registry/persistentvolumeclaims/kube-node-lease0; response_count:0; response_revision:1073; }","duration":"221.132571ms","start":"2026-02-04T01:49:42.213862Z","end":"2026-02-04T01:49:42.434995Z","steps":["trace[519307070] 'range keys from in-memory index tree' (duration: 220.721892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:42.435428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.965037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-node-lease/\" range_end:\"/registry/rolebindings/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:49:42.435754Z","caller":"traceutil/trace.go:171","msg":"trace[499564431] range","detail":"{range_begin:/registry/rolebindings/kube-node-lease/; range_end:/registry/rolebindings/kube-node-lease0; response_count:0; response_revision:1073; }","duration":"201.309694ms","start":"2026-02-04T01:49:42.234426Z","end":"2026-02-04T01:49:42.435736Z","steps":["trace[499564431] 'range keys from in-memory index tree' (duration: 200.904776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:49:42.492724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.707053ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-04T01:49:49.730929Z","caller":"traceutil/trace.go:171","msg":"trace[1318104094] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1142; }","duration":"116.628353ms","start":"2026-02-04T01:49:49.61428Z","end":"2026-02-04T01:49:49.730909Z","steps":["trace[1318104094] 'read index received' (duration: 116.442189ms)","trace[1318104094] 'applied index is now lower than readState.Index' (duration: 185.164µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:49:49.73135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.038181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-4oet7y\" ","response":"range_response_count:1 size:692"} {"level":"info","ts":"2026-02-04T01:49:49.731431Z","caller":"traceutil/trace.go:171","msg":"trace[612609120] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-4oet7y; range_end:; response_count:1; response_revision:1092; }","duration":"117.144063ms","start":"2026-02-04T01:49:49.614266Z","end":"2026-02-04T01:49:49.73141Z","steps":["trace[612609120] 'agreement among raft nodes before linearized reading' (duration: 116.986539ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:49:49.732192Z","caller":"traceutil/trace.go:171","msg":"trace[99522721] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"120.319415ms","start":"2026-02-04T01:49:49.611858Z","end":"2026-02-04T01:49:49.732178Z","steps":["trace[99522721] 'process raft request' (duration: 118.919547ms)"],"step_count":1}