{"level":"warn","ts":"2026-04-07T01:19:03.099041Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T01:19:03.099124Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.62:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.62:2380","--initial-cluster=instance=https://162.253.55.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.62: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-04-07T01:19:03.09919Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T01:19:03.099204Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-04-07T01:19:03.099218Z","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-04-07T01:19:03.099842Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"]} {"level":"info","ts":"2026-04-07T01:19:03.09993Z","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://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.62: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-04-07T01:19:03.105409Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.217881ms"} {"level":"info","ts":"2026-04-07T01:19:03.117376Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"791a7e25b533cd26","cluster-id":"be484d2748da72cd"} {"level":"info","ts":"2026-04-07T01:19:03.117532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T01:19:03.117567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 0"} {"level":"info","ts":"2026-04-07T01:19:03.117589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 791a7e25b533cd26 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T01:19:03.11761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 1"} {"level":"info","ts":"2026-04-07T01:19:03.117671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"warn","ts":"2026-04-07T01:19:03.127511Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T01:19:03.128955Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T01:19:03.130999Z","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-04-07T01:19:03.13307Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"791a7e25b533cd26","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T01:19:03.134868Z","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-04-07T01:19:03.135042Z","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-04-07T01:19:03.135057Z","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-04-07T01:19:03.13336Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"791a7e25b533cd26","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T01:19:03.136286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"info","ts":"2026-04-07T01:19:03.136421Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","added-peer-id":"791a7e25b533cd26","added-peer-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-04-07T01:19:03.138573Z","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-04-07T01:19:03.13873Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-04-07T01:19:03.138913Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-04-07T01:19:03.138996Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T01:19:03.13897Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"791a7e25b533cd26","initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T01:19:03.719957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T01:19:03.720027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T01:19:03.720075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgPreVoteResp from 791a7e25b533cd26 at term 1"} {"level":"info","ts":"2026-04-07T01:19:03.720094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became candidate at term 2"} {"level":"info","ts":"2026-04-07T01:19:03.720103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgVoteResp from 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-04-07T01:19:03.720169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became leader at term 2"} {"level":"info","ts":"2026-04-07T01:19:03.720191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 791a7e25b533cd26 elected leader 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-04-07T01:19:03.867109Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"791a7e25b533cd26","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.62:2379]}","request-path":"/0/members/791a7e25b533cd26/attributes","cluster-id":"be484d2748da72cd","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T01:19:03.867262Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T01:19:03.867409Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:19:03.867447Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T01:19:03.867706Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T01:19:03.867722Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T01:19:03.870811Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:19:03.870826Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.62:2379"} {"level":"info","ts":"2026-04-07T01:19:03.871182Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:19:03.871321Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:19:03.873115Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T01:19:07.129778Z","caller":"traceutil/trace.go:171","msg":"trace[1911549192] transaction","detail":"{read_only:false; response_revision:164; number_of_response:1; }","duration":"123.00706ms","start":"2026-04-07T01:19:07.006736Z","end":"2026-04-07T01:19:07.129743Z","steps":["trace[1911549192] 'process raft request' (duration: 47.194276ms)","trace[1911549192] 'compare' (duration: 75.639624ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:19:07.362081Z","caller":"traceutil/trace.go:171","msg":"trace[422493879] transaction","detail":"{read_only:false; response_revision:169; number_of_response:1; }","duration":"134.226742ms","start":"2026-04-07T01:19:07.227821Z","end":"2026-04-07T01:19:07.362048Z","steps":["trace[422493879] 'process raft request' (duration: 54.775847ms)","trace[422493879] 'compare' (duration: 79.153557ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:19:08.265921Z","caller":"traceutil/trace.go:171","msg":"trace[1383201426] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"152.433975ms","start":"2026-04-07T01:19:08.11345Z","end":"2026-04-07T01:19:08.265884Z","steps":["trace[1383201426] 'process raft request' (duration: 85.121685ms)","trace[1383201426] 'compare' (duration: 67.099007ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:19:08.448491Z","caller":"traceutil/trace.go:171","msg":"trace[363728822] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"117.123085ms","start":"2026-04-07T01:19:08.331339Z","end":"2026-04-07T01:19:08.448462Z","steps":["trace[363728822] 'process raft request' (duration: 117.02999ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:08.448542Z","caller":"traceutil/trace.go:171","msg":"trace[1289810362] linearizableReadLoop","detail":"{readStateIndex:230; appliedIndex:229; }","duration":"119.375232ms","start":"2026-04-07T01:19:08.329113Z","end":"2026-04-07T01:19:08.448488Z","steps":["trace[1289810362] 'read index received' (duration: 64.058452ms)","trace[1289810362] 'applied index is now lower than readState.Index' (duration: 55.308869ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:19:08.448887Z","caller":"traceutil/trace.go:171","msg":"trace[686683147] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"120.116737ms","start":"2026-04-07T01:19:08.328751Z","end":"2026-04-07T01:19:08.448868Z","steps":["trace[686683147] 'process raft request' (duration: 64.400162ms)","trace[686683147] 'compare' (duration: 55.011592ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:19:08.44914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.465336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T01:19:08.449242Z","caller":"traceutil/trace.go:171","msg":"trace[1658412285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:226; }","duration":"118.588204ms","start":"2026-04-07T01:19:08.330638Z","end":"2026-04-07T01:19:08.449226Z","steps":["trace[1658412285] 'agreement among raft nodes before linearized reading' (duration: 118.389001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:08.449455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.311279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:19:08.44964Z","caller":"traceutil/trace.go:171","msg":"trace[1989645696] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:0; response_revision:226; }","duration":"120.464018ms","start":"2026-04-07T01:19:08.329074Z","end":"2026-04-07T01:19:08.449538Z","steps":["trace[1989645696] 'agreement among raft nodes before linearized reading' (duration: 120.259616ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:08.651994Z","caller":"traceutil/trace.go:171","msg":"trace[1956679032] linearizableReadLoop","detail":"{readStateIndex:237; appliedIndex:236; }","duration":"117.741392ms","start":"2026-04-07T01:19:08.534221Z","end":"2026-04-07T01:19:08.651963Z","steps":["trace[1956679032] 'read index received' (duration: 30.577003ms)","trace[1956679032] 'applied index is now lower than readState.Index' (duration: 87.162589ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:19:08.652065Z","caller":"traceutil/trace.go:171","msg":"trace[880872074] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"158.233458ms","start":"2026-04-07T01:19:08.493775Z","end":"2026-04-07T01:19:08.652009Z","steps":["trace[880872074] 'process raft request' (duration: 70.990874ms)","trace[880872074] 'compare' (duration: 86.977368ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:19:08.652258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.194338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T01:19:08.652345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.965311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:19:08.652377Z","caller":"traceutil/trace.go:171","msg":"trace[1220428560] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:0; response_revision:232; }","duration":"116.363448ms","start":"2026-04-07T01:19:08.535989Z","end":"2026-04-07T01:19:08.652353Z","steps":["trace[1220428560] 'agreement among raft nodes before linearized reading' (duration: 116.196048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:08.652262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.011678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:19:08.65242Z","caller":"traceutil/trace.go:171","msg":"trace[1703141286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:232; }","duration":"114.042156ms","start":"2026-04-07T01:19:08.538349Z","end":"2026-04-07T01:19:08.652391Z","steps":["trace[1703141286] 'agreement among raft nodes before linearized reading' (duration: 113.964661ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:08.652652Z","caller":"traceutil/trace.go:171","msg":"trace[906557260] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:0; response_revision:232; }","duration":"118.401743ms","start":"2026-04-07T01:19:08.534156Z","end":"2026-04-07T01:19:08.652558Z","steps":["trace[906557260] 'agreement among raft nodes before linearized reading' (duration: 117.968506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:08.903616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.844652ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:19:08.904477Z","caller":"traceutil/trace.go:171","msg":"trace[242887978] linearizableReadLoop","detail":"{readStateIndex:246; appliedIndex:244; }","duration":"155.919006ms","start":"2026-04-07T01:19:08.748503Z","end":"2026-04-07T01:19:08.904422Z","steps":["trace[242887978] 'read index received' (duration: 32.053912ms)","trace[242887978] 'applied index is now lower than readState.Index' (duration: 123.863374ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:19:08.905652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.136382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:19:08.905692Z","caller":"traceutil/trace.go:171","msg":"trace[1487044845] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:0; response_revision:241; }","duration":"157.225236ms","start":"2026-04-07T01:19:08.748455Z","end":"2026-04-07T01:19:08.905681Z","steps":["trace[1487044845] 'agreement among raft nodes before linearized reading' (duration: 156.097957ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:08.905921Z","caller":"traceutil/trace.go:171","msg":"trace[2108480740] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"157.992353ms","start":"2026-04-07T01:19:08.747911Z","end":"2026-04-07T01:19:08.905903Z","steps":["trace[2108480740] 'process raft request' (duration: 32.699091ms)","trace[2108480740] 'compare' (duration: 120.623747ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:19:08.906071Z","caller":"traceutil/trace.go:171","msg":"trace[1081314654] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"158.097089ms","start":"2026-04-07T01:19:08.747957Z","end":"2026-04-07T01:19:08.906054Z","steps":["trace[1081314654] 'process raft request' (duration: 156.244686ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:08.906315Z","caller":"traceutil/trace.go:171","msg":"trace[2094923362] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"156.059826ms","start":"2026-04-07T01:19:08.750208Z","end":"2026-04-07T01:19:08.906267Z","steps":["trace[2094923362] 'process raft request' (duration: 154.137549ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:09.085412Z","caller":"traceutil/trace.go:171","msg":"trace[2108789692] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"174.754889ms","start":"2026-04-07T01:19:08.91062Z","end":"2026-04-07T01:19:09.085375Z","steps":["trace[2108789692] 'process raft request' (duration: 91.972291ms)","trace[2108789692] 'compare' (duration: 82.474379ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:19:09.085481Z","caller":"traceutil/trace.go:171","msg":"trace[442035090] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"172.30225ms","start":"2026-04-07T01:19:08.913143Z","end":"2026-04-07T01:19:09.085445Z","steps":["trace[442035090] 'process raft request' (duration: 172.238036ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:09.085722Z","caller":"traceutil/trace.go:171","msg":"trace[1236162681] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"173.183613ms","start":"2026-04-07T01:19:08.912518Z","end":"2026-04-07T01:19:09.085702Z","steps":["trace[1236162681] 'process raft request' (duration: 172.819931ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:09.08582Z","caller":"traceutil/trace.go:171","msg":"trace[2006149087] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"173.261718ms","start":"2026-04-07T01:19:08.912522Z","end":"2026-04-07T01:19:09.085784Z","steps":["trace[2006149087] 'process raft request' (duration: 172.759518ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:11.169872Z","caller":"traceutil/trace.go:171","msg":"trace[1634069134] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"118.486688ms","start":"2026-04-07T01:19:11.05135Z","end":"2026-04-07T01:19:11.169837Z","steps":["trace[1634069134] 'process raft request' (duration: 103.322347ms)","trace[1634069134] 'compare' (duration: 14.949418ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:19:22.080237Z","caller":"traceutil/trace.go:171","msg":"trace[460020990] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"111.639703ms","start":"2026-04-07T01:19:21.968565Z","end":"2026-04-07T01:19:22.080204Z","steps":["trace[460020990] 'process raft request' (duration: 78.814317ms)","trace[460020990] 'compare' (duration: 32.664095ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:19:28.815199Z","caller":"traceutil/trace.go:171","msg":"trace[1978097839] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:478; }","duration":"220.319059ms","start":"2026-04-07T01:19:28.594865Z","end":"2026-04-07T01:19:28.815184Z","steps":["trace[1978097839] 'read index received' (duration: 220.1696ms)","trace[1978097839] 'applied index is now lower than readState.Index' (duration: 148.799µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:19:28.815341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.469728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T01:19:28.815362Z","caller":"traceutil/trace.go:171","msg":"trace[909239797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:463; }","duration":"220.530183ms","start":"2026-04-07T01:19:28.594823Z","end":"2026-04-07T01:19:28.815353Z","steps":["trace[909239797] 'agreement among raft nodes before linearized reading' (duration: 220.424256ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:28.815365Z","caller":"traceutil/trace.go:171","msg":"trace[1111488174] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"269.770793ms","start":"2026-04-07T01:19:28.545558Z","end":"2026-04-07T01:19:28.815329Z","steps":["trace[1111488174] 'process raft request' (duration: 269.532158ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:35.155452Z","caller":"traceutil/trace.go:171","msg":"trace[1625711800] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"281.119161ms","start":"2026-04-07T01:19:34.874292Z","end":"2026-04-07T01:19:35.155411Z","steps":["trace[1625711800] 'process raft request' (duration: 280.761063ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:35.184208Z","caller":"traceutil/trace.go:171","msg":"trace[1639571621] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"309.277636ms","start":"2026-04-07T01:19:34.874892Z","end":"2026-04-07T01:19:35.18417Z","steps":["trace[1639571621] 'process raft request' (duration: 303.484212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:35.184685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:19:34.874866Z","time spent":"309.449433ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:19:36.040643Z","caller":"traceutil/trace.go:171","msg":"trace[1149420277] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:534; }","duration":"162.910626ms","start":"2026-04-07T01:19:35.8777Z","end":"2026-04-07T01:19:36.04061Z","steps":["trace[1149420277] 'read index received' (duration: 69.641937ms)","trace[1149420277] 'applied index is now lower than readState.Index' (duration: 93.265739ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:19:36.040831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.098129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T01:19:36.040876Z","caller":"traceutil/trace.go:171","msg":"trace[1052401445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:528; }","duration":"163.214786ms","start":"2026-04-07T01:19:35.87765Z","end":"2026-04-07T01:19:36.040864Z","steps":["trace[1052401445] 'agreement among raft nodes before linearized reading' (duration: 163.08738ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:36.040941Z","caller":"traceutil/trace.go:171","msg":"trace[1130013705] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"157.232228ms","start":"2026-04-07T01:19:35.883655Z","end":"2026-04-07T01:19:36.040887Z","steps":["trace[1130013705] 'process raft request' (duration: 156.482262ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:36.041164Z","caller":"traceutil/trace.go:171","msg":"trace[1960673273] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"156.367176ms","start":"2026-04-07T01:19:35.884784Z","end":"2026-04-07T01:19:36.041151Z","steps":["trace[1960673273] 'process raft request' (duration: 155.392616ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:36.04119Z","caller":"traceutil/trace.go:171","msg":"trace[1368028551] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"157.671444ms","start":"2026-04-07T01:19:35.883508Z","end":"2026-04-07T01:19:36.041179Z","steps":["trace[1368028551] 'process raft request' (duration: 156.581118ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:36.041202Z","caller":"traceutil/trace.go:171","msg":"trace[882930478] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"164.68039ms","start":"2026-04-07T01:19:35.876506Z","end":"2026-04-07T01:19:36.041187Z","steps":["trace[882930478] 'process raft request' (duration: 163.254483ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:36.04129Z","caller":"traceutil/trace.go:171","msg":"trace[1199277089] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"156.394226ms","start":"2026-04-07T01:19:35.884886Z","end":"2026-04-07T01:19:36.04128Z","steps":["trace[1199277089] 'process raft request' (duration: 155.353638ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:36.04138Z","caller":"traceutil/trace.go:171","msg":"trace[581079850] transaction","detail":"{read_only:false; number_of_response:1; response_revision:524; }","duration":"151.25613ms","start":"2026-04-07T01:19:35.890113Z","end":"2026-04-07T01:19:36.04137Z","steps":["trace[581079850] 'process raft request' (duration: 150.211933ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:36.041421Z","caller":"traceutil/trace.go:171","msg":"trace[1807104582] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"163.536236ms","start":"2026-04-07T01:19:35.877877Z","end":"2026-04-07T01:19:36.041413Z","steps":["trace[1807104582] 'process raft request' (duration: 162.12136ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:36.041497Z","caller":"traceutil/trace.go:171","msg":"trace[852441023] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"144.473688ms","start":"2026-04-07T01:19:35.897012Z","end":"2026-04-07T01:19:36.041486Z","steps":["trace[852441023] 'process raft request' (duration: 143.549126ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:36.041534Z","caller":"traceutil/trace.go:171","msg":"trace[938037265] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"151.27214ms","start":"2026-04-07T01:19:35.890256Z","end":"2026-04-07T01:19:36.041528Z","steps":["trace[938037265] 'process raft request' (duration: 150.115666ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:36.041592Z","caller":"traceutil/trace.go:171","msg":"trace[1712311956] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"148.276946ms","start":"2026-04-07T01:19:35.89331Z","end":"2026-04-07T01:19:36.041587Z","steps":["trace[1712311956] 'process raft request' (duration: 147.140622ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:36.041503Z","caller":"traceutil/trace.go:171","msg":"trace[656687397] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"145.94726ms","start":"2026-04-07T01:19:35.895548Z","end":"2026-04-07T01:19:36.041496Z","steps":["trace[656687397] 'process raft request' (duration: 144.960161ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:36.041409Z","caller":"traceutil/trace.go:171","msg":"trace[566392383] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"158.207867ms","start":"2026-04-07T01:19:35.882842Z","end":"2026-04-07T01:19:36.041049Z","steps":["trace[566392383] 'process raft request' (duration: 157.202668ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:37.441213Z","caller":"traceutil/trace.go:171","msg":"trace[114547680] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"937.9025ms","start":"2026-04-07T01:19:36.50328Z","end":"2026-04-07T01:19:37.441182Z","steps":["trace[114547680] 'process raft request' (duration: 928.111457ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:37.441531Z","caller":"traceutil/trace.go:171","msg":"trace[1093806125] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:588; }","duration":"281.859455ms","start":"2026-04-07T01:19:37.159621Z","end":"2026-04-07T01:19:37.441481Z","steps":["trace[1093806125] 'read index received' (duration: 271.371183ms)","trace[1093806125] 'applied index is now lower than readState.Index' (duration: 10.486813ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:19:37.44162Z","caller":"traceutil/trace.go:171","msg":"trace[2056567374] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"879.090587ms","start":"2026-04-07T01:19:36.562461Z","end":"2026-04-07T01:19:37.441552Z","steps":["trace[2056567374] 'process raft request' (duration: 878.743338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:37.441776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.689162ms","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-04-07T01:19:37.441791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.164707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T01:19:37.441877Z","caller":"traceutil/trace.go:171","msg":"trace[1761922255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:568; }","duration":"282.300523ms","start":"2026-04-07T01:19:37.159559Z","end":"2026-04-07T01:19:37.441859Z","steps":["trace[1761922255] 'agreement among raft nodes before linearized reading' (duration: 282.06509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:37.44187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:19:36.56243Z","time spent":"879.3239ms","remote":"127.0.0.1:59976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T01:19:37.441912Z","caller":"traceutil/trace.go:171","msg":"trace[952011375] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:568; }","duration":"251.820258ms","start":"2026-04-07T01:19:37.189992Z","end":"2026-04-07T01:19:37.441812Z","steps":["trace[952011375] 'agreement among raft nodes before linearized reading' (duration: 251.698131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:37.441567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:19:36.503255Z","time spent":"938.132203ms","remote":"127.0.0.1:59976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:19:44.523359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.611702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:19:44.523454Z","caller":"traceutil/trace.go:171","msg":"trace[92314225] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:624; }","duration":"278.720129ms","start":"2026-04-07T01:19:44.24471Z","end":"2026-04-07T01:19:44.52343Z","steps":["trace[92314225] 'range keys from in-memory index tree' (duration: 278.516894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:44.523441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.17564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T01:19:44.523518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.522974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:19:44.523622Z","caller":"traceutil/trace.go:171","msg":"trace[2090071202] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:624; }","duration":"240.310578ms","start":"2026-04-07T01:19:44.283211Z","end":"2026-04-07T01:19:44.523521Z","steps":["trace[2090071202] 'count revisions from in-memory index tree' (duration: 240.144251ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:44.523638Z","caller":"traceutil/trace.go:171","msg":"trace[1442830130] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:624; }","duration":"403.693431ms","start":"2026-04-07T01:19:44.119922Z","end":"2026-04-07T01:19:44.523615Z","steps":["trace[1442830130] 'range keys from in-memory index tree' (duration: 403.452185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:44.52365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.388496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:19:44.523749Z","caller":"traceutil/trace.go:171","msg":"trace[77770066] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:624; }","duration":"240.559442ms","start":"2026-04-07T01:19:44.283166Z","end":"2026-04-07T01:19:44.523725Z","steps":["trace[77770066] 'range keys from in-memory index tree' (duration: 240.304328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:44.523754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.502433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T01:19:44.523795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.69884ms","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-04-07T01:19:44.523802Z","caller":"traceutil/trace.go:171","msg":"trace[872506601] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:624; }","duration":"261.561262ms","start":"2026-04-07T01:19:44.262225Z","end":"2026-04-07T01:19:44.523786Z","steps":["trace[872506601] 'range keys from in-memory index tree' (duration: 261.468493ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:44.523837Z","caller":"traceutil/trace.go:171","msg":"trace[1391227528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"403.748189ms","start":"2026-04-07T01:19:44.120077Z","end":"2026-04-07T01:19:44.523825Z","steps":["trace[1391227528] 'range keys from in-memory index tree' (duration: 403.605512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:44.523804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.748438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T01:19:44.523872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:19:44.120073Z","time spent":"403.789418ms","remote":"127.0.0.1:60210","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-04-07T01:19:44.523909Z","caller":"traceutil/trace.go:171","msg":"trace[1491649535] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:624; }","duration":"261.901115ms","start":"2026-04-07T01:19:44.261981Z","end":"2026-04-07T01:19:44.523882Z","steps":["trace[1491649535] 'range keys from in-memory index tree' (duration: 261.64344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:44.523958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.096602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T01:19:44.52403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.851567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T01:19:44.524049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.649357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:19:44.524078Z","caller":"traceutil/trace.go:171","msg":"trace[354793765] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:624; }","duration":"403.909845ms","start":"2026-04-07T01:19:44.120152Z","end":"2026-04-07T01:19:44.524061Z","steps":["trace[354793765] 'count revisions from in-memory index tree' (duration: 403.827987ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:44.524087Z","caller":"traceutil/trace.go:171","msg":"trace[1305489526] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:624; }","duration":"558.327826ms","start":"2026-04-07T01:19:43.96573Z","end":"2026-04-07T01:19:44.524058Z","steps":["trace[1305489526] 'range keys from in-memory index tree' (duration: 557.936235ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:44.524098Z","caller":"traceutil/trace.go:171","msg":"trace[1306413983] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:624; }","duration":"226.717866ms","start":"2026-04-07T01:19:44.297365Z","end":"2026-04-07T01:19:44.524083Z","steps":["trace[1306413983] 'range keys from in-memory index tree' (duration: 226.588138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:44.524118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:19:44.120148Z","time spent":"403.958984ms","remote":"127.0.0.1:47790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-07T01:19:44.524154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:19:43.965708Z","time spent":"558.430645ms","remote":"127.0.0.1:47776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T01:19:44.524174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.837747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:19:44.524212Z","caller":"traceutil/trace.go:171","msg":"trace[666249559] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:624; }","duration":"557.890596ms","start":"2026-04-07T01:19:43.96631Z","end":"2026-04-07T01:19:44.524201Z","steps":["trace[666249559] 'range keys from in-memory index tree' (duration: 557.774438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:44.524244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:19:43.966301Z","time spent":"557.933256ms","remote":"127.0.0.1:47776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-07T01:19:44.524308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.525162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T01:19:44.52431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.207449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T01:19:44.524025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.584392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:19:44.524369Z","caller":"traceutil/trace.go:171","msg":"trace[18108520] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:624; }","duration":"558.62277ms","start":"2026-04-07T01:19:43.96573Z","end":"2026-04-07T01:19:44.524353Z","steps":["trace[18108520] 'count revisions from in-memory index tree' (duration: 558.445715ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:44.524371Z","caller":"traceutil/trace.go:171","msg":"trace[1167962309] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:624; }","duration":"279.279378ms","start":"2026-04-07T01:19:44.245079Z","end":"2026-04-07T01:19:44.524358Z","steps":["trace[1167962309] 'range keys from in-memory index tree' (duration: 279.107091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:44.524376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.900122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T01:19:44.524389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:19:44.119906Z","time spent":"403.775148ms","remote":"127.0.0.1:47790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T01:19:44.524407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:19:43.965708Z","time spent":"558.688719ms","remote":"127.0.0.1:47776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-07T01:19:44.52441Z","caller":"traceutil/trace.go:171","msg":"trace[2130366031] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:624; }","duration":"279.987864ms","start":"2026-04-07T01:19:44.244406Z","end":"2026-04-07T01:19:44.524394Z","steps":["trace[2130366031] 'count revisions from in-memory index tree' (duration: 279.516604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:44.524425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.564718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:19:44.524465Z","caller":"traceutil/trace.go:171","msg":"trace[1310918798] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:624; }","duration":"226.837023ms","start":"2026-04-07T01:19:44.297617Z","end":"2026-04-07T01:19:44.524454Z","steps":["trace[1310918798] 'range keys from in-memory index tree' (duration: 226.490509ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:44.524426Z","caller":"traceutil/trace.go:171","msg":"trace[902627583] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:624; }","duration":"226.96505ms","start":"2026-04-07T01:19:44.297446Z","end":"2026-04-07T01:19:44.524411Z","steps":["trace[902627583] 'count revisions from in-memory index tree' (duration: 226.819883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:44.524839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.766658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:19:44.524895Z","caller":"traceutil/trace.go:171","msg":"trace[2118793671] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:624; }","duration":"262.849156ms","start":"2026-04-07T01:19:44.262034Z","end":"2026-04-07T01:19:44.524883Z","steps":["trace[2118793671] 'count revisions from in-memory index tree' (duration: 262.737948ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:44.803306Z","caller":"traceutil/trace.go:171","msg":"trace[570057049] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"369.817914ms","start":"2026-04-07T01:19:44.433456Z","end":"2026-04-07T01:19:44.803274Z","steps":["trace[570057049] 'read index received' (duration: 369.222506ms)","trace[570057049] 'applied index is now lower than readState.Index' (duration: 594.588µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:19:44.803427Z","caller":"traceutil/trace.go:171","msg":"trace[1765072583] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"497.118326ms","start":"2026-04-07T01:19:44.306289Z","end":"2026-04-07T01:19:44.803408Z","steps":["trace[1765072583] 'process raft request' (duration: 496.47034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:44.803656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.188746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-07T01:19:44.803662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:19:44.306271Z","time spent":"497.224544ms","remote":"127.0.0.1:60052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:19:44.803702Z","caller":"traceutil/trace.go:171","msg":"trace[1686920847] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:625; }","duration":"370.263935ms","start":"2026-04-07T01:19:44.433427Z","end":"2026-04-07T01:19:44.80369Z","steps":["trace[1686920847] 'agreement among raft nodes before linearized reading' (duration: 369.992821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:44.803733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:19:44.43341Z","time spent":"370.315864ms","remote":"127.0.0.1:60210","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-04-07T01:19:44.859723Z","caller":"traceutil/trace.go:171","msg":"trace[1217169814] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"329.669377ms","start":"2026-04-07T01:19:44.530015Z","end":"2026-04-07T01:19:44.859685Z","steps":["trace[1217169814] 'process raft request' (duration: 302.514523ms)","trace[1217169814] 'compare' (duration: 26.89285ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:19:44.859976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.481457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:19:44.860091Z","caller":"traceutil/trace.go:171","msg":"trace[1678630386] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"376.650393ms","start":"2026-04-07T01:19:44.48342Z","end":"2026-04-07T01:19:44.86007Z","steps":["trace[1678630386] 'agreement among raft nodes before linearized reading' (duration: 376.285791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:44.860169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:19:44.483399Z","time spent":"376.758921ms","remote":"127.0.0.1:59932","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-04-07T01:19:44.860389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:19:44.529995Z","time spent":"329.835163ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:19:45.782954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.50942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:19:45.78305Z","caller":"traceutil/trace.go:171","msg":"trace[1763850202] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"895.670742ms","start":"2026-04-07T01:19:44.887364Z","end":"2026-04-07T01:19:45.783035Z","steps":["trace[1763850202] 'read index received' (duration: 143.023144ms)","trace[1763850202] 'applied index is now lower than readState.Index' (duration: 752.646648ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:19:45.783279Z","caller":"traceutil/trace.go:171","msg":"trace[795966919] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"909.554607ms","start":"2026-04-07T01:19:44.873712Z","end":"2026-04-07T01:19:45.783267Z","steps":["trace[795966919] 'process raft request' (duration: 156.654984ms)","trace[795966919] 'compare' (duration: 752.361504ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:19:45.783374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:19:44.873686Z","time spent":"909.618276ms","remote":"127.0.0.1:60144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:19:45.783443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.325765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:19:45.783509Z","caller":"traceutil/trace.go:171","msg":"trace[2000498646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"300.422243ms","start":"2026-04-07T01:19:45.483069Z","end":"2026-04-07T01:19:45.783492Z","steps":["trace[2000498646] 'agreement among raft nodes before linearized reading' (duration: 300.312745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:45.783542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:19:45.483055Z","time spent":"300.481542ms","remote":"127.0.0.1:59918","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-04-07T01:19:45.783619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.066741ms","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-04-07T01:19:45.783559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.149072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-07T01:19:45.783665Z","caller":"traceutil/trace.go:171","msg":"trace[604471949] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"188.184668ms","start":"2026-04-07T01:19:45.595467Z","end":"2026-04-07T01:19:45.783651Z","steps":["trace[604471949] 'agreement among raft nodes before linearized reading' (duration: 187.998412ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:45.783701Z","caller":"traceutil/trace.go:171","msg":"trace[599865371] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:629; }","duration":"896.354498ms","start":"2026-04-07T01:19:44.887325Z","end":"2026-04-07T01:19:45.783679Z","steps":["trace[599865371] 'agreement among raft nodes before linearized reading' (duration: 896.069924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:45.783756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:19:44.88731Z","time spent":"896.433217ms","remote":"127.0.0.1:60160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-07T01:19:45.784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.041907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T01:19:45.784121Z","caller":"traceutil/trace.go:171","msg":"trace[984535696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:629; }","duration":"280.217313ms","start":"2026-04-07T01:19:45.503879Z","end":"2026-04-07T01:19:45.784097Z","steps":["trace[984535696] 'agreement among raft nodes before linearized reading' (duration: 279.820201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:46.9856Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675886247761017,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T01:19:47.10383Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.244575078s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-07T01:19:47.821137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.909272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:19:47.82142Z","caller":"traceutil/trace.go:171","msg":"trace[643460390] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:655; }","duration":"1.336759733s","start":"2026-04-07T01:19:46.484613Z","end":"2026-04-07T01:19:47.821372Z","steps":["trace[643460390] 'read index received' (duration: 619.525656ms)","trace[643460390] 'applied index is now lower than readState.Index' (duration: 717.232917ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:19:47.821518Z","caller":"traceutil/trace.go:171","msg":"trace[230850062] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"1.962386925s","start":"2026-04-07T01:19:45.85912Z","end":"2026-04-07T01:19:47.821507Z","steps":["trace[230850062] 'process raft request' (duration: 1.245002331s)","trace[230850062] 'compare' (duration: 716.576838ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:19:47.821575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:19:45.859088Z","time spent":"1.962456413s","remote":"127.0.0.1:60144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T01:19:47.821944Z","caller":"traceutil/trace.go:171","msg":"trace[350314754] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.287936129s","start":"2026-04-07T01:19:46.533997Z","end":"2026-04-07T01:19:47.821933Z","steps":["trace[350314754] 'process raft request' (duration: 1.28727047s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:47.822015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:19:46.53396Z","time spent":"1.288012039s","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:19:47.82219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33758412s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:19:47.822221Z","caller":"traceutil/trace.go:171","msg":"trace[1153386350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"1.33761554s","start":"2026-04-07T01:19:46.484595Z","end":"2026-04-07T01:19:47.822211Z","steps":["trace[1153386350] 'agreement among raft nodes before linearized reading' (duration: 1.33756582s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:47.822253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:19:46.484534Z","time spent":"1.337712768s","remote":"127.0.0.1:59918","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-04-07T01:19:47.822509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.618797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T01:19:47.822564Z","caller":"traceutil/trace.go:171","msg":"trace[1260844428] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"957.773125ms","start":"2026-04-07T01:19:46.864778Z","end":"2026-04-07T01:19:47.822551Z","steps":["trace[1260844428] 'agreement among raft nodes before linearized reading' (duration: 957.595248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:47.822605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:19:46.86475Z","time spent":"957.848573ms","remote":"127.0.0.1:60210","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-04-07T01:19:47.822618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.637697ms","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-04-07T01:19:47.82267Z","caller":"traceutil/trace.go:171","msg":"trace[455583057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"957.750615ms","start":"2026-04-07T01:19:46.864907Z","end":"2026-04-07T01:19:47.822658Z","steps":["trace[455583057] 'agreement among raft nodes before linearized reading' (duration: 957.593868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:47.822701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:19:46.864896Z","time spent":"957.799334ms","remote":"127.0.0.1:60210","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-04-07T01:19:49.354758Z","caller":"traceutil/trace.go:171","msg":"trace[482253705] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"500.362678ms","start":"2026-04-07T01:19:48.854354Z","end":"2026-04-07T01:19:49.354717Z","steps":["trace[482253705] 'process raft request' (duration: 448.855475ms)","trace[482253705] 'compare' (duration: 51.306496ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:19:49.354989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:19:48.854324Z","time spent":"500.552485ms","remote":"127.0.0.1:60144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17228,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:19:50.022352Z","caller":"traceutil/trace.go:171","msg":"trace[798083690] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"100.952584ms","start":"2026-04-07T01:19:49.921376Z","end":"2026-04-07T01:19:50.022329Z","steps":["trace[798083690] 'process raft request' (duration: 100.52661ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:50.050231Z","caller":"traceutil/trace.go:171","msg":"trace[63820879] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"126.983521ms","start":"2026-04-07T01:19:49.923223Z","end":"2026-04-07T01:19:50.050207Z","steps":["trace[63820879] 'process raft request' (duration: 126.936272ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:50.050391Z","caller":"traceutil/trace.go:171","msg":"trace[2079934557] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"127.190058ms","start":"2026-04-07T01:19:49.923186Z","end":"2026-04-07T01:19:50.050376Z","steps":["trace[2079934557] 'process raft request' (duration: 126.864983ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:50.150898Z","caller":"traceutil/trace.go:171","msg":"trace[922036445] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"123.990761ms","start":"2026-04-07T01:19:50.026881Z","end":"2026-04-07T01:19:50.150872Z","steps":["trace[922036445] 'process raft request' (duration: 107.292658ms)","trace[922036445] 'compare' (duration: 16.564594ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:19:52.229849Z","caller":"traceutil/trace.go:171","msg":"trace[1639482312] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:707; }","duration":"175.468527ms","start":"2026-04-07T01:19:52.054349Z","end":"2026-04-07T01:19:52.229817Z","steps":["trace[1639482312] 'read index received' (duration: 175.18982ms)","trace[1639482312] 'applied index is now lower than readState.Index' (duration: 277.767µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:19:52.230001Z","caller":"traceutil/trace.go:171","msg":"trace[802534217] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"198.069938ms","start":"2026-04-07T01:19:52.031921Z","end":"2026-04-07T01:19:52.229991Z","steps":["trace[802534217] 'process raft request' (duration: 197.721302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:52.230279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.762163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T01:19:52.230379Z","caller":"traceutil/trace.go:171","msg":"trace[678358079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:683; }","duration":"175.933602ms","start":"2026-04-07T01:19:52.054421Z","end":"2026-04-07T01:19:52.230354Z","steps":["trace[678358079] 'agreement among raft nodes before linearized reading' (duration: 175.645945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:52.230807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.237468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-07T01:19:52.230825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.338607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:19:52.230855Z","caller":"traceutil/trace.go:171","msg":"trace[2140644663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:683; }","duration":"176.527344ms","start":"2026-04-07T01:19:52.054316Z","end":"2026-04-07T01:19:52.230843Z","steps":["trace[2140644663] 'agreement among raft nodes before linearized reading' (duration: 176.224227ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:19:52.230863Z","caller":"traceutil/trace.go:171","msg":"trace[1724847415] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:683; }","duration":"175.392707ms","start":"2026-04-07T01:19:52.055458Z","end":"2026-04-07T01:19:52.230851Z","steps":["trace[1724847415] 'agreement among raft nodes before linearized reading' (duration: 175.330978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:19:55.62787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.093716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:19:55.627996Z","caller":"traceutil/trace.go:171","msg":"trace[622803797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:724; }","duration":"121.280923ms","start":"2026-04-07T01:19:55.50669Z","end":"2026-04-07T01:19:55.627971Z","steps":["trace[622803797] 'range keys from in-memory index tree' (duration: 120.945337ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:20:02.515692Z","caller":"traceutil/trace.go:171","msg":"trace[426025444] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"135.7722ms","start":"2026-04-07T01:20:02.379882Z","end":"2026-04-07T01:20:02.515654Z","steps":["trace[426025444] 'process raft request' (duration: 135.482471ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:20:02.515919Z","caller":"traceutil/trace.go:171","msg":"trace[1235661008] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"135.792949ms","start":"2026-04-07T01:20:02.380079Z","end":"2026-04-07T01:20:02.515872Z","steps":["trace[1235661008] 'process raft request' (duration: 135.479549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:20:03.666007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.63454ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T01:20:03.666149Z","caller":"traceutil/trace.go:171","msg":"trace[702033868] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:809; }","duration":"180.867776ms","start":"2026-04-07T01:20:03.485258Z","end":"2026-04-07T01:20:03.666126Z","steps":["trace[702033868] 'read index received' (duration: 155.56µs)","trace[702033868] 'applied index is now lower than readState.Index' (duration: 180.710196ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:20:03.666331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.059255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:20:03.666372Z","caller":"traceutil/trace.go:171","msg":"trace[1617601119] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:781; }","duration":"181.140875ms","start":"2026-04-07T01:20:03.48522Z","end":"2026-04-07T01:20:03.666361Z","steps":["trace[1617601119] 'agreement among raft nodes before linearized reading' (duration: 181.012275ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:20:04.038259Z","caller":"traceutil/trace.go:171","msg":"trace[1169150881] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"163.750179ms","start":"2026-04-07T01:20:03.874205Z","end":"2026-04-07T01:20:04.037955Z","steps":["trace[1169150881] 'process raft request' (duration: 163.530079ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:20:04.258032Z","caller":"traceutil/trace.go:171","msg":"trace[2014167436] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"210.160057ms","start":"2026-04-07T01:20:04.047849Z","end":"2026-04-07T01:20:04.258009Z","steps":["trace[2014167436] 'process raft request' (duration: 210.006997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:20:04.545097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.435923ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:20:04.545242Z","caller":"traceutil/trace.go:171","msg":"trace[1209010343] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"490.45221ms","start":"2026-04-07T01:20:04.054766Z","end":"2026-04-07T01:20:04.545218Z","steps":["trace[1209010343] 'process raft request' (duration: 332.779647ms)","trace[1209010343] 'compare' (duration: 157.246793ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:20:04.545319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:20:04.054747Z","time spent":"490.53437ms","remote":"127.0.0.1:60478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14465,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:20:04.615772Z","caller":"traceutil/trace.go:171","msg":"trace[883127797] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:813; }","duration":"235.988899ms","start":"2026-04-07T01:20:04.379753Z","end":"2026-04-07T01:20:04.615742Z","steps":["trace[883127797] 'read index received' (duration: 7.78918ms)","trace[883127797] 'applied index is now lower than readState.Index' (duration: 228.198189ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:20:04.616085Z","caller":"traceutil/trace.go:171","msg":"trace[96667866] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"347.317198ms","start":"2026-04-07T01:20:04.268727Z","end":"2026-04-07T01:20:04.616044Z","steps":["trace[96667866] 'process raft request' (duration: 346.879458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:20:04.616134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.360729ms","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-04-07T01:20:04.616451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:20:04.268688Z","time spent":"347.552158ms","remote":"127.0.0.1:60134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4528,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:20:04.616434Z","caller":"traceutil/trace.go:171","msg":"trace[909219164] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:786; }","duration":"236.672419ms","start":"2026-04-07T01:20:04.379684Z","end":"2026-04-07T01:20:04.616356Z","steps":["trace[909219164] 'agreement among raft nodes before linearized reading' (duration: 236.196229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:20:04.617362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.851073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T01:20:04.618739Z","caller":"traceutil/trace.go:171","msg":"trace[736308397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:786; }","duration":"166.383784ms","start":"2026-04-07T01:20:04.452332Z","end":"2026-04-07T01:20:04.618716Z","steps":["trace[736308397] 'agreement among raft nodes before linearized reading' (duration: 163.848123ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:20:04.725037Z","caller":"traceutil/trace.go:171","msg":"trace[306168199] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"102.949678ms","start":"2026-04-07T01:20:04.622063Z","end":"2026-04-07T01:20:04.725013Z","steps":["trace[306168199] 'process raft request' (duration: 92.430577ms)","trace[306168199] 'compare' (duration: 10.372721ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:20:04.725553Z","caller":"traceutil/trace.go:171","msg":"trace[595427688] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"102.429288ms","start":"2026-04-07T01:20:04.62309Z","end":"2026-04-07T01:20:04.725519Z","steps":["trace[595427688] 'process raft request' (duration: 101.882028ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:20:06.064234Z","caller":"traceutil/trace.go:171","msg":"trace[796574913] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"285.967453ms","start":"2026-04-07T01:20:05.778236Z","end":"2026-04-07T01:20:06.064204Z","steps":["trace[796574913] 'process raft request' (duration: 285.771193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:20:08.984698Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675886247761366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T01:20:09.485054Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675886247761366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T01:20:09.834192Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.46989727s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-07T01:20:09.834894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:20:08.364165Z","time spent":"1.470722604s","remote":"127.0.0.1:59966","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-07T01:20:09.932822Z","caller":"traceutil/trace.go:171","msg":"trace[249534215] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:836; }","duration":"1.448489087s","start":"2026-04-07T01:20:08.484305Z","end":"2026-04-07T01:20:09.932794Z","steps":["trace[249534215] 'read index received' (duration: 1.350205635s)","trace[249534215] 'applied index is now lower than readState.Index' (duration: 98.282062ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:20:09.933177Z","caller":"traceutil/trace.go:171","msg":"trace[1762690789] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"952.342174ms","start":"2026-04-07T01:20:08.9808Z","end":"2026-04-07T01:20:09.933142Z","steps":["trace[1762690789] 'process raft request' (duration: 946.142148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:20:09.933399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:20:08.980766Z","time spent":"952.499805ms","remote":"127.0.0.1:60186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:20:09.933411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.449095729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T01:20:09.93347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.268249853s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T01:20:09.933568Z","caller":"traceutil/trace.go:171","msg":"trace[44542150] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:808; }","duration":"1.268394973s","start":"2026-04-07T01:20:08.665135Z","end":"2026-04-07T01:20:09.93353Z","steps":["trace[44542150] 'agreement among raft nodes before linearized reading' (duration: 1.268106252s)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:20:09.933562Z","caller":"traceutil/trace.go:171","msg":"trace[375587931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:808; }","duration":"1.44930494s","start":"2026-04-07T01:20:08.484228Z","end":"2026-04-07T01:20:09.933533Z","steps":["trace[375587931] 'agreement among raft nodes before linearized reading' (duration: 1.449044939s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:20:09.93363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:20:08.665051Z","time spent":"1.268569113s","remote":"127.0.0.1:60210","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-04-07T01:20:09.933647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:20:08.484204Z","time spent":"1.44942231s","remote":"127.0.0.1:59918","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-04-07T01:20:09.934212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187959574s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T01:20:09.934284Z","caller":"traceutil/trace.go:171","msg":"trace[122808264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:808; }","duration":"1.188084164s","start":"2026-04-07T01:20:08.746183Z","end":"2026-04-07T01:20:09.934268Z","steps":["trace[122808264] 'agreement among raft nodes before linearized reading' (duration: 1.187907813s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:20:09.934843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:20:08.746157Z","time spent":"1.188506675s","remote":"127.0.0.1:60210","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-04-07T01:20:09.935138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.192056498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T01:20:09.935235Z","caller":"traceutil/trace.go:171","msg":"trace[1152319084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:808; }","duration":"1.19221467s","start":"2026-04-07T01:20:08.743001Z","end":"2026-04-07T01:20:09.935216Z","steps":["trace[1152319084] 'agreement among raft nodes before linearized reading' (duration: 1.191915537s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:20:09.935332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:20:08.742961Z","time spent":"1.19235743s","remote":"127.0.0.1:60210","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-04-07T01:20:09.93561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.192654001s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-07T01:20:09.935684Z","caller":"traceutil/trace.go:171","msg":"trace[337401875] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:808; }","duration":"1.193000192s","start":"2026-04-07T01:20:08.742669Z","end":"2026-04-07T01:20:09.935669Z","steps":["trace[337401875] 'agreement among raft nodes before linearized reading' (duration: 1.192344639s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:20:09.93573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:20:08.742654Z","time spent":"1.193066353s","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T01:20:09.936033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006816997s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T01:20:09.936148Z","caller":"traceutil/trace.go:171","msg":"trace[1436503751] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:808; }","duration":"1.006919197s","start":"2026-04-07T01:20:08.929168Z","end":"2026-04-07T01:20:09.936087Z","steps":["trace[1436503751] 'agreement among raft nodes before linearized reading' (duration: 1.006499245s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:20:09.936263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:20:08.929153Z","time spent":"1.007089758s","remote":"127.0.0.1:60052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":9,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-04-07T01:20:16.119153Z","caller":"traceutil/trace.go:171","msg":"trace[1694356352] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"135.434781ms","start":"2026-04-07T01:20:15.983687Z","end":"2026-04-07T01:20:16.119121Z","steps":["trace[1694356352] 'process raft request' (duration: 135.201249ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:20:16.217101Z","caller":"traceutil/trace.go:171","msg":"trace[1160756245] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"230.528731ms","start":"2026-04-07T01:20:15.986516Z","end":"2026-04-07T01:20:16.217044Z","steps":["trace[1160756245] 'process raft request' (duration: 230.38503ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:20:16.217235Z","caller":"traceutil/trace.go:171","msg":"trace[266013860] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"230.518702ms","start":"2026-04-07T01:20:15.98668Z","end":"2026-04-07T01:20:16.217198Z","steps":["trace[266013860] 'process raft request' (duration: 230.26621ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:20:16.217215Z","caller":"traceutil/trace.go:171","msg":"trace[1585968629] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"131.310417ms","start":"2026-04-07T01:20:16.085884Z","end":"2026-04-07T01:20:16.217194Z","steps":["trace[1585968629] 'process raft request' (duration: 131.097505ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:20:16.217237Z","caller":"traceutil/trace.go:171","msg":"trace[1195323883] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"231.697943ms","start":"2026-04-07T01:20:15.9855Z","end":"2026-04-07T01:20:16.217198Z","steps":["trace[1195323883] 'process raft request' (duration: 230.29527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:20:16.217686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.325308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-07T01:20:16.21772Z","caller":"traceutil/trace.go:171","msg":"trace[82698437] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:877; }","duration":"101.426579ms","start":"2026-04-07T01:20:16.116286Z","end":"2026-04-07T01:20:16.217713Z","steps":["trace[82698437] 'agreement among raft nodes before linearized reading' (duration: 101.272447ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:20:16.218213Z","caller":"traceutil/trace.go:171","msg":"trace[1418264195] linearizableReadLoop","detail":"{readStateIndex:909; appliedIndex:904; }","duration":"100.682261ms","start":"2026-04-07T01:20:16.116343Z","end":"2026-04-07T01:20:16.217025Z","steps":["trace[1418264195] 'read index received' (duration: 2.590565ms)","trace[1418264195] 'applied index is now lower than readState.Index' (duration: 98.090046ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:20:16.467838Z","caller":"traceutil/trace.go:171","msg":"trace[2050562025] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"245.402057ms","start":"2026-04-07T01:20:16.222395Z","end":"2026-04-07T01:20:16.467797Z","steps":["trace[2050562025] 'process raft request' (duration: 173.09062ms)","trace[2050562025] 'compare' (duration: 72.152355ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:20:16.8175Z","caller":"traceutil/trace.go:171","msg":"trace[188557670] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"108.753889ms","start":"2026-04-07T01:20:16.708717Z","end":"2026-04-07T01:20:16.817471Z","steps":["trace[188557670] 'process raft request' (duration: 104.522709ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:20:16.821452Z","caller":"traceutil/trace.go:171","msg":"trace[1630892038] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"109.081673ms","start":"2026-04-07T01:20:16.712307Z","end":"2026-04-07T01:20:16.821389Z","steps":["trace[1630892038] 'process raft request' (duration: 109.005382ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:20:16.822214Z","caller":"traceutil/trace.go:171","msg":"trace[551917795] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"113.144351ms","start":"2026-04-07T01:20:16.708992Z","end":"2026-04-07T01:20:16.822136Z","steps":["trace[551917795] 'process raft request' (duration: 112.152622ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:20:17.191297Z","caller":"traceutil/trace.go:171","msg":"trace[1656778729] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:917; }","duration":"192.026248ms","start":"2026-04-07T01:20:16.999256Z","end":"2026-04-07T01:20:17.191283Z","steps":["trace[1656778729] 'read index received' (duration: 191.906466ms)","trace[1656778729] 'applied index is now lower than readState.Index' (duration: 119.192µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:20:17.191361Z","caller":"traceutil/trace.go:171","msg":"trace[793746853] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"322.655607ms","start":"2026-04-07T01:20:16.8687Z","end":"2026-04-07T01:20:17.191356Z","steps":["trace[793746853] 'process raft request' (duration: 322.495786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:20:17.19143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:20:16.868686Z","time spent":"322.689008ms","remote":"127.0.0.1:60144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4761,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:20:17.191605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.33312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-04-07T01:20:17.191626Z","caller":"traceutil/trace.go:171","msg":"trace[1947915192] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:886; }","duration":"192.432122ms","start":"2026-04-07T01:20:16.999189Z","end":"2026-04-07T01:20:17.191621Z","steps":["trace[1947915192] 'agreement among raft nodes before linearized reading' (duration: 192.360381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:20:17.191734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.086058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-04-07T01:20:17.191753Z","caller":"traceutil/trace.go:171","msg":"trace[1241904454] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:886; }","duration":"192.181289ms","start":"2026-04-07T01:20:16.999568Z","end":"2026-04-07T01:20:17.191749Z","steps":["trace[1241904454] 'agreement among raft nodes before linearized reading' (duration: 192.148919ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:20:21.867045Z","caller":"traceutil/trace.go:171","msg":"trace[579122004] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"154.6514ms","start":"2026-04-07T01:20:21.712355Z","end":"2026-04-07T01:20:21.867006Z","steps":["trace[579122004] 'process raft request' (duration: 154.459628ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:20:22.114579Z","caller":"traceutil/trace.go:171","msg":"trace[788167691] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:975; }","duration":"168.138491ms","start":"2026-04-07T01:20:21.946412Z","end":"2026-04-07T01:20:22.11455Z","steps":["trace[788167691] 'read index received' (duration: 25.13701ms)","trace[788167691] 'applied index is now lower than readState.Index' (duration: 143.000271ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:20:22.114656Z","caller":"traceutil/trace.go:171","msg":"trace[658888152] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"169.686841ms","start":"2026-04-07T01:20:21.944919Z","end":"2026-04-07T01:20:22.114606Z","steps":["trace[658888152] 'process raft request' (duration: 139.308362ms)","trace[658888152] 'compare' (duration: 30.117875ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:20:22.114799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.351914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:20:22.114709Z","caller":"traceutil/trace.go:171","msg":"trace[463244639] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"167.237689ms","start":"2026-04-07T01:20:21.947459Z","end":"2026-04-07T01:20:22.114696Z","steps":["trace[463244639] 'process raft request' (duration: 167.027356ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:20:22.114871Z","caller":"traceutil/trace.go:171","msg":"trace[1822394950] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:945; }","duration":"168.480305ms","start":"2026-04-07T01:20:21.946374Z","end":"2026-04-07T01:20:22.114854Z","steps":["trace[1822394950] 'agreement among raft nodes before linearized reading' (duration: 168.357784ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:20:22.350332Z","caller":"traceutil/trace.go:171","msg":"trace[196115587] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"101.168086ms","start":"2026-04-07T01:20:22.249129Z","end":"2026-04-07T01:20:22.350297Z","steps":["trace[196115587] 'process raft request' (duration: 100.234653ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:20:22.68332Z","caller":"traceutil/trace.go:171","msg":"trace[1405142197] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"137.70871ms","start":"2026-04-07T01:20:22.545584Z","end":"2026-04-07T01:20:22.683293Z","steps":["trace[1405142197] 'process raft request' (duration: 137.537117ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:20:23.483653Z","caller":"traceutil/trace.go:171","msg":"trace[813677333] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"184.516796ms","start":"2026-04-07T01:20:23.299108Z","end":"2026-04-07T01:20:23.483625Z","steps":["trace[813677333] 'process raft request' (duration: 184.338453ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:20:25.377365Z","caller":"traceutil/trace.go:171","msg":"trace[1684816175] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"329.094955ms","start":"2026-04-07T01:20:25.048239Z","end":"2026-04-07T01:20:25.377334Z","steps":["trace[1684816175] 'process raft request' (duration: 328.898732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:20:25.377534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:20:25.048224Z","time spent":"329.217487ms","remote":"127.0.0.1:60144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5056,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:20:33.646273Z","caller":"traceutil/trace.go:171","msg":"trace[564953404] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1049; }","duration":"164.708898ms","start":"2026-04-07T01:20:33.481537Z","end":"2026-04-07T01:20:33.646246Z","steps":["trace[564953404] 'read index received' (duration: 131.384817ms)","trace[564953404] 'applied index is now lower than readState.Index' (duration: 33.322501ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:20:33.646411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.855361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:20:33.646442Z","caller":"traceutil/trace.go:171","msg":"trace[1038200934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1013; }","duration":"164.950523ms","start":"2026-04-07T01:20:33.481482Z","end":"2026-04-07T01:20:33.646432Z","steps":["trace[1038200934] 'agreement among raft nodes before linearized reading' (duration: 164.860171ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:21:02.637699Z","caller":"traceutil/trace.go:171","msg":"trace[285193907] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"153.793749ms","start":"2026-04-07T01:21:02.483869Z","end":"2026-04-07T01:21:02.637663Z","steps":["trace[285193907] 'read index received' (duration: 153.653344ms)","trace[285193907] 'applied index is now lower than readState.Index' (duration: 138.674µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:21:02.637891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.989965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:21:02.637938Z","caller":"traceutil/trace.go:171","msg":"trace[20573563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1111; }","duration":"154.099218ms","start":"2026-04-07T01:21:02.483825Z","end":"2026-04-07T01:21:02.637924Z","steps":["trace[20573563] 'agreement among raft nodes before linearized reading' (duration: 153.982514ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:21:02.637856Z","caller":"traceutil/trace.go:171","msg":"trace[757223083] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"156.107366ms","start":"2026-04-07T01:21:02.481688Z","end":"2026-04-07T01:21:02.637795Z","steps":["trace[757223083] 'process raft request' (duration: 155.757664ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:21:03.14067Z","caller":"traceutil/trace.go:171","msg":"trace[353482589] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"111.601261ms","start":"2026-04-07T01:21:03.029038Z","end":"2026-04-07T01:21:03.140639Z","steps":["trace[353482589] 'process raft request' (duration: 109.805621ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:21:18.779673Z","caller":"traceutil/trace.go:171","msg":"trace[1908842375] linearizableReadLoop","detail":"{readStateIndex:1209; appliedIndex:1208; }","duration":"295.361186ms","start":"2026-04-07T01:21:18.484263Z","end":"2026-04-07T01:21:18.779624Z","steps":["trace[1908842375] 'read index received' (duration: 269.744365ms)","trace[1908842375] 'applied index is now lower than readState.Index' (duration: 25.616031ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:21:18.779707Z","caller":"traceutil/trace.go:171","msg":"trace[627346370] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"337.978808ms","start":"2026-04-07T01:21:18.441688Z","end":"2026-04-07T01:21:18.779667Z","steps":["trace[627346370] 'process raft request' (duration: 312.277994ms)","trace[627346370] 'compare' (duration: 25.521587ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:21:18.779967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.673158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T01:21:18.779976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:21:18.441653Z","time spent":"338.174096ms","remote":"127.0.0.1:59966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:21:18.780016Z","caller":"traceutil/trace.go:171","msg":"trace[1719724450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1163; }","duration":"295.797773ms","start":"2026-04-07T01:21:18.484206Z","end":"2026-04-07T01:21:18.780004Z","steps":["trace[1719724450] 'agreement among raft nodes before linearized reading' (duration: 295.582604ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:21:19.436643Z","caller":"traceutil/trace.go:171","msg":"trace[1876332609] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"148.966398ms","start":"2026-04-07T01:21:19.287628Z","end":"2026-04-07T01:21:19.436594Z","steps":["trace[1876332609] 'process raft request' (duration: 148.735889ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:21:41.506489Z","caller":"traceutil/trace.go:171","msg":"trace[514870433] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"138.933383ms","start":"2026-04-07T01:21:41.367516Z","end":"2026-04-07T01:21:41.506449Z","steps":["trace[514870433] 'process raft request' (duration: 138.702713ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:21:43.111553Z","caller":"traceutil/trace.go:171","msg":"trace[1976155565] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"135.620274ms","start":"2026-04-07T01:21:42.975883Z","end":"2026-04-07T01:21:43.111504Z","steps":["trace[1976155565] 'process raft request' (duration: 135.2987ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:21:43.631635Z","caller":"traceutil/trace.go:171","msg":"trace[944108659] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1296; }","duration":"121.580114ms","start":"2026-04-07T01:21:43.510017Z","end":"2026-04-07T01:21:43.631597Z","steps":["trace[944108659] 'read index received' (duration: 57.502598ms)","trace[944108659] 'applied index is now lower than readState.Index' (duration: 64.075916ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:21:43.63198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.922419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T01:21:43.632018Z","caller":"traceutil/trace.go:171","msg":"trace[1267348212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1246; }","duration":"122.025423ms","start":"2026-04-07T01:21:43.509981Z","end":"2026-04-07T01:21:43.632007Z","steps":["trace[1267348212] 'agreement among raft nodes before linearized reading' (duration: 121.804503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:22:13.87016Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675886247762745,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T01:22:13.907325Z","caller":"traceutil/trace.go:171","msg":"trace[65469765] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"641.64298ms","start":"2026-04-07T01:22:13.265651Z","end":"2026-04-07T01:22:13.907294Z","steps":["trace[65469765] 'process raft request' (duration: 641.473982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:22:13.90751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:22:13.265622Z","time spent":"641.798648ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:22:13.935031Z","caller":"traceutil/trace.go:171","msg":"trace[271490137] linearizableReadLoop","detail":"{readStateIndex:1439; appliedIndex:1437; }","duration":"565.554801ms","start":"2026-04-07T01:22:13.369444Z","end":"2026-04-07T01:22:13.934998Z","steps":["trace[271490137] 'read index received' (duration: 537.705585ms)","trace[271490137] 'applied index is now lower than readState.Index' (duration: 27.847056ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:22:13.935253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.815234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T01:22:13.935316Z","caller":"traceutil/trace.go:171","msg":"trace[86800699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1381; }","duration":"565.93268ms","start":"2026-04-07T01:22:13.369369Z","end":"2026-04-07T01:22:13.935301Z","steps":["trace[86800699] 'agreement among raft nodes before linearized reading' (duration: 565.741161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:22:13.935372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:22:13.369338Z","time spent":"566.025834ms","remote":"127.0.0.1:60210","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-04-07T01:22:13.935556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.311577ms","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-04-07T01:22:13.935918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.985955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:22:13.936046Z","caller":"traceutil/trace.go:171","msg":"trace[839641494] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1381; }","duration":"202.208215ms","start":"2026-04-07T01:22:13.733813Z","end":"2026-04-07T01:22:13.936021Z","steps":["trace[839641494] 'agreement among raft nodes before linearized reading' (duration: 201.952713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:22:13.935971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.648267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T01:22:13.936102Z","caller":"traceutil/trace.go:171","msg":"trace[1401634853] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1381; }","duration":"461.831246ms","start":"2026-04-07T01:22:13.474258Z","end":"2026-04-07T01:22:13.936089Z","steps":["trace[1401634853] 'agreement among raft nodes before linearized reading' (duration: 461.615495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:22:13.936143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:22:13.474234Z","time spent":"461.901471ms","remote":"127.0.0.1:60210","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-04-07T01:22:13.935972Z","caller":"traceutil/trace.go:171","msg":"trace[1375667941] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1381; }","duration":"561.762038ms","start":"2026-04-07T01:22:13.374192Z","end":"2026-04-07T01:22:13.935955Z","steps":["trace[1375667941] 'agreement among raft nodes before linearized reading' (duration: 561.003452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:22:13.937015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:22:13.374138Z","time spent":"562.858661ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}