{"level":"warn","ts":"2026-05-05T12:42:00.595162Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T12:42:00.59524Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49: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-05-05T12:42:00.595307Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T12:42:00.595318Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-05-05T12:42:00.595336Z","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-05-05T12:42:00.595954Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-05-05T12:42:00.596028Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49: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-05-05T12:42:00.59907Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.709682ms"} {"level":"info","ts":"2026-05-05T12:42:00.640552Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-05-05T12:42:00.640723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T12:42:00.640763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-05-05T12:42:00.640804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T12:42:00.64085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-05-05T12:42:00.640938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-05-05T12:42:00.694977Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T12:42:00.699052Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T12:42:00.700741Z","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-05-05T12:42:00.705408Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T12:42:00.705545Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T12:42:00.705858Z","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-05-05T12:42:00.705911Z","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-05-05T12:42:00.705923Z","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-05-05T12:42:00.706491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-05-05T12:42:00.707019Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-05-05T12:42:00.709951Z","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-05-05T12:42:00.710098Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-05-05T12:42:00.710142Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-05-05T12:42:00.710274Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T12:42:00.710311Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T12:42:00.742223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T12:42:00.74226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T12:42:00.742281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-05-05T12:42:00.742294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-05-05T12:42:00.742302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-05-05T12:42:00.742318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-05-05T12:42:00.742326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-05-05T12:42:00.748075Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T12:42:00.748102Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T12:42:00.748211Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T12:42:00.748475Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:42:00.748582Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T12:42:00.748941Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T12:42:00.752658Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-05-05T12:42:00.752733Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T12:42:00.750033Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:42:00.760236Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:42:00.760262Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-05T12:42:02.543977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.214689ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-05-05T12:42:02.544085Z","caller":"traceutil/trace.go:171","msg":"trace[1990634381] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:5; }","duration":"137.652972ms","start":"2026-05-05T12:42:02.406419Z","end":"2026-05-05T12:42:02.544072Z","steps":["trace[1990634381] 'read index received' (duration: 29.969247ms)","trace[1990634381] 'applied index is now lower than readState.Index' (duration: 107.682935ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:42:02.544143Z","caller":"traceutil/trace.go:171","msg":"trace[1029009109] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"148.435775ms","start":"2026-05-05T12:42:02.395668Z","end":"2026-05-05T12:42:02.544104Z","steps":["trace[1029009109] 'process raft request' (duration: 40.672229ms)","trace[1029009109] 'compare' (duration: 107.111768ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:42:02.544198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.776283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:42:02.544227Z","caller":"traceutil/trace.go:171","msg":"trace[1397748076] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:0; response_revision:3; }","duration":"137.855073ms","start":"2026-05-05T12:42:02.406365Z","end":"2026-05-05T12:42:02.54422Z","steps":["trace[1397748076] 'agreement among raft nodes before linearized reading' (duration: 137.773922ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:02.587464Z","caller":"traceutil/trace.go:171","msg":"trace[262049904] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"178.350822ms","start":"2026-05-05T12:42:02.409096Z","end":"2026-05-05T12:42:02.587446Z","steps":["trace[262049904] 'process raft request' (duration: 177.983988ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:02.587976Z","caller":"traceutil/trace.go:171","msg":"trace[1128712920] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"178.116447ms","start":"2026-05-05T12:42:02.409845Z","end":"2026-05-05T12:42:02.587961Z","steps":["trace[1128712920] 'process raft request' (duration: 177.325259ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:02.588074Z","caller":"traceutil/trace.go:171","msg":"trace[1245776731] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"178.130478ms","start":"2026-05-05T12:42:02.409883Z","end":"2026-05-05T12:42:02.588013Z","steps":["trace[1245776731] 'process raft request' (duration: 177.515561ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:02.588212Z","caller":"traceutil/trace.go:171","msg":"trace[596159262] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"178.27006ms","start":"2026-05-05T12:42:02.409902Z","end":"2026-05-05T12:42:02.588172Z","steps":["trace[596159262] 'process raft request' (duration: 177.873155ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:02.588369Z","caller":"traceutil/trace.go:171","msg":"trace[1368014082] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"178.027637ms","start":"2026-05-05T12:42:02.410329Z","end":"2026-05-05T12:42:02.588357Z","steps":["trace[1368014082] 'process raft request' (duration: 177.807664ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:02.58876Z","caller":"traceutil/trace.go:171","msg":"trace[811175037] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"178.25552ms","start":"2026-05-05T12:42:02.410425Z","end":"2026-05-05T12:42:02.58868Z","steps":["trace[811175037] 'process raft request' (duration: 177.872635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:02.588934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.032701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T12:42:02.588947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.104062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:42:02.589009Z","caller":"traceutil/trace.go:171","msg":"trace[638207164] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"180.209343ms","start":"2026-05-05T12:42:02.408786Z","end":"2026-05-05T12:42:02.588995Z","steps":["trace[638207164] 'agreement among raft nodes before linearized reading' (duration: 180.064461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:02.589045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.509718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:42:02.58906Z","caller":"traceutil/trace.go:171","msg":"trace[21454201] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"164.139728ms","start":"2026-05-05T12:42:02.424901Z","end":"2026-05-05T12:42:02.589041Z","steps":["trace[21454201] 'process raft request' (duration: 163.764903ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:02.589009Z","caller":"traceutil/trace.go:171","msg":"trace[49885030] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"180.141223ms","start":"2026-05-05T12:42:02.408846Z","end":"2026-05-05T12:42:02.588988Z","steps":["trace[49885030] 'agreement among raft nodes before linearized reading' (duration: 179.956271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:02.589225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.537891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:42:02.589259Z","caller":"traceutil/trace.go:171","msg":"trace[1704289704] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"126.602403ms","start":"2026-05-05T12:42:02.462647Z","end":"2026-05-05T12:42:02.589249Z","steps":["trace[1704289704] 'agreement among raft nodes before linearized reading' (duration: 126.548832ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:02.5891Z","caller":"traceutil/trace.go:171","msg":"trace[730195557] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"181.565439ms","start":"2026-05-05T12:42:02.407523Z","end":"2026-05-05T12:42:02.589088Z","steps":["trace[730195557] 'agreement among raft nodes before linearized reading' (duration: 181.495828ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:02.589345Z","caller":"traceutil/trace.go:171","msg":"trace[872039733] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"166.625886ms","start":"2026-05-05T12:42:02.422705Z","end":"2026-05-05T12:42:02.589331Z","steps":["trace[872039733] 'process raft request' (duration: 165.886316ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:02.753025Z","caller":"traceutil/trace.go:171","msg":"trace[626491420] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"159.974168ms","start":"2026-05-05T12:42:02.593026Z","end":"2026-05-05T12:42:02.753Z","steps":["trace[626491420] 'process raft request' (duration: 67.880814ms)","trace[626491420] 'compare' (duration: 91.922322ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:42:02.764825Z","caller":"traceutil/trace.go:171","msg":"trace[1679486434] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"171.521341ms","start":"2026-05-05T12:42:02.593279Z","end":"2026-05-05T12:42:02.7648Z","steps":["trace[1679486434] 'process raft request' (duration: 171.40729ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:02.764964Z","caller":"traceutil/trace.go:171","msg":"trace[921992634] transaction","detail":"{read_only:false; number_of_response:0; response_revision:15; }","duration":"170.932085ms","start":"2026-05-05T12:42:02.594022Z","end":"2026-05-05T12:42:02.764954Z","steps":["trace[921992634] 'process raft request' (duration: 170.782474ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:02.764989Z","caller":"traceutil/trace.go:171","msg":"trace[904250602] linearizableReadLoop","detail":"{readStateIndex:17; appliedIndex:14; }","duration":"171.283618ms","start":"2026-05-05T12:42:02.59369Z","end":"2026-05-05T12:42:02.764974Z","steps":["trace[904250602] 'read index received' (duration: 67.225676ms)","trace[904250602] 'applied index is now lower than readState.Index' (duration: 104.057092ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:42:02.765068Z","caller":"traceutil/trace.go:171","msg":"trace[1818445237] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"169.75559ms","start":"2026-05-05T12:42:02.595303Z","end":"2026-05-05T12:42:02.765058Z","steps":["trace[1818445237] 'process raft request' (duration: 169.536688ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:02.764901Z","caller":"traceutil/trace.go:171","msg":"trace[28123469] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"171.45283ms","start":"2026-05-05T12:42:02.593427Z","end":"2026-05-05T12:42:02.76488Z","steps":["trace[28123469] 'process raft request' (duration: 171.319329ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:02.765177Z","caller":"traceutil/trace.go:171","msg":"trace[94983088] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"169.60002ms","start":"2026-05-05T12:42:02.595567Z","end":"2026-05-05T12:42:02.765167Z","steps":["trace[94983088] 'process raft request' (duration: 169.310295ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:02.765194Z","caller":"traceutil/trace.go:171","msg":"trace[809671335] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"171.367359ms","start":"2026-05-05T12:42:02.593688Z","end":"2026-05-05T12:42:02.765055Z","steps":["trace[809671335] 'process raft request' (duration: 171.090126ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:02.765276Z","caller":"traceutil/trace.go:171","msg":"trace[647823794] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"164.786074ms","start":"2026-05-05T12:42:02.60046Z","end":"2026-05-05T12:42:02.765246Z","steps":["trace[647823794] 'process raft request' (duration: 164.47249ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:02.765295Z","caller":"traceutil/trace.go:171","msg":"trace[1443799927] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"169.55823ms","start":"2026-05-05T12:42:02.595701Z","end":"2026-05-05T12:42:02.765259Z","steps":["trace[1443799927] 'process raft request' (duration: 169.205595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:02.765431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.723474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3490"} {"level":"info","ts":"2026-05-05T12:42:02.765597Z","caller":"traceutil/trace.go:171","msg":"trace[1367674808] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:19; }","duration":"171.900997ms","start":"2026-05-05T12:42:02.593661Z","end":"2026-05-05T12:42:02.765562Z","steps":["trace[1367674808] 'agreement among raft nodes before linearized reading' (duration: 171.616834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:02.76789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.094021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-6bz92\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-05-05T12:42:02.767964Z","caller":"traceutil/trace.go:171","msg":"trace[1898605385] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-6bz92; range_end:; response_count:1; response_revision:20; }","duration":"174.201224ms","start":"2026-05-05T12:42:02.593751Z","end":"2026-05-05T12:42:02.767952Z","steps":["trace[1898605385] 'agreement among raft nodes before linearized reading' (duration: 174.068961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:02.76792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.988228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-05-05T12:42:02.768176Z","caller":"traceutil/trace.go:171","msg":"trace[1939233353] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"115.289223ms","start":"2026-05-05T12:42:02.652876Z","end":"2026-05-05T12:42:02.768166Z","steps":["trace[1939233353] 'agreement among raft nodes before linearized reading' (duration: 114.967749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:02.768238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.089149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:42:02.768319Z","caller":"traceutil/trace.go:171","msg":"trace[1260515818] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:20; }","duration":"172.19131ms","start":"2026-05-05T12:42:02.59611Z","end":"2026-05-05T12:42:02.768301Z","steps":["trace[1260515818] 'agreement among raft nodes before linearized reading' (duration: 172.062408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:03.125499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.218986ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-05T12:42:03.125623Z","caller":"traceutil/trace.go:171","msg":"trace[1046131210] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"334.55873ms","start":"2026-05-05T12:42:02.791041Z","end":"2026-05-05T12:42:03.125599Z","steps":["trace[1046131210] 'process raft request' (duration: 135.149312ms)","trace[1046131210] 'compare' (duration: 199.075805ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:42:03.125694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:02.791027Z","time spent":"334.635701ms","remote":"127.0.0.1:41658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:42:03.127867Z","caller":"traceutil/trace.go:171","msg":"trace[2005452081] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"319.635617ms","start":"2026-05-05T12:42:02.808215Z","end":"2026-05-05T12:42:03.127851Z","steps":["trace[2005452081] 'process raft request' (duration: 319.339084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:03.128046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:02.808195Z","time spent":"319.766109ms","remote":"127.0.0.1:41980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":711,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:42:03.128183Z","caller":"traceutil/trace.go:171","msg":"trace[1496098982] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"318.298143ms","start":"2026-05-05T12:42:02.809866Z","end":"2026-05-05T12:42:03.128164Z","steps":["trace[1496098982] 'process raft request' (duration: 318.02896ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:03.128226Z","caller":"traceutil/trace.go:171","msg":"trace[1483766855] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"318.470775ms","start":"2026-05-05T12:42:02.809707Z","end":"2026-05-05T12:42:03.128177Z","steps":["trace[1483766855] 'process raft request' (duration: 318.121141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:03.128325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:02.809851Z","time spent":"318.377593ms","remote":"127.0.0.1:42046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":983,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:42:03.128354Z","caller":"traceutil/trace.go:171","msg":"trace[275875337] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"319.84853ms","start":"2026-05-05T12:42:02.808425Z","end":"2026-05-05T12:42:03.128274Z","steps":["trace[275875337] 'process raft request' (duration: 319.333334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:03.128417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:02.809698Z","time spent":"318.632266ms","remote":"127.0.0.1:42046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":926,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:42:03.12843Z","caller":"traceutil/trace.go:171","msg":"trace[2042954272] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"318.362052ms","start":"2026-05-05T12:42:02.810014Z","end":"2026-05-05T12:42:03.128376Z","steps":["trace[2042954272] 'process raft request' (duration: 317.951287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:03.128583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:02.808411Z","time spent":"320.078712ms","remote":"127.0.0.1:42046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:42:03.128217Z","caller":"traceutil/trace.go:171","msg":"trace[194688568] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"318.12471ms","start":"2026-05-05T12:42:02.810062Z","end":"2026-05-05T12:42:03.128187Z","steps":["trace[194688568] 'process raft request' (duration: 317.933708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:03.12864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:02.809963Z","time spent":"318.516275ms","remote":"127.0.0.1:42046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:42:03.128675Z","caller":"traceutil/trace.go:171","msg":"trace[1961417205] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"318.745767ms","start":"2026-05-05T12:42:02.809917Z","end":"2026-05-05T12:42:03.128663Z","steps":["trace[1961417205] 'process raft request' (duration: 318.009009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:03.128874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:02.809908Z","time spent":"318.93222ms","remote":"127.0.0.1:42046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T12:42:03.128909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:02.810053Z","time spent":"318.673106ms","remote":"127.0.0.1:41642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3004,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T12:42:03.807836Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278611386450343,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T12:42:04.299192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.091765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-05T12:42:04.299403Z","caller":"traceutil/trace.go:171","msg":"trace[1511962220] linearizableReadLoop","detail":"{readStateIndex:45; appliedIndex:43; }","duration":"992.587312ms","start":"2026-05-05T12:42:03.306794Z","end":"2026-05-05T12:42:04.299381Z","steps":["trace[1511962220] 'read index received' (duration: 766.217114ms)","trace[1511962220] 'applied index is now lower than readState.Index' (duration: 226.369358ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:42:04.299465Z","caller":"traceutil/trace.go:171","msg":"trace[2011172101] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"1.088183445s","start":"2026-05-05T12:42:03.211256Z","end":"2026-05-05T12:42:04.29944Z","steps":["trace[2011172101] 'process raft request' (duration: 861.758776ms)","trace[2011172101] 'compare' (duration: 225.970054ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:42:04.299492Z","caller":"traceutil/trace.go:171","msg":"trace[611689238] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"1.085212721s","start":"2026-05-05T12:42:03.21427Z","end":"2026-05-05T12:42:04.299483Z","steps":["trace[611689238] 'process raft request' (duration: 1.085066059s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:04.299554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:03.211241Z","time spent":"1.088267455s","remote":"127.0.0.1:41978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":647,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T12:42:04.299552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:03.214244Z","time spent":"1.085285172s","remote":"127.0.0.1:41658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":350,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T12:42:04.299747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.803434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:42:04.299769Z","caller":"traceutil/trace.go:171","msg":"trace[1245432670] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:41; }","duration":"458.856044ms","start":"2026-05-05T12:42:03.840905Z","end":"2026-05-05T12:42:04.299761Z","steps":["trace[1245432670] 'agreement among raft nodes before linearized reading' (duration: 458.796853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:04.299787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:03.840886Z","time spent":"458.897934ms","remote":"127.0.0.1:41680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-05-05T12:42:04.299859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.066567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:42:04.299871Z","caller":"traceutil/trace.go:171","msg":"trace[625921706] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:41; }","duration":"993.104698ms","start":"2026-05-05T12:42:03.306763Z","end":"2026-05-05T12:42:04.299868Z","steps":["trace[625921706] 'agreement among raft nodes before linearized reading' (duration: 993.084908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:04.299802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.501511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:42:04.299938Z","caller":"traceutil/trace.go:171","msg":"trace[288686265] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:41; }","duration":"992.722874ms","start":"2026-05-05T12:42:03.307189Z","end":"2026-05-05T12:42:04.299912Z","steps":["trace[288686265] 'agreement among raft nodes before linearized reading' (duration: 992.326989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:04.300011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.15114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:42:04.300083Z","caller":"traceutil/trace.go:171","msg":"trace[805862447] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:41; }","duration":"706.264261ms","start":"2026-05-05T12:42:03.593809Z","end":"2026-05-05T12:42:04.300073Z","steps":["trace[805862447] 'agreement among raft nodes before linearized reading' (duration: 706.1688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:04.300086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:03.307168Z","time spent":"992.897665ms","remote":"127.0.0.1:41898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-05-05T12:42:04.300112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:03.593762Z","time spent":"706.342752ms","remote":"127.0.0.1:41536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T12:42:04.300258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.88859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:42:04.300284Z","caller":"traceutil/trace.go:171","msg":"trace[1394850746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:41; }","duration":"946.943941ms","start":"2026-05-05T12:42:03.353332Z","end":"2026-05-05T12:42:04.300276Z","steps":["trace[1394850746] 'agreement among raft nodes before linearized reading' (duration: 946.90131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:04.300312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:03.353314Z","time spent":"946.991882ms","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T12:42:04.300977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:03.306748Z","time spent":"993.132228ms","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"info","ts":"2026-05-05T12:42:04.467424Z","caller":"traceutil/trace.go:171","msg":"trace[1164367917] linearizableReadLoop","detail":"{readStateIndex:48; appliedIndex:46; }","duration":"120.718834ms","start":"2026-05-05T12:42:04.346543Z","end":"2026-05-05T12:42:04.467262Z","steps":["trace[1164367917] 'read index received' (duration: 84.337323ms)","trace[1164367917] 'applied index is now lower than readState.Index' (duration: 36.380771ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:42:04.467472Z","caller":"traceutil/trace.go:171","msg":"trace[2068226327] transaction","detail":"{read_only:false; response_revision:44; number_of_response:1; }","duration":"142.784943ms","start":"2026-05-05T12:42:04.324656Z","end":"2026-05-05T12:42:04.467441Z","steps":["trace[2068226327] 'process raft request' (duration: 142.5383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:04.467582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.391876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:42:04.467695Z","caller":"traceutil/trace.go:171","msg":"trace[1475202228] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:44; }","duration":"163.555748ms","start":"2026-05-05T12:42:04.304108Z","end":"2026-05-05T12:42:04.467664Z","steps":["trace[1475202228] 'agreement among raft nodes before linearized reading' (duration: 163.411336ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:04.467689Z","caller":"traceutil/trace.go:171","msg":"trace[1327570630] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"163.825491ms","start":"2026-05-05T12:42:04.303844Z","end":"2026-05-05T12:42:04.46767Z","steps":["trace[1327570630] 'process raft request' (duration: 127.034355ms)","trace[1327570630] 'compare' (duration: 36.203029ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:42:04.468017Z","caller":"traceutil/trace.go:171","msg":"trace[1423679095] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"118.058714ms","start":"2026-05-05T12:42:04.349934Z","end":"2026-05-05T12:42:04.467993Z","steps":["trace[1423679095] 'process raft request' (duration: 117.873642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:04.468069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.257377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T12:42:04.468164Z","caller":"traceutil/trace.go:171","msg":"trace[61106239] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:45; }","duration":"119.420239ms","start":"2026-05-05T12:42:04.348719Z","end":"2026-05-05T12:42:04.46814Z","steps":["trace[61106239] 'agreement among raft nodes before linearized reading' (duration: 119.205216ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:04.733493Z","caller":"traceutil/trace.go:171","msg":"trace[1065731332] transaction","detail":"{read_only:false; response_revision:85; number_of_response:1; }","duration":"132.78986ms","start":"2026-05-05T12:42:04.600681Z","end":"2026-05-05T12:42:04.733471Z","steps":["trace[1065731332] 'process raft request' (duration: 126.642591ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:06.728157Z","caller":"traceutil/trace.go:171","msg":"trace[1918520032] linearizableReadLoop","detail":"{readStateIndex:267; appliedIndex:266; }","duration":"251.719584ms","start":"2026-05-05T12:42:06.476412Z","end":"2026-05-05T12:42:06.728132Z","steps":["trace[1918520032] 'read index received' (duration: 251.494321ms)","trace[1918520032] 'applied index is now lower than readState.Index' (duration: 223.893µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:42:06.728391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.964746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-05-05T12:42:06.728453Z","caller":"traceutil/trace.go:171","msg":"trace[2130416655] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:261; }","duration":"252.092428ms","start":"2026-05-05T12:42:06.476348Z","end":"2026-05-05T12:42:06.728441Z","steps":["trace[2130416655] 'agreement among raft nodes before linearized reading' (duration: 251.898246ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:06.728441Z","caller":"traceutil/trace.go:171","msg":"trace[1241803647] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"362.819982ms","start":"2026-05-05T12:42:06.3656Z","end":"2026-05-05T12:42:06.72842Z","steps":["trace[1241803647] 'process raft request' (duration: 362.351497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:06.728552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:06.365572Z","time spent":"362.929823ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:42:06.966229Z","caller":"traceutil/trace.go:171","msg":"trace[742830120] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"229.518508ms","start":"2026-05-05T12:42:06.736684Z","end":"2026-05-05T12:42:06.966202Z","steps":["trace[742830120] 'process raft request' (duration: 229.394097ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:07.029446Z","caller":"traceutil/trace.go:171","msg":"trace[109321410] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"291.697833ms","start":"2026-05-05T12:42:06.73773Z","end":"2026-05-05T12:42:07.029428Z","steps":["trace[109321410] 'process raft request' (duration: 291.530662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:07.304424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.42158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T12:42:07.304511Z","caller":"traceutil/trace.go:171","msg":"trace[2075053146] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"265.208122ms","start":"2026-05-05T12:42:07.039288Z","end":"2026-05-05T12:42:07.304496Z","steps":["trace[2075053146] 'process raft request' (duration: 153.660021ms)","trace[2075053146] 'compare' (duration: 111.070076ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:42:07.328989Z","caller":"traceutil/trace.go:171","msg":"trace[1315525548] linearizableReadLoop","detail":"{readStateIndex:272; appliedIndex:270; }","duration":"275.205293ms","start":"2026-05-05T12:42:07.053763Z","end":"2026-05-05T12:42:07.328968Z","steps":["trace[1315525548] 'read index received' (duration: 139.201243ms)","trace[1315525548] 'applied index is now lower than readState.Index' (duration: 136.003179ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:42:07.329112Z","caller":"traceutil/trace.go:171","msg":"trace[318378335] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"285.808318ms","start":"2026-05-05T12:42:07.043271Z","end":"2026-05-05T12:42:07.329079Z","steps":["trace[318378335] 'process raft request' (duration: 285.595515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:07.329312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.534476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:42:07.329387Z","caller":"traceutil/trace.go:171","msg":"trace[853375100] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:266; }","duration":"275.650677ms","start":"2026-05-05T12:42:07.053717Z","end":"2026-05-05T12:42:07.329367Z","steps":["trace[853375100] 'agreement among raft nodes before linearized reading' (duration: 275.507125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:08.347273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.259141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T12:42:08.347396Z","caller":"traceutil/trace.go:171","msg":"trace[604611778] linearizableReadLoop","detail":"{readStateIndex:299; appliedIndex:298; }","duration":"130.715995ms","start":"2026-05-05T12:42:08.216664Z","end":"2026-05-05T12:42:08.34738Z","steps":["trace[604611778] 'read index received' (duration: 266.792µs)","trace[604611778] 'applied index is now lower than readState.Index' (duration: 130.448163ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:42:08.347532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.877317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-05-05T12:42:08.34755Z","caller":"traceutil/trace.go:171","msg":"trace[1699923266] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"149.920363ms","start":"2026-05-05T12:42:08.197598Z","end":"2026-05-05T12:42:08.347519Z","steps":["trace[1699923266] 'process raft request' (duration: 19.34956ms)","trace[1699923266] 'compare' (duration: 130.112859ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:42:08.347663Z","caller":"traceutil/trace.go:171","msg":"trace[1013415470] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:293; }","duration":"131.054149ms","start":"2026-05-05T12:42:08.216597Z","end":"2026-05-05T12:42:08.347651Z","steps":["trace[1013415470] 'agreement among raft nodes before linearized reading' (duration: 130.828256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:08.587727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.83737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-05T12:42:08.587831Z","caller":"traceutil/trace.go:171","msg":"trace[873061613] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:294; }","duration":"122.002372ms","start":"2026-05-05T12:42:08.465807Z","end":"2026-05-05T12:42:08.58781Z","steps":["trace[873061613] 'range keys from in-memory index tree' (duration: 121.710948ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:08.897991Z","caller":"traceutil/trace.go:171","msg":"trace[1854990655] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"276.090361ms","start":"2026-05-05T12:42:08.621862Z","end":"2026-05-05T12:42:08.897952Z","steps":["trace[1854990655] 'process raft request' (duration: 222.034204ms)","trace[1854990655] 'compare' (duration: 53.878595ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:42:09.153964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.354851ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T12:42:09.154198Z","caller":"traceutil/trace.go:171","msg":"trace[1503529149] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"488.91415ms","start":"2026-05-05T12:42:08.665249Z","end":"2026-05-05T12:42:09.154163Z","steps":["trace[1503529149] 'process raft request' (duration: 354.281846ms)","trace[1503529149] 'compare' (duration: 134.175589ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:42:09.154284Z","caller":"traceutil/trace.go:171","msg":"trace[57013714] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"461.204109ms","start":"2026-05-05T12:42:08.693043Z","end":"2026-05-05T12:42:09.154247Z","steps":["trace[57013714] 'process raft request' (duration: 461.030128ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:09.15429Z","caller":"traceutil/trace.go:171","msg":"trace[820214853] transaction","detail":"{read_only:false; number_of_response:0; response_revision:298; }","duration":"370.277296ms","start":"2026-05-05T12:42:08.783985Z","end":"2026-05-05T12:42:09.154263Z","steps":["trace[820214853] 'process raft request' (duration: 370.157334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:09.15432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:08.665219Z","time spent":"489.058122ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T12:42:09.154419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:08.783956Z","time spent":"370.405228ms","remote":"127.0.0.1:41716","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T12:42:09.154425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:08.693032Z","time spent":"461.324241ms","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:42:09.154423Z","caller":"traceutil/trace.go:171","msg":"trace[1453665696] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"354.829228ms","start":"2026-05-05T12:42:08.799571Z","end":"2026-05-05T12:42:09.1544Z","steps":["trace[1453665696] 'process raft request' (duration: 354.671936ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:09.154456Z","caller":"traceutil/trace.go:171","msg":"trace[297558920] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"354.942639ms","start":"2026-05-05T12:42:08.799493Z","end":"2026-05-05T12:42:09.154436Z","steps":["trace[297558920] 'process raft request' (duration: 354.690577ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:09.154507Z","caller":"traceutil/trace.go:171","msg":"trace[121814758] transaction","detail":"{read_only:false; number_of_response:0; response_revision:301; }","duration":"354.308993ms","start":"2026-05-05T12:42:08.800187Z","end":"2026-05-05T12:42:09.154496Z","steps":["trace[121814758] 'process raft request' (duration: 354.147631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:09.154508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:08.799567Z","time spent":"354.902769ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T12:42:09.154535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:08.799474Z","time spent":"355.02408ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T12:42:09.154551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:08.800183Z","time spent":"354.347773ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:42:09.154615Z","caller":"traceutil/trace.go:171","msg":"trace[1957156808] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"354.511524ms","start":"2026-05-05T12:42:08.800097Z","end":"2026-05-05T12:42:09.154609Z","steps":["trace[1957156808] 'process raft request' (duration: 354.183301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:09.154643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:08.800091Z","time spent":"354.537985ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:42:09.154649Z","caller":"traceutil/trace.go:171","msg":"trace[2040463183] transaction","detail":"{read_only:false; number_of_response:0; response_revision:301; }","duration":"353.958009ms","start":"2026-05-05T12:42:08.80068Z","end":"2026-05-05T12:42:09.154638Z","steps":["trace[2040463183] 'process raft request' (duration: 353.687246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:09.154699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:08.800674Z","time spent":"354.002859ms","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:42:09.15483Z","caller":"traceutil/trace.go:171","msg":"trace[1917442536] linearizableReadLoop","detail":"{readStateIndex:303; appliedIndex:301; }","duration":"462.088899ms","start":"2026-05-05T12:42:08.692727Z","end":"2026-05-05T12:42:09.154816Z","steps":["trace[1917442536] 'read index received' (duration: 151.266348ms)","trace[1917442536] 'applied index is now lower than readState.Index' (duration: 310.820841ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:42:09.154979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.236601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:42:09.15504Z","caller":"traceutil/trace.go:171","msg":"trace[72814710] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:301; }","duration":"462.328882ms","start":"2026-05-05T12:42:08.692694Z","end":"2026-05-05T12:42:09.155023Z","steps":["trace[72814710] 'agreement among raft nodes before linearized reading' (duration: 462.17157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:09.15509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:08.692679Z","time spent":"462.400674ms","remote":"127.0.0.1:41798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-05-05T12:42:09.155551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.667156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-05T12:42:09.155601Z","caller":"traceutil/trace.go:171","msg":"trace[1876468246] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:302; }","duration":"462.751976ms","start":"2026-05-05T12:42:08.692835Z","end":"2026-05-05T12:42:09.155587Z","steps":["trace[1876468246] 'agreement among raft nodes before linearized reading' (duration: 462.640285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:09.155647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:08.692815Z","time spent":"462.821537ms","remote":"127.0.0.1:41920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":688,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-05T12:42:09.155904Z","caller":"traceutil/trace.go:171","msg":"trace[935496384] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"253.465489ms","start":"2026-05-05T12:42:08.902424Z","end":"2026-05-05T12:42:09.15589Z","steps":["trace[935496384] 'process raft request' (duration: 252.382267ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:09.314863Z","caller":"traceutil/trace.go:171","msg":"trace[862214101] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:314; }","duration":"141.843031ms","start":"2026-05-05T12:42:09.172997Z","end":"2026-05-05T12:42:09.31484Z","steps":["trace[862214101] 'read index received' (duration: 82.982821ms)","trace[862214101] 'applied index is now lower than readState.Index' (duration: 58.85927ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:42:09.31499Z","caller":"traceutil/trace.go:171","msg":"trace[13538836] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"144.139066ms","start":"2026-05-05T12:42:09.170775Z","end":"2026-05-05T12:42:09.314914Z","steps":["trace[13538836] 'process raft request' (duration: 85.242885ms)","trace[13538836] 'compare' (duration: 58.486336ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:42:09.315006Z","caller":"traceutil/trace.go:171","msg":"trace[1751231973] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"144.130136ms","start":"2026-05-05T12:42:09.170837Z","end":"2026-05-05T12:42:09.314967Z","steps":["trace[1751231973] 'process raft request' (duration: 143.899483ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:09.315058Z","caller":"traceutil/trace.go:171","msg":"trace[2307839] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"139.716738ms","start":"2026-05-05T12:42:09.175319Z","end":"2026-05-05T12:42:09.315035Z","steps":["trace[2307839] 'process raft request' (duration: 139.483146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:09.315291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.51092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:42:09.315334Z","caller":"traceutil/trace.go:171","msg":"trace[1879289510] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:0; response_revision:308; }","duration":"144.584681ms","start":"2026-05-05T12:42:09.170733Z","end":"2026-05-05T12:42:09.315317Z","steps":["trace[1879289510] 'agreement among raft nodes before linearized reading' (duration: 144.205247ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:12.269755Z","caller":"traceutil/trace.go:171","msg":"trace[1959131014] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"256.397762ms","start":"2026-05-05T12:42:12.013334Z","end":"2026-05-05T12:42:12.269732Z","steps":["trace[1959131014] 'process raft request' (duration: 256.155009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:13.886435Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278611386451043,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T12:42:14.386571Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278611386451043,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T12:42:14.553783Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.81686154s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T12:42:14.554453Z","caller":"traceutil/trace.go:171","msg":"trace[242284852] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"1.817633017s","start":"2026-05-05T12:42:12.736786Z","end":"2026-05-05T12:42:14.554419Z","steps":["trace[242284852] 'process raft request' (duration: 1.817357004s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:14.554782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:12.736764Z","time spent":"1.817795249s","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:42:15.386105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999952784s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-05T12:42:15.386191Z","caller":"traceutil/trace.go:171","msg":"trace[1246839415] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000097936s","start":"2026-05-05T12:42:13.386073Z","end":"2026-05-05T12:42:15.386171Z","steps":["trace[1246839415] 'agreement among raft nodes before linearized reading' (duration: 1.999992695s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:15.386241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:13.386049Z","time spent":"2.000182707s","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/05 12:42:15 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-05T12:42:15.93314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.249153ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T12:42:15.933252Z","caller":"traceutil/trace.go:171","msg":"trace[2090593616] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:406; }","duration":"2.547105216s","start":"2026-05-05T12:42:13.386131Z","end":"2026-05-05T12:42:15.933236Z","steps":["trace[2090593616] 'read index received' (duration: 1.167951692s)","trace[2090593616] 'applied index is now lower than readState.Index' (duration: 1.379152374s)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:42:15.933334Z","caller":"traceutil/trace.go:171","msg":"trace[1115371206] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"2.869495915s","start":"2026-05-05T12:42:13.063783Z","end":"2026-05-05T12:42:15.933279Z","steps":["trace[1115371206] 'process raft request' (duration: 2.188030951s)","trace[1115371206] 'compare' (duration: 681.117951ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:42:15.93353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:13.06376Z","time spent":"2.869670048s","remote":"127.0.0.1:41798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:42:16.025062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.239604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T12:42:16.025395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.604090207s","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-05-05T12:42:16.025494Z","caller":"traceutil/trace.go:171","msg":"trace[605380945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:396; }","duration":"2.604242938s","start":"2026-05-05T12:42:13.421232Z","end":"2026-05-05T12:42:16.025475Z","steps":["trace[605380945] 'agreement among raft nodes before linearized reading' (duration: 2.604039286s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:16.02554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:13.421214Z","time spent":"2.604315999s","remote":"127.0.0.1:41798","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-05-05T12:42:16.025622Z","caller":"traceutil/trace.go:171","msg":"trace[31950854] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:396; }","duration":"638.696139ms","start":"2026-05-05T12:42:15.386755Z","end":"2026-05-05T12:42:16.025451Z","steps":["trace[31950854] 'agreement among raft nodes before linearized reading' (duration: 638.192434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:16.025782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:15.386724Z","time spent":"639.043482ms","remote":"127.0.0.1:41536","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-05-05T12:42:16.025047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.632033756s","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-05-05T12:42:16.026158Z","caller":"traceutil/trace.go:171","msg":"trace[779065703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:396; }","duration":"2.633180867s","start":"2026-05-05T12:42:13.39295Z","end":"2026-05-05T12:42:16.026131Z","steps":["trace[779065703] 'agreement among raft nodes before linearized reading' (duration: 2.631895334s)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:16.025082Z","caller":"traceutil/trace.go:171","msg":"trace[1479395837] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"1.464082954s","start":"2026-05-05T12:42:14.560951Z","end":"2026-05-05T12:42:16.025034Z","steps":["trace[1479395837] 'process raft request' (duration: 1.463750431s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:16.026232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:13.392934Z","time spent":"2.633277017s","remote":"127.0.0.1:41798","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-05-05T12:42:16.025137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.330299775s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"warn","ts":"2026-05-05T12:42:16.026358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:14.560929Z","time spent":"1.465360026s","remote":"127.0.0.1:41998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2903,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:42:16.026397Z","caller":"traceutil/trace.go:171","msg":"trace[1657154220] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:396; }","duration":"1.331585357s","start":"2026-05-05T12:42:14.694796Z","end":"2026-05-05T12:42:16.026381Z","steps":["trace[1657154220] 'agreement among raft nodes before linearized reading' (duration: 1.330302315s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:16.0265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:14.694779Z","time spent":"1.331661558s","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4346,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-05-05T12:42:16.025199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.752095141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-05T12:42:16.025251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.496632687s","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-05-05T12:42:16.02671Z","caller":"traceutil/trace.go:171","msg":"trace[1316340974] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:396; }","duration":"1.753549015s","start":"2026-05-05T12:42:14.273065Z","end":"2026-05-05T12:42:16.026614Z","steps":["trace[1316340974] 'agreement among raft nodes before linearized reading' (duration: 1.752098391s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:16.026758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:14.273051Z","time spent":"1.753692349s","remote":"127.0.0.1:41798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T12:42:16.026755Z","caller":"traceutil/trace.go:171","msg":"trace[1332050617] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:396; }","duration":"2.498156592s","start":"2026-05-05T12:42:13.528576Z","end":"2026-05-05T12:42:16.026733Z","steps":["trace[1332050617] 'agreement among raft nodes before linearized reading' (duration: 2.496644447s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:16.025346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.160083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-05T12:42:16.026847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:13.528557Z","time spent":"2.498261693s","remote":"127.0.0.1:41798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T12:42:16.02685Z","caller":"traceutil/trace.go:171","msg":"trace[1881734446] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:396; }","duration":"630.726179ms","start":"2026-05-05T12:42:15.396113Z","end":"2026-05-05T12:42:16.026839Z","steps":["trace[1881734446] 'agreement among raft nodes before linearized reading' (duration: 629.109892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:16.026908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:15.396087Z","time spent":"630.80733ms","remote":"127.0.0.1:41558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.49\" "} {"level":"info","ts":"2026-05-05T12:42:17.858165Z","caller":"traceutil/trace.go:171","msg":"trace[1971514135] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"215.463859ms","start":"2026-05-05T12:42:17.642668Z","end":"2026-05-05T12:42:17.858132Z","steps":["trace[1971514135] 'process raft request' (duration: 215.255457ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:19.679925Z","caller":"traceutil/trace.go:171","msg":"trace[862616852] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"290.596603ms","start":"2026-05-05T12:42:19.3893Z","end":"2026-05-05T12:42:19.679896Z","steps":["trace[862616852] 'process raft request' (duration: 290.384481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:20.934623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.507965ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-05T12:42:24.150306Z","caller":"traceutil/trace.go:171","msg":"trace[1094323323] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"229.8095ms","start":"2026-05-05T12:42:23.920477Z","end":"2026-05-05T12:42:24.150286Z","steps":["trace[1094323323] 'process raft request' (duration: 229.613048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:38.949545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.201251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:42:38.949614Z","caller":"traceutil/trace.go:171","msg":"trace[1387504594] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:518; }","duration":"266.312183ms","start":"2026-05-05T12:42:38.683286Z","end":"2026-05-05T12:42:38.949598Z","steps":["trace[1387504594] 'range keys from in-memory index tree' (duration: 266.04917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:38.949788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.834465ms","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-05-05T12:42:38.949841Z","caller":"traceutil/trace.go:171","msg":"trace[1818805037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:518; }","duration":"532.913797ms","start":"2026-05-05T12:42:38.416921Z","end":"2026-05-05T12:42:38.949835Z","steps":["trace[1818805037] 'range keys from in-memory index tree' (duration: 532.730415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:38.949898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.595698ms","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-05-05T12:42:38.949944Z","caller":"traceutil/trace.go:171","msg":"trace[1700889364] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:518; }","duration":"528.676838ms","start":"2026-05-05T12:42:38.421254Z","end":"2026-05-05T12:42:38.94993Z","steps":["trace[1700889364] 'range keys from in-memory index tree' (duration: 528.480637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:38.949974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:38.421232Z","time spent":"528.73569ms","remote":"127.0.0.1:41798","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-05-05T12:42:38.949865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:38.416907Z","time spent":"532.950807ms","remote":"127.0.0.1:41798","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-05-05T12:42:39.549102Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278611386451298,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T12:42:40.049534Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278611386451298,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T12:42:40.068115Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.114185707s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T12:42:40.068483Z","caller":"traceutil/trace.go:171","msg":"trace[1630461437] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.114615451s","start":"2026-05-05T12:42:38.95384Z","end":"2026-05-05T12:42:40.068455Z","steps":["trace[1630461437] 'process raft request' (duration: 1.11441417s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:40.068675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:38.95382Z","time spent":"1.114772852s","remote":"127.0.0.1:41798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:42:41.05523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.85777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T12:42:41.055714Z","caller":"traceutil/trace.go:171","msg":"trace[388358052] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"728.997025ms","start":"2026-05-05T12:42:40.326701Z","end":"2026-05-05T12:42:41.055698Z","steps":["trace[388358052] 'process raft request' (duration: 728.932744ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:41.055735Z","caller":"traceutil/trace.go:171","msg":"trace[2074408500] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:537; }","duration":"2.007587996s","start":"2026-05-05T12:42:39.048114Z","end":"2026-05-05T12:42:41.055702Z","steps":["trace[2074408500] 'read index received' (duration: 1.02015416s)","trace[2074408500] 'applied index is now lower than readState.Index' (duration: 987.431306ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:42:41.055859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:40.326676Z","time spent":"729.120465ms","remote":"127.0.0.1:41798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:42:41.055888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.007745397s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:42:41.055925Z","caller":"traceutil/trace.go:171","msg":"trace[188351551] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:521; }","duration":"2.007838687s","start":"2026-05-05T12:42:39.048074Z","end":"2026-05-05T12:42:41.055913Z","steps":["trace[188351551] 'agreement among raft nodes before linearized reading' (duration: 2.007744187s)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:41.055848Z","caller":"traceutil/trace.go:171","msg":"trace[1152760830] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"2.101917516s","start":"2026-05-05T12:42:38.953878Z","end":"2026-05-05T12:42:41.055796Z","steps":["trace[1152760830] 'process raft request' (duration: 1.436164729s)","trace[1152760830] 'compare' (duration: 664.710129ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:42:41.055974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:39.048055Z","time spent":"2.007909779s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-05-05T12:42:41.056272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:38.953851Z","time spent":"2.102242589s","remote":"127.0.0.1:41798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:42:41.346454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.682591ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-05T12:42:41.346556Z","caller":"traceutil/trace.go:171","msg":"trace[656683052] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:540; }","duration":"290.747611ms","start":"2026-05-05T12:42:41.055791Z","end":"2026-05-05T12:42:41.346538Z","steps":["trace[656683052] 'read index received' (duration: 100.919828ms)","trace[656683052] 'applied index is now lower than readState.Index' (duration: 189.826313ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:42:41.346756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045661158s","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-05-05T12:42:41.346794Z","caller":"traceutil/trace.go:171","msg":"trace[614844053] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:521; }","duration":"1.045740289s","start":"2026-05-05T12:42:40.301043Z","end":"2026-05-05T12:42:41.346783Z","steps":["trace[614844053] 'agreement among raft nodes before linearized reading' (duration: 1.045570567s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:41.346769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.352087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-05T12:42:41.346831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:40.301024Z","time spent":"1.045799469s","remote":"127.0.0.1:41798","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":"info","ts":"2026-05-05T12:42:41.346857Z","caller":"traceutil/trace.go:171","msg":"trace[974118884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:521; }","duration":"970.492159ms","start":"2026-05-05T12:42:40.37635Z","end":"2026-05-05T12:42:41.346842Z","steps":["trace[974118884] 'agreement among raft nodes before linearized reading' (duration: 970.207717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:41.346881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.960627156s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T12:42:41.346921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:40.376331Z","time spent":"970.58267ms","remote":"127.0.0.1:41798","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-05-05T12:42:41.346778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.758416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:42:41.346937Z","caller":"traceutil/trace.go:171","msg":"trace[1066829656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"1.960717227s","start":"2026-05-05T12:42:39.386202Z","end":"2026-05-05T12:42:41.346919Z","steps":["trace[1066829656] 'agreement among raft nodes before linearized reading' (duration: 1.960592736s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:41.34699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:39.386184Z","time spent":"1.960794907s","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T12:42:41.346987Z","caller":"traceutil/trace.go:171","msg":"trace[233798641] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:521; }","duration":"279.004708ms","start":"2026-05-05T12:42:41.067966Z","end":"2026-05-05T12:42:41.34697Z","steps":["trace[233798641] 'agreement among raft nodes before linearized reading' (duration: 278.759106ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:42:42.262506Z","caller":"traceutil/trace.go:171","msg":"trace[1842296770] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"464.978617ms","start":"2026-05-05T12:42:41.797506Z","end":"2026-05-05T12:42:42.262485Z","steps":["trace[1842296770] 'process raft request' (duration: 464.849886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:42.26262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:41.797485Z","time spent":"465.074009ms","remote":"127.0.0.1:41568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:42:42.2627Z","caller":"traceutil/trace.go:171","msg":"trace[1943702026] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:585; }","duration":"189.895876ms","start":"2026-05-05T12:42:42.072737Z","end":"2026-05-05T12:42:42.262633Z","steps":["trace[1943702026] 'read index received' (duration: 167.205411ms)","trace[1943702026] 'applied index is now lower than readState.Index' (duration: 22.689435ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:42:42.262705Z","caller":"traceutil/trace.go:171","msg":"trace[2055546760] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"466.714793ms","start":"2026-05-05T12:42:41.795938Z","end":"2026-05-05T12:42:42.262653Z","steps":["trace[2055546760] 'process raft request' (duration: 446.425269ms)","trace[2055546760] 'compare' (duration: 19.607538ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:42:42.262738Z","caller":"traceutil/trace.go:171","msg":"trace[1133297378] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"450.435652ms","start":"2026-05-05T12:42:41.812269Z","end":"2026-05-05T12:42:42.262705Z","steps":["trace[1133297378] 'process raft request' (duration: 450.16488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:42.262845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.091327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-05T12:42:42.262864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:41.795918Z","time spent":"466.872534ms","remote":"127.0.0.1:41568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:42:42.262878Z","caller":"traceutil/trace.go:171","msg":"trace[1257255796] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:565; }","duration":"190.180628ms","start":"2026-05-05T12:42:42.072688Z","end":"2026-05-05T12:42:42.262869Z","steps":["trace[1257255796] 'agreement among raft nodes before linearized reading' (duration: 190.074558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:42.262874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:41.812246Z","time spent":"450.557834ms","remote":"127.0.0.1:41568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65199,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:42:47.468481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.23711ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:42:47.468557Z","caller":"traceutil/trace.go:171","msg":"trace[934395153] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:617; }","duration":"269.325191ms","start":"2026-05-05T12:42:47.199219Z","end":"2026-05-05T12:42:47.468544Z","steps":["trace[934395153] 'range keys from in-memory index tree' (duration: 269.226079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:47.468557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074483204s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:42:47.468612Z","caller":"traceutil/trace.go:171","msg":"trace[1574160047] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"1.074530374s","start":"2026-05-05T12:42:46.394058Z","end":"2026-05-05T12:42:47.468588Z","steps":["trace[1574160047] 'range keys from in-memory index tree' (duration: 1.074421243s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:47.468648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:46.394008Z","time spent":"1.074629636s","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T12:42:47.476382Z","caller":"traceutil/trace.go:171","msg":"trace[1326080481] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"397.937398ms","start":"2026-05-05T12:42:47.078386Z","end":"2026-05-05T12:42:47.476323Z","steps":["trace[1326080481] 'read index received' (duration: 393.226501ms)","trace[1326080481] 'applied index is now lower than readState.Index' (duration: 4.709377ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:42:47.47681Z","caller":"traceutil/trace.go:171","msg":"trace[812909841] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"803.25079ms","start":"2026-05-05T12:42:46.673539Z","end":"2026-05-05T12:42:47.47679Z","steps":["trace[812909841] 'process raft request' (duration: 798.829067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:47.476962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:46.673522Z","time spent":"803.341771ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036189,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:42:47.47731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.916891ms","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-05-05T12:42:47.477377Z","caller":"traceutil/trace.go:171","msg":"trace[521967623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"399.008031ms","start":"2026-05-05T12:42:47.078337Z","end":"2026-05-05T12:42:47.477345Z","steps":["trace[521967623] 'agreement among raft nodes before linearized reading' (duration: 398.900329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:47.477419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:47.078314Z","time spent":"399.093112ms","remote":"127.0.0.1:41798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T12:42:48.249386Z","caller":"traceutil/trace.go:171","msg":"trace[565670389] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"316.086766ms","start":"2026-05-05T12:42:47.933275Z","end":"2026-05-05T12:42:48.249361Z","steps":["trace[565670389] 'process raft request' (duration: 315.506063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:48.249631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:47.933249Z","time spent":"316.21854ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036221,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:42:49.276646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.594367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T12:42:49.276762Z","caller":"traceutil/trace.go:171","msg":"trace[1561829061] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"973.604049ms","start":"2026-05-05T12:42:48.303139Z","end":"2026-05-05T12:42:49.276743Z","steps":["trace[1561829061] 'process raft request' (duration: 494.834625ms)","trace[1561829061] 'compare' (duration: 478.318876ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:42:49.276815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:48.303125Z","time spent":"973.668402ms","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T12:42:50.815301Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278611386451489,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T12:42:51.316234Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278611386451489,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T12:42:51.824885Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278611386451489,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T12:42:52.325058Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278611386451489,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T12:42:52.563749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.17754719s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:42:52.563826Z","caller":"traceutil/trace.go:171","msg":"trace[2117033237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"3.177666705s","start":"2026-05-05T12:42:49.386139Z","end":"2026-05-05T12:42:52.563805Z","steps":["trace[2117033237] 'range keys from in-memory index tree' (duration: 3.177419785s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:52.563863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:49.386116Z","time spent":"3.177739297s","remote":"127.0.0.1:41526","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-05-05T12:42:52.563907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.068717279s","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-05-05T12:42:52.563969Z","caller":"traceutil/trace.go:171","msg":"trace[199478096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"3.068822794s","start":"2026-05-05T12:42:49.495129Z","end":"2026-05-05T12:42:52.563952Z","steps":["trace[199478096] 'range keys from in-memory index tree' (duration: 3.068567193s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:52.564014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:49.495111Z","time spent":"3.068894736s","remote":"127.0.0.1:41798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T12:42:52.564129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.068284032s","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-05-05T12:42:52.56415Z","caller":"traceutil/trace.go:171","msg":"trace[909202570] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"3.068324103s","start":"2026-05-05T12:42:49.495819Z","end":"2026-05-05T12:42:52.564143Z","steps":["trace[909202570] 'range keys from in-memory index tree' (duration: 3.067927558s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:52.564164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:49.49581Z","time spent":"3.068350055s","remote":"127.0.0.1:41798","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-05-05T12:42:52.56422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.067488941s","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-05-05T12:42:52.564244Z","caller":"traceutil/trace.go:171","msg":"trace[1896225947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:629; }","duration":"3.067523131s","start":"2026-05-05T12:42:49.496711Z","end":"2026-05-05T12:42:52.564234Z","steps":["trace[1896225947] 'range keys from in-memory index tree' (duration: 3.067026922s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:52.564265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:49.496704Z","time spent":"3.067555082s","remote":"127.0.0.1:41798","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-05-05T12:42:52.582009Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.85493774s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T12:42:52.583303Z","caller":"traceutil/trace.go:171","msg":"trace[428136824] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"2.857016161s","start":"2026-05-05T12:42:49.726224Z","end":"2026-05-05T12:42:52.58324Z","steps":["trace[428136824] 'process raft request' (duration: 2.856344185s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:52.583456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:49.725873Z","time spent":"2.85750603s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:42:52.62292Z","caller":"traceutil/trace.go:171","msg":"trace[1301188734] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"1.534956478s","start":"2026-05-05T12:42:51.087944Z","end":"2026-05-05T12:42:52.6229Z","steps":["trace[1301188734] 'process raft request' (duration: 1.534824123s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:52.623053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:51.087919Z","time spent":"1.535068132s","remote":"127.0.0.1:41798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:42:52.623394Z","caller":"traceutil/trace.go:171","msg":"trace[893698322] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:653; }","duration":"2.308914737s","start":"2026-05-05T12:42:50.314458Z","end":"2026-05-05T12:42:52.623373Z","steps":["trace[893698322] 'read index received' (duration: 2.26789114s)","trace[893698322] 'applied index is now lower than readState.Index' (duration: 41.022917ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:42:52.623638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.237296969s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:42:52.623701Z","caller":"traceutil/trace.go:171","msg":"trace[538929011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"1.237396883s","start":"2026-05-05T12:42:51.38629Z","end":"2026-05-05T12:42:52.623687Z","steps":["trace[538929011] 'agreement among raft nodes before linearized reading' (duration: 1.237300979s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:52.623729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:51.386276Z","time spent":"1.237447984s","remote":"127.0.0.1:41536","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-05-05T12:42:52.623751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.309287402s","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-05-05T12:42:52.623789Z","caller":"traceutil/trace.go:171","msg":"trace[673082509] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"2.309364185s","start":"2026-05-05T12:42:50.314413Z","end":"2026-05-05T12:42:52.623777Z","steps":["trace[673082509] 'agreement among raft nodes before linearized reading' (duration: 2.309243131s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:52.623818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:42:50.314394Z","time spent":"2.309417248s","remote":"127.0.0.1:41798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T12:42:54.635536Z","caller":"traceutil/trace.go:171","msg":"trace[2066602246] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"111.730932ms","start":"2026-05-05T12:42:54.523778Z","end":"2026-05-05T12:42:54.635508Z","steps":["trace[2066602246] 'process raft request' (duration: 111.547226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:42:57.247594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.617549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-05-05T12:42:57.247696Z","caller":"traceutil/trace.go:171","msg":"trace[1747817422] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:714; }","duration":"203.779645ms","start":"2026-05-05T12:42:57.043884Z","end":"2026-05-05T12:42:57.247664Z","steps":["trace[1747817422] 'range keys from in-memory index tree' (duration: 203.34763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:43:08.88706Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278611386451806,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T12:43:09.330008Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.402377751s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T12:43:09.330482Z","caller":"traceutil/trace.go:171","msg":"trace[967792568] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"943.925312ms","start":"2026-05-05T12:43:08.386536Z","end":"2026-05-05T12:43:09.330461Z","steps":["trace[967792568] 'read index received' (duration: 943.583862ms)","trace[967792568] 'applied index is now lower than readState.Index' (duration: 340.59µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:43:09.330719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.123959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:43:09.330834Z","caller":"traceutil/trace.go:171","msg":"trace[842506856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"944.310915ms","start":"2026-05-05T12:43:08.386502Z","end":"2026-05-05T12:43:09.330813Z","steps":["trace[842506856] 'agreement among raft nodes before linearized reading' (duration: 944.074047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:43:09.330915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:43:08.386485Z","time spent":"944.418817ms","remote":"127.0.0.1:41526","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-05-05T12:43:09.33094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.343899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-05T12:43:09.331055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.894746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-05T12:43:09.331029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.797724ms","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-05-05T12:43:09.331116Z","caller":"traceutil/trace.go:171","msg":"trace[533914922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:798; }","duration":"517.916238ms","start":"2026-05-05T12:43:08.813187Z","end":"2026-05-05T12:43:09.331103Z","steps":["trace[533914922] 'agreement among raft nodes before linearized reading' (duration: 517.591967ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:43:09.331116Z","caller":"traceutil/trace.go:171","msg":"trace[1886964326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:798; }","duration":"517.96718ms","start":"2026-05-05T12:43:08.813129Z","end":"2026-05-05T12:43:09.331096Z","steps":["trace[1886964326] 'agreement among raft nodes before linearized reading' (duration: 517.611847ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:43:09.331144Z","caller":"traceutil/trace.go:171","msg":"trace[1154237988] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"1.403620443s","start":"2026-05-05T12:43:07.927505Z","end":"2026-05-05T12:43:09.331126Z","steps":["trace[1154237988] 'process raft request' (duration: 1.402818326s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:43:09.331157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:43:08.813116Z","time spent":"518.032412ms","remote":"127.0.0.1:41798","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-05-05T12:43:09.331154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:43:08.813179Z","time spent":"517.96461ms","remote":"127.0.0.1:41798","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-05-05T12:43:09.331053Z","caller":"traceutil/trace.go:171","msg":"trace[690223430] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:798; }","duration":"526.515746ms","start":"2026-05-05T12:43:08.804516Z","end":"2026-05-05T12:43:09.331032Z","steps":["trace[690223430] 'agreement among raft nodes before linearized reading' (duration: 526.207166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:43:09.331211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:43:08.804497Z","time spent":"526.701062ms","remote":"127.0.0.1:41798","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-05-05T12:43:09.330967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.605581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-05T12:43:09.331243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:43:07.927491Z","time spent":"1.403686284s","remote":"127.0.0.1:41720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:43:09.331271Z","caller":"traceutil/trace.go:171","msg":"trace[271790932] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:798; }","duration":"518.930842ms","start":"2026-05-05T12:43:08.812332Z","end":"2026-05-05T12:43:09.331263Z","steps":["trace[271790932] 'agreement among raft nodes before linearized reading' (duration: 518.490867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:43:09.331301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:43:08.812272Z","time spent":"519.020605ms","remote":"127.0.0.1:41798","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-05-05T12:43:09.330965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.031294ms","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-05-05T12:43:09.331371Z","caller":"traceutil/trace.go:171","msg":"trace[1431011390] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:798; }","duration":"519.483999ms","start":"2026-05-05T12:43:08.811877Z","end":"2026-05-05T12:43:09.331361Z","steps":["trace[1431011390] 'agreement among raft nodes before linearized reading' (duration: 518.956921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:43:09.331396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:43:08.811861Z","time spent":"519.527771ms","remote":"127.0.0.1:41798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T12:43:09.887667Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278611386451810,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T12:43:10.177432Z","caller":"traceutil/trace.go:171","msg":"trace[281646412] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"841.663886ms","start":"2026-05-05T12:43:09.335737Z","end":"2026-05-05T12:43:10.177401Z","steps":["trace[281646412] 'process raft request' (duration: 839.714924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:43:10.177595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:43:09.335711Z","time spent":"841.79718ms","remote":"127.0.0.1:41798","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-05-05T12:43:10.179903Z","caller":"traceutil/trace.go:171","msg":"trace[51729838] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"843.757651ms","start":"2026-05-05T12:43:09.336113Z","end":"2026-05-05T12:43:10.17987Z","steps":["trace[51729838] 'process raft request' (duration: 843.539454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:43:10.180187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:43:09.336093Z","time spent":"843.902787ms","remote":"127.0.0.1:41798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:43:10.180259Z","caller":"traceutil/trace.go:171","msg":"trace[1389398026] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"843.777352ms","start":"2026-05-05T12:43:09.336461Z","end":"2026-05-05T12:43:10.180239Z","steps":["trace[1389398026] 'process raft request' (duration: 843.348448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:43:10.180346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:43:09.33645Z","time spent":"843.852425ms","remote":"127.0.0.1:41798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:43:10.180586Z","caller":"traceutil/trace.go:171","msg":"trace[1015883690] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:828; }","duration":"793.954077ms","start":"2026-05-05T12:43:09.386617Z","end":"2026-05-05T12:43:10.180571Z","steps":["trace[1015883690] 'read index received' (duration: 788.834635ms)","trace[1015883690] 'applied index is now lower than readState.Index' (duration: 5.118592ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:43:10.180592Z","caller":"traceutil/trace.go:171","msg":"trace[533390511] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"843.941837ms","start":"2026-05-05T12:43:09.336615Z","end":"2026-05-05T12:43:10.180557Z","steps":["trace[533390511] 'process raft request' (duration: 843.550975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:43:10.18069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.077111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:43:10.180726Z","caller":"traceutil/trace.go:171","msg":"trace[1422030046] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"794.141683ms","start":"2026-05-05T12:43:09.386572Z","end":"2026-05-05T12:43:10.180714Z","steps":["trace[1422030046] 'agreement among raft nodes before linearized reading' (duration: 794.04613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:43:10.180725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:43:09.336608Z","time spent":"844.044841ms","remote":"127.0.0.1:41798","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-05-05T12:43:10.180755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:43:09.386555Z","time spent":"794.193984ms","remote":"127.0.0.1:41526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T12:43:10.180943Z","caller":"traceutil/trace.go:171","msg":"trace[802395673] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"111.031019ms","start":"2026-05-05T12:43:10.069901Z","end":"2026-05-05T12:43:10.180932Z","steps":["trace[802395673] 'process raft request' (duration: 110.611946ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:43:10.181003Z","caller":"traceutil/trace.go:171","msg":"trace[52496217] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"843.645957ms","start":"2026-05-05T12:43:09.337346Z","end":"2026-05-05T12:43:10.180992Z","steps":["trace[52496217] 'process raft request' (duration: 843.021957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:43:10.181069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:43:09.337337Z","time spent":"843.695109ms","remote":"127.0.0.1:41798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:43:11.472332Z","caller":"traceutil/trace.go:171","msg":"trace[506132643] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:837; }","duration":"101.834164ms","start":"2026-05-05T12:43:11.370479Z","end":"2026-05-05T12:43:11.472313Z","steps":["trace[506132643] 'read index received' (duration: 101.686359ms)","trace[506132643] 'applied index is now lower than readState.Index' (duration: 147.055µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:43:11.472605Z","caller":"traceutil/trace.go:171","msg":"trace[778558776] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"125.2586ms","start":"2026-05-05T12:43:11.347339Z","end":"2026-05-05T12:43:11.472597Z","steps":["trace[778558776] 'process raft request' (duration: 124.872048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:43:11.472757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.256858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:43:11.472784Z","caller":"traceutil/trace.go:171","msg":"trace[1026307260] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:807; }","duration":"102.31216ms","start":"2026-05-05T12:43:11.370465Z","end":"2026-05-05T12:43:11.472777Z","steps":["trace[1026307260] 'agreement among raft nodes before linearized reading' (duration: 102.254998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:43:11.472894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.370581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:43:11.472916Z","caller":"traceutil/trace.go:171","msg":"trace[1185540282] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:807; }","duration":"102.405491ms","start":"2026-05-05T12:43:11.370505Z","end":"2026-05-05T12:43:11.472911Z","steps":["trace[1185540282] 'agreement among raft nodes before linearized reading' (duration: 102.36991ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:43:34.469839Z","caller":"traceutil/trace.go:171","msg":"trace[173198341] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"100.479371ms","start":"2026-05-05T12:43:34.369332Z","end":"2026-05-05T12:43:34.469811Z","steps":["trace[173198341] 'process raft request' (duration: 100.305907ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:43:54.668481Z","caller":"traceutil/trace.go:171","msg":"trace[872571393] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"100.18425ms","start":"2026-05-05T12:43:54.568265Z","end":"2026-05-05T12:43:54.66845Z","steps":["trace[872571393] 'process raft request' (duration: 99.72262ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:44:03.600983Z","caller":"traceutil/trace.go:171","msg":"trace[1903842056] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"192.841288ms","start":"2026-05-05T12:44:03.408118Z","end":"2026-05-05T12:44:03.60096Z","steps":["trace[1903842056] 'process raft request' (duration: 192.700525ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:44:19.134507Z","caller":"traceutil/trace.go:171","msg":"trace[122771479] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"346.504925ms","start":"2026-05-05T12:44:18.787964Z","end":"2026-05-05T12:44:19.134469Z","steps":["trace[122771479] 'process raft request' (duration: 346.311301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:44:19.134723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:44:18.787944Z","time spent":"346.667217ms","remote":"127.0.0.1:41798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:44:19.16934Z","caller":"traceutil/trace.go:171","msg":"trace[1349266171] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"380.677898ms","start":"2026-05-05T12:44:18.788623Z","end":"2026-05-05T12:44:19.169301Z","steps":["trace[1349266171] 'process raft request' (duration: 380.440384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:44:19.169583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:44:18.788609Z","time spent":"380.838842ms","remote":"127.0.0.1:41798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:44:19.169606Z","caller":"traceutil/trace.go:171","msg":"trace[168089317] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"380.880353ms","start":"2026-05-05T12:44:18.788699Z","end":"2026-05-05T12:44:19.169579Z","steps":["trace[168089317] 'process raft request' (duration: 380.544726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:44:19.169754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:44:18.788691Z","time spent":"380.971995ms","remote":"127.0.0.1:41798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:44:35.425771Z","caller":"traceutil/trace.go:171","msg":"trace[5166262] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"139.283712ms","start":"2026-05-05T12:44:35.286381Z","end":"2026-05-05T12:44:35.425664Z","steps":["trace[5166262] 'process raft request' (duration: 85.035939ms)","trace[5166262] 'compare' (duration: 53.892336ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:44:41.000212Z","caller":"traceutil/trace.go:171","msg":"trace[967722935] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"111.140712ms","start":"2026-05-05T12:44:40.889042Z","end":"2026-05-05T12:44:41.000183Z","steps":["trace[967722935] 'process raft request' (duration: 110.921848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:44:43.236965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.843696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-05T12:44:43.237079Z","caller":"traceutil/trace.go:171","msg":"trace[565003103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1235; }","duration":"114.997459ms","start":"2026-05-05T12:44:43.122053Z","end":"2026-05-05T12:44:43.237051Z","steps":["trace[565003103] 'range keys from in-memory index tree' (duration: 114.647263ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:44:44.073944Z","caller":"traceutil/trace.go:171","msg":"trace[1404094796] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"193.217051ms","start":"2026-05-05T12:44:43.880697Z","end":"2026-05-05T12:44:44.073914Z","steps":["trace[1404094796] 'process raft request' (duration: 193.032708ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:44:45.551837Z","caller":"traceutil/trace.go:171","msg":"trace[478558773] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1293; }","duration":"100.891661ms","start":"2026-05-05T12:44:45.450917Z","end":"2026-05-05T12:44:45.551808Z","steps":["trace[478558773] 'read index received' (duration: 92.863265ms)","trace[478558773] 'applied index is now lower than readState.Index' (duration: 8.027036ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:44:45.55186Z","caller":"traceutil/trace.go:171","msg":"trace[300496092] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"114.674417ms","start":"2026-05-05T12:44:45.437153Z","end":"2026-05-05T12:44:45.551828Z","steps":["trace[300496092] 'process raft request' (duration: 106.668091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:44:45.552221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.276287ms","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-05-05T12:44:45.552341Z","caller":"traceutil/trace.go:171","msg":"trace[157789552] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1244; }","duration":"101.432079ms","start":"2026-05-05T12:44:45.450876Z","end":"2026-05-05T12:44:45.552308Z","steps":["trace[157789552] 'agreement among raft nodes before linearized reading' (duration: 101.057943ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:44:45.718268Z","caller":"traceutil/trace.go:171","msg":"trace[1558770509] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"162.193442ms","start":"2026-05-05T12:44:45.556045Z","end":"2026-05-05T12:44:45.718239Z","steps":["trace[1558770509] 'process raft request' (duration: 103.964799ms)","trace[1558770509] 'compare' (duration: 58.104191ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:44:45.730208Z","caller":"traceutil/trace.go:171","msg":"trace[945390853] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1294; }","duration":"173.53454ms","start":"2026-05-05T12:44:45.55665Z","end":"2026-05-05T12:44:45.730184Z","steps":["trace[945390853] 'read index received' (duration: 103.388631ms)","trace[945390853] 'applied index is now lower than readState.Index' (duration: 70.142738ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:44:45.730595Z","caller":"traceutil/trace.go:171","msg":"trace[532077253] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"174.325452ms","start":"2026-05-05T12:44:45.556255Z","end":"2026-05-05T12:44:45.730581Z","steps":["trace[532077253] 'process raft request' (duration: 173.767383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:44:45.730919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.24973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-05T12:44:45.731511Z","caller":"traceutil/trace.go:171","msg":"trace[1625985843] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1246; }","duration":"174.844319ms","start":"2026-05-05T12:44:45.556629Z","end":"2026-05-05T12:44:45.731473Z","steps":["trace[1625985843] 'agreement among raft nodes before linearized reading' (duration: 174.23166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:44:45.732018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.034756ms","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-05-05T12:44:45.732937Z","caller":"traceutil/trace.go:171","msg":"trace[1401318413] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1246; }","duration":"163.96914ms","start":"2026-05-05T12:44:45.568951Z","end":"2026-05-05T12:44:45.73292Z","steps":["trace[1401318413] 'agreement among raft nodes before linearized reading' (duration: 162.965574ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:45:05.165127Z","caller":"traceutil/trace.go:171","msg":"trace[1516091040] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"213.221707ms","start":"2026-05-05T12:45:04.951877Z","end":"2026-05-05T12:45:05.165099Z","steps":["trace[1516091040] 'process raft request' (duration: 213.047915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:45:06.273617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.324259ms","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-05-05T12:45:06.273682Z","caller":"traceutil/trace.go:171","msg":"trace[1858328137] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1347; }","duration":"459.442961ms","start":"2026-05-05T12:45:05.814219Z","end":"2026-05-05T12:45:06.273662Z","steps":["trace[1858328137] 'range keys from in-memory index tree' (duration: 459.069945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:45:06.273709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:45:05.814193Z","time spent":"459.509961ms","remote":"127.0.0.1:41798","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-05-05T12:45:06.273674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.284879ms","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-05-05T12:45:06.273759Z","caller":"traceutil/trace.go:171","msg":"trace[1999179123] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1347; }","duration":"423.404281ms","start":"2026-05-05T12:45:05.850337Z","end":"2026-05-05T12:45:06.273741Z","steps":["trace[1999179123] 'range keys from in-memory index tree' (duration: 423.099136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:45:06.273799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:45:05.850283Z","time spent":"423.507272ms","remote":"127.0.0.1:41798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T12:45:06.273923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.477397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:45:06.273991Z","caller":"traceutil/trace.go:171","msg":"trace[1767828380] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1347; }","duration":"126.581278ms","start":"2026-05-05T12:45:06.147391Z","end":"2026-05-05T12:45:06.273972Z","steps":["trace[1767828380] 'count revisions from in-memory index tree' (duration: 126.411806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:45:06.274123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.234972ms","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-05-05T12:45:06.274166Z","caller":"traceutil/trace.go:171","msg":"trace[520426256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1347; }","duration":"716.405905ms","start":"2026-05-05T12:45:05.55775Z","end":"2026-05-05T12:45:06.274155Z","steps":["trace[520426256] 'range keys from in-memory index tree' (duration: 715.39218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:45:06.274205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:45:05.557718Z","time spent":"716.476436ms","remote":"127.0.0.1:41798","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-05-05T12:45:06.274165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.746739ms","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-05-05T12:45:06.274382Z","caller":"traceutil/trace.go:171","msg":"trace[1421838400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1347; }","duration":"426.947863ms","start":"2026-05-05T12:45:05.84736Z","end":"2026-05-05T12:45:06.274307Z","steps":["trace[1421838400] 'range keys from in-memory index tree' (duration: 426.393933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:45:06.274552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:45:05.847344Z","time spent":"427.193406ms","remote":"127.0.0.1:41798","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\" "}