{"level":"warn","ts":"2026-04-12T20:45:32.314831Z","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-12T20:45:32.315192Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.153:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.153:2380","--initial-cluster=instance=https://199.19.213.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.153: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-12T20:45:32.315289Z","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-12T20:45:32.315308Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.153:2380"]} {"level":"info","ts":"2026-04-12T20:45:32.315334Z","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-12T20:45:32.316112Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.153:2379"]} {"level":"info","ts":"2026-04-12T20:45:32.316191Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.153:2380"],"listen-peer-urls":["https://199.19.213.153:2380"],"advertise-client-urls":["https://199.19.213.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.153: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-12T20:45:32.346819Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"30.3955ms"} {"level":"info","ts":"2026-04-12T20:45:32.704408Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"36b63984d68b7065","cluster-id":"7c251967b436417d"} {"level":"info","ts":"2026-04-12T20:45:32.704789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 switched to configuration voters=()"} {"level":"info","ts":"2026-04-12T20:45:32.704939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 became follower at term 0"} {"level":"info","ts":"2026-04-12T20:45:32.705013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 36b63984d68b7065 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-12T20:45:32.705092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 became follower at term 1"} {"level":"info","ts":"2026-04-12T20:45:32.705233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 switched to configuration voters=(3942401766507376741)"} {"level":"warn","ts":"2026-04-12T20:45:32.717263Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-12T20:45:32.724276Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-12T20:45:32.727782Z","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-12T20:45:32.730537Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"36b63984d68b7065","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-12T20:45:32.730701Z","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-12T20:45:32.730801Z","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-12T20:45:32.730814Z","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-12T20:45:32.730752Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"36b63984d68b7065","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-12T20:45:32.733269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 switched to configuration voters=(3942401766507376741)"} {"level":"info","ts":"2026-04-12T20:45:32.733442Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7c251967b436417d","local-member-id":"36b63984d68b7065","added-peer-id":"36b63984d68b7065","added-peer-peer-urls":["https://199.19.213.153:2380"]} {"level":"info","ts":"2026-04-12T20:45:32.734284Z","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-12T20:45:32.734486Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.153:2380"} {"level":"info","ts":"2026-04-12T20:45:32.734575Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.153:2380"} {"level":"info","ts":"2026-04-12T20:45:32.734639Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"36b63984d68b7065","initial-advertise-peer-urls":["https://199.19.213.153:2380"],"listen-peer-urls":["https://199.19.213.153:2380"],"advertise-client-urls":["https://199.19.213.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-12T20:45:32.734672Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-12T20:45:33.405595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 is starting a new election at term 1"} {"level":"info","ts":"2026-04-12T20:45:33.40565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-12T20:45:33.405675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 received MsgPreVoteResp from 36b63984d68b7065 at term 1"} {"level":"info","ts":"2026-04-12T20:45:33.405694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 became candidate at term 2"} {"level":"info","ts":"2026-04-12T20:45:33.405699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 received MsgVoteResp from 36b63984d68b7065 at term 2"} {"level":"info","ts":"2026-04-12T20:45:33.405707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 became leader at term 2"} {"level":"info","ts":"2026-04-12T20:45:33.405714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 36b63984d68b7065 elected leader 36b63984d68b7065 at term 2"} {"level":"info","ts":"2026-04-12T20:45:33.408572Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"36b63984d68b7065","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.153:2379]}","request-path":"/0/members/36b63984d68b7065/attributes","cluster-id":"7c251967b436417d","publish-timeout":"7s"} {"level":"info","ts":"2026-04-12T20:45:33.408633Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:45:33.408774Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T20:45:33.408811Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T20:45:33.409145Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-12T20:45:33.409164Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-12T20:45:33.411768Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.153:2379"} {"level":"info","ts":"2026-04-12T20:45:33.414748Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7c251967b436417d","local-member-id":"36b63984d68b7065","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:45:33.414923Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:45:33.415007Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:45:33.415165Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-12T20:45:37.514973Z","caller":"traceutil/trace.go:171","msg":"trace[2115408351] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"133.444246ms","start":"2026-04-12T20:45:37.381506Z","end":"2026-04-12T20:45:37.51495Z","steps":["trace[2115408351] 'process raft request' (duration: 133.295742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:37.913034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.351024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:45:37.913147Z","caller":"traceutil/trace.go:171","msg":"trace[452148561] linearizableReadLoop","detail":"{readStateIndex:253; appliedIndex:252; }","duration":"248.104238ms","start":"2026-04-12T20:45:37.665023Z","end":"2026-04-12T20:45:37.913127Z","steps":["trace[452148561] 'read index received' (duration: 33.594µs)","trace[452148561] 'applied index is now lower than readState.Index' (duration: 248.069524ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:45:37.913258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.210137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:45:37.913289Z","caller":"traceutil/trace.go:171","msg":"trace[908243196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:248; }","duration":"248.276853ms","start":"2026-04-12T20:45:37.665003Z","end":"2026-04-12T20:45:37.91328Z","steps":["trace[908243196] 'agreement among raft nodes before linearized reading' (duration: 248.172083ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:37.913305Z","caller":"traceutil/trace.go:171","msg":"trace[815563546] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"390.615418ms","start":"2026-04-12T20:45:37.52264Z","end":"2026-04-12T20:45:37.913256Z","steps":["trace[815563546] 'process raft request' (duration: 90.619747ms)","trace[815563546] 'compare' (duration: 299.178728ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:45:37.91345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:37.522604Z","time spent":"390.777341ms","remote":"127.0.0.1:60074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:45:39.40518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.225277ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:45:39.405305Z","caller":"traceutil/trace.go:171","msg":"trace[194740909] linearizableReadLoop","detail":"{readStateIndex:299; appliedIndex:298; }","duration":"210.736805ms","start":"2026-04-12T20:45:39.19455Z","end":"2026-04-12T20:45:39.405287Z","steps":["trace[194740909] 'read index received' (duration: 102.416186ms)","trace[194740909] 'applied index is now lower than readState.Index' (duration: 108.31861ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:45:39.405373Z","caller":"traceutil/trace.go:171","msg":"trace[1816190159] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"233.707168ms","start":"2026-04-12T20:45:39.171656Z","end":"2026-04-12T20:45:39.405363Z","steps":["trace[1816190159] 'process raft request' (duration: 125.22755ms)","trace[1816190159] 'compare' (duration: 108.040741ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:45:39.405816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.301617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-12T20:45:39.405902Z","caller":"traceutil/trace.go:171","msg":"trace[1532144307] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:294; }","duration":"235.421779ms","start":"2026-04-12T20:45:39.170457Z","end":"2026-04-12T20:45:39.405879Z","steps":["trace[1532144307] 'agreement among raft nodes before linearized reading' (duration: 235.174787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:39.405798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.212235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:45:39.406003Z","caller":"traceutil/trace.go:171","msg":"trace[877622840] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:0; response_revision:294; }","duration":"235.434468ms","start":"2026-04-12T20:45:39.170539Z","end":"2026-04-12T20:45:39.405974Z","steps":["trace[877622840] 'agreement among raft nodes before linearized reading' (duration: 235.14289ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:39.415158Z","caller":"traceutil/trace.go:171","msg":"trace[425035023] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"214.1107ms","start":"2026-04-12T20:45:39.201027Z","end":"2026-04-12T20:45:39.415138Z","steps":["trace[425035023] 'process raft request' (duration: 213.867758ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:39.415194Z","caller":"traceutil/trace.go:171","msg":"trace[2015746673] transaction","detail":"{read_only:false; number_of_response:0; response_revision:295; }","duration":"172.736298ms","start":"2026-04-12T20:45:39.242441Z","end":"2026-04-12T20:45:39.415177Z","steps":["trace[2015746673] 'process raft request' (duration: 172.646024ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:39.41534Z","caller":"traceutil/trace.go:171","msg":"trace[706781394] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"172.84994ms","start":"2026-04-12T20:45:39.242478Z","end":"2026-04-12T20:45:39.415328Z","steps":["trace[706781394] 'process raft request' (duration: 172.636096ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:39.415377Z","caller":"traceutil/trace.go:171","msg":"trace[910084676] transaction","detail":"{read_only:false; number_of_response:0; response_revision:296; }","duration":"172.85357ms","start":"2026-04-12T20:45:39.2425Z","end":"2026-04-12T20:45:39.415353Z","steps":["trace[910084676] 'process raft request' (duration: 172.738319ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:39.415544Z","caller":"traceutil/trace.go:171","msg":"trace[369157325] transaction","detail":"{read_only:false; number_of_response:0; response_revision:296; }","duration":"172.361456ms","start":"2026-04-12T20:45:39.243174Z","end":"2026-04-12T20:45:39.415536Z","steps":["trace[369157325] 'process raft request' (duration: 172.122145ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:39.415727Z","caller":"traceutil/trace.go:171","msg":"trace[247280350] transaction","detail":"{read_only:false; number_of_response:0; response_revision:296; }","duration":"170.927725ms","start":"2026-04-12T20:45:39.244784Z","end":"2026-04-12T20:45:39.415712Z","steps":["trace[247280350] 'process raft request' (duration: 170.532945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:39.41582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.549064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-04-12T20:45:39.415859Z","caller":"traceutil/trace.go:171","msg":"trace[764234323] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:296; }","duration":"218.630618ms","start":"2026-04-12T20:45:39.197217Z","end":"2026-04-12T20:45:39.415847Z","steps":["trace[764234323] 'agreement among raft nodes before linearized reading' (duration: 218.537225ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:39.415508Z","caller":"traceutil/trace.go:171","msg":"trace[1980277307] transaction","detail":"{read_only:false; number_of_response:0; response_revision:295; }","duration":"190.457099ms","start":"2026-04-12T20:45:39.225026Z","end":"2026-04-12T20:45:39.415483Z","steps":["trace[1980277307] 'process raft request' (duration: 190.009383ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:46.289226Z","caller":"traceutil/trace.go:171","msg":"trace[223684695] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"111.607904ms","start":"2026-04-12T20:45:46.17757Z","end":"2026-04-12T20:45:46.289178Z","steps":["trace[223684695] 'process raft request' (duration: 109.532308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:49.093503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.458792ms","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-12T20:45:49.093631Z","caller":"traceutil/trace.go:171","msg":"trace[854726761] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:407; }","duration":"151.623433ms","start":"2026-04-12T20:45:48.94198Z","end":"2026-04-12T20:45:49.093603Z","steps":["trace[854726761] 'range keys from in-memory index tree' (duration: 151.359958ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:45:49.797989Z","caller":"traceutil/trace.go:171","msg":"trace[668775637] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"470.859061ms","start":"2026-04-12T20:45:49.327098Z","end":"2026-04-12T20:45:49.797957Z","steps":["trace[668775637] 'process raft request' (duration: 470.591445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:49.798447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:49.327081Z","time spent":"470.999664ms","remote":"127.0.0.1:60164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:45:52.200196Z","caller":"traceutil/trace.go:171","msg":"trace[1784398820] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"124.921024ms","start":"2026-04-12T20:45:52.075248Z","end":"2026-04-12T20:45:52.200169Z","steps":["trace[1784398820] 'process raft request' (duration: 124.677725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:45:52.743819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.053542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:45:52.743941Z","caller":"traceutil/trace.go:171","msg":"trace[1180411259] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"539.565986ms","start":"2026-04-12T20:45:52.204332Z","end":"2026-04-12T20:45:52.743898Z","steps":["trace[1180411259] 'process raft request' (duration: 100.351182ms)","trace[1180411259] 'compare' (duration: 438.935937ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:45:52.74399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:45:52.20431Z","time spent":"539.659231ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:46:03.519589Z","caller":"traceutil/trace.go:171","msg":"trace[1177650179] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"334.745222ms","start":"2026-04-12T20:46:03.184825Z","end":"2026-04-12T20:46:03.51957Z","steps":["trace[1177650179] 'process raft request' (duration: 334.546578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:03.519707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:03.18481Z","time spent":"334.832829ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:46:11.985225Z","caller":"traceutil/trace.go:171","msg":"trace[953697014] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"433.169801ms","start":"2026-04-12T20:46:11.552032Z","end":"2026-04-12T20:46:11.985202Z","steps":["trace[953697014] 'process raft request' (duration: 433.041403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:11.985384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:11.552012Z","time spent":"433.294468ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:46:12.507436Z","caller":"traceutil/trace.go:171","msg":"trace[389922916] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"811.431493ms","start":"2026-04-12T20:46:11.695956Z","end":"2026-04-12T20:46:12.507387Z","steps":["trace[389922916] 'process raft request' (duration: 810.710373ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:12.507868Z","caller":"traceutil/trace.go:171","msg":"trace[1671083293] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"808.487192ms","start":"2026-04-12T20:46:11.699311Z","end":"2026-04-12T20:46:12.507798Z","steps":["trace[1671083293] 'process raft request' (duration: 807.88896ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:12.507886Z","caller":"traceutil/trace.go:171","msg":"trace[593804555] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"794.939664ms","start":"2026-04-12T20:46:11.712866Z","end":"2026-04-12T20:46:12.507806Z","steps":["trace[593804555] 'process raft request' (duration: 794.50234ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:12.507898Z","caller":"traceutil/trace.go:171","msg":"trace[1026753013] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"801.700863ms","start":"2026-04-12T20:46:11.706167Z","end":"2026-04-12T20:46:12.507868Z","steps":["trace[1026753013] 'process raft request' (duration: 801.164101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:12.508089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:11.695937Z","time spent":"811.963054ms","remote":"127.0.0.1:60020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:46:12.508114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:11.712849Z","time spent":"795.163ms","remote":"127.0.0.1:60020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:46:12.508127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:11.706068Z","time spent":"801.955659ms","remote":"127.0.0.1:60020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:46:12.508157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:11.699288Z","time spent":"808.699848ms","remote":"127.0.0.1:60020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:46:12.508328Z","caller":"traceutil/trace.go:171","msg":"trace[1400006061] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"791.391342ms","start":"2026-04-12T20:46:11.716918Z","end":"2026-04-12T20:46:12.508309Z","steps":["trace[1400006061] 'process raft request' (duration: 790.952569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:12.508413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:11.716912Z","time spent":"791.460881ms","remote":"127.0.0.1:60020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:46:12.508423Z","caller":"traceutil/trace.go:171","msg":"trace[17746605] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"795.482985ms","start":"2026-04-12T20:46:11.712922Z","end":"2026-04-12T20:46:12.508405Z","steps":["trace[17746605] 'process raft request' (duration: 794.538239ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:12.508546Z","caller":"traceutil/trace.go:171","msg":"trace[1491486510] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"792.658612ms","start":"2026-04-12T20:46:11.715876Z","end":"2026-04-12T20:46:12.508534Z","steps":["trace[1491486510] 'process raft request' (duration: 791.900253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:12.508533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:11.712918Z","time spent":"795.580003ms","remote":"127.0.0.1:60020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:46:12.508573Z","caller":"traceutil/trace.go:171","msg":"trace[911564052] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"773.541086ms","start":"2026-04-12T20:46:11.735004Z","end":"2026-04-12T20:46:12.508545Z","steps":["trace[911564052] 'process raft request' (duration: 773.057922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:12.508598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:11.71587Z","time spent":"792.705791ms","remote":"127.0.0.1:60020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:46:12.508669Z","caller":"traceutil/trace.go:171","msg":"trace[367752515] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"753.396485ms","start":"2026-04-12T20:46:11.755264Z","end":"2026-04-12T20:46:12.508661Z","steps":["trace[367752515] 'process raft request' (duration: 752.939652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:12.508715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:11.755237Z","time spent":"753.461014ms","remote":"127.0.0.1:60020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:46:12.508712Z","caller":"traceutil/trace.go:171","msg":"trace[734894427] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"779.815242ms","start":"2026-04-12T20:46:11.728884Z","end":"2026-04-12T20:46:12.5087Z","steps":["trace[734894427] 'process raft request' (duration: 779.069973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:12.508665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:11.734992Z","time spent":"773.632094ms","remote":"127.0.0.1:60020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:46:12.507972Z","caller":"traceutil/trace.go:171","msg":"trace[197507026] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"807.462458ms","start":"2026-04-12T20:46:11.700325Z","end":"2026-04-12T20:46:12.507788Z","steps":["trace[197507026] 'process raft request' (duration: 806.964965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:12.509762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:11.728869Z","time spent":"779.884832ms","remote":"127.0.0.1:60020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:46:12.509961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:11.700312Z","time spent":"809.558617ms","remote":"127.0.0.1:60020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:46:12.529445Z","caller":"traceutil/trace.go:171","msg":"trace[55412823] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"681.467773ms","start":"2026-04-12T20:46:11.847962Z","end":"2026-04-12T20:46:12.52943Z","steps":["trace[55412823] 'process raft request' (duration: 673.161708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:12.529536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:11.847948Z","time spent":"681.542982ms","remote":"127.0.0.1:60020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:46:12.529607Z","caller":"traceutil/trace.go:171","msg":"trace[925548646] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:545; }","duration":"789.222217ms","start":"2026-04-12T20:46:11.740365Z","end":"2026-04-12T20:46:12.529587Z","steps":["trace[925548646] 'read index received' (duration: 244.794328ms)","trace[925548646] 'applied index is now lower than readState.Index' (duration: 544.427079ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:46:12.529653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.283827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:46:12.529893Z","caller":"traceutil/trace.go:171","msg":"trace[869206399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"789.524843ms","start":"2026-04-12T20:46:11.740346Z","end":"2026-04-12T20:46:12.529871Z","steps":["trace[869206399] 'agreement among raft nodes before linearized reading' (duration: 789.283857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:12.529919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:11.74034Z","time spent":"789.572832ms","remote":"127.0.0.1:59984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-12T20:46:12.536965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.736696ms","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-12T20:46:12.537022Z","caller":"traceutil/trace.go:171","msg":"trace[2108396013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:545; }","duration":"232.840903ms","start":"2026-04-12T20:46:12.304167Z","end":"2026-04-12T20:46:12.537008Z","steps":["trace[2108396013] 'agreement among raft nodes before linearized reading' (duration: 232.674476ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:14.950188Z","caller":"traceutil/trace.go:171","msg":"trace[1115842456] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"383.264747ms","start":"2026-04-12T20:46:14.566895Z","end":"2026-04-12T20:46:14.95016Z","steps":["trace[1115842456] 'read index received' (duration: 382.863023ms)","trace[1115842456] 'applied index is now lower than readState.Index' (duration: 400.614µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:46:14.95023Z","caller":"traceutil/trace.go:171","msg":"trace[187350069] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"897.17162ms","start":"2026-04-12T20:46:14.053038Z","end":"2026-04-12T20:46:14.950209Z","steps":["trace[187350069] 'process raft request' (duration: 896.876644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:14.950331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:14.05302Z","time spent":"897.26118ms","remote":"127.0.0.1:60020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208470,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:46:14.950344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.590228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:46:14.950371Z","caller":"traceutil/trace.go:171","msg":"trace[678969430] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"210.666196ms","start":"2026-04-12T20:46:14.739696Z","end":"2026-04-12T20:46:14.950362Z","steps":["trace[678969430] 'agreement among raft nodes before linearized reading' (duration: 210.602397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:14.950457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.698482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:46:14.950476Z","caller":"traceutil/trace.go:171","msg":"trace[1194328975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"207.745352ms","start":"2026-04-12T20:46:14.742725Z","end":"2026-04-12T20:46:14.95047Z","steps":["trace[1194328975] 'agreement among raft nodes before linearized reading' (duration: 207.715502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:14.950556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.857174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-12T20:46:14.950667Z","caller":"traceutil/trace.go:171","msg":"trace[490881502] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:604; }","duration":"111.994211ms","start":"2026-04-12T20:46:14.838643Z","end":"2026-04-12T20:46:14.950637Z","steps":["trace[490881502] 'agreement among raft nodes before linearized reading' (duration: 111.733334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:14.950743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.813713ms","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-12T20:46:14.950828Z","caller":"traceutil/trace.go:171","msg":"trace[1028649196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:604; }","duration":"383.93402ms","start":"2026-04-12T20:46:14.566854Z","end":"2026-04-12T20:46:14.950788Z","steps":["trace[1028649196] 'agreement among raft nodes before linearized reading' (duration: 383.442407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:14.950899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:14.566835Z","time spent":"384.053298ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-12T20:46:20.24382Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8099052692762568291,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T20:46:20.744373Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8099052692762568291,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T20:46:21.245477Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8099052692762568291,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T20:46:21.743049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999863645s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-12T20:46:21.743158Z","caller":"traceutil/trace.go:171","msg":"trace[1016276016] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000023104s","start":"2026-04-12T20:46:19.743107Z","end":"2026-04-12T20:46:21.74313Z","steps":["trace[1016276016] 'agreement among raft nodes before linearized reading' (duration: 1.999890645s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:21.743479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:19.743087Z","time spent":"2.000335751s","remote":"127.0.0.1:59986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/12 20:46:21 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-12T20:46:21.74648Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8099052692762568291,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T20:46:22.177502Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.536650374s","expected-duration":"1s"} {"level":"info","ts":"2026-04-12T20:46:22.178544Z","caller":"traceutil/trace.go:171","msg":"trace[1892156829] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"2.544476553s","start":"2026-04-12T20:46:19.634048Z","end":"2026-04-12T20:46:22.178525Z","steps":["trace[1892156829] 'process raft request' (duration: 2.544063814s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:22.178694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:19.633968Z","time spent":"2.544639062s","remote":"127.0.0.1:60074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:46:23.752408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00086957s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-12T20:46:23.75251Z","caller":"traceutil/trace.go:171","msg":"trace[1050277984] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00101616s","start":"2026-04-12T20:46:21.751475Z","end":"2026-04-12T20:46:23.752491Z","steps":["trace[1050277984] 'agreement among raft nodes before linearized reading' (duration: 2.00088999s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:23.752559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:21.751462Z","time spent":"2.0010856s","remote":"127.0.0.1:59984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/12 20:46:23 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-12T20:46:24.504811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.757659732s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:46:24.504961Z","caller":"traceutil/trace.go:171","msg":"trace[1509315252] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:631; }","duration":"1.757820242s","start":"2026-04-12T20:46:22.747118Z","end":"2026-04-12T20:46:24.504938Z","steps":["trace[1509315252] 'range keys from in-memory index tree' (duration: 1.757644292s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:24.50544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.705744458s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:46:24.506064Z","caller":"traceutil/trace.go:171","msg":"trace[158067819] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"2.758731893s","start":"2026-04-12T20:46:21.747317Z","end":"2026-04-12T20:46:24.506049Z","steps":["trace[158067819] 'process raft request' (duration: 2.758194172s)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:24.506074Z","caller":"traceutil/trace.go:171","msg":"trace[423234295] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"4.322701165s","start":"2026-04-12T20:46:20.183346Z","end":"2026-04-12T20:46:24.506047Z","steps":["trace[423234295] 'process raft request' (duration: 2.616246556s)","trace[423234295] 'compare' (duration: 1.704954868s)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:46:24.506117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:21.747286Z","time spent":"2.758802953s","remote":"127.0.0.1:60044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:46:24.506188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:20.183322Z","time spent":"4.322814524s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:46:25.022816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999878821s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-12T20:46:25.022954Z","caller":"traceutil/trace.go:171","msg":"trace[1002913654] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"5.000128591s","start":"2026-04-12T20:46:20.022775Z","end":"2026-04-12T20:46:25.022904Z","steps":["trace[1002913654] 'agreement among raft nodes before linearized reading' (duration: 4.99990162s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:25.023034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:20.022755Z","time spent":"5.000265911s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-12T20:46:25.753146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000226999s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-12T20:46:25.753288Z","caller":"traceutil/trace.go:171","msg":"trace[1637828067] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00042228s","start":"2026-04-12T20:46:23.75283Z","end":"2026-04-12T20:46:25.753252Z","steps":["trace[1637828067] 'agreement among raft nodes before linearized reading' (duration: 2.000214849s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:25.753531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:23.752787Z","time spent":"2.000726629s","remote":"127.0.0.1:59986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/12 20:46:25 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-12T20:46:25.984728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999386317s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-12T20:46:25.984973Z","caller":"traceutil/trace.go:171","msg":"trace[937067124] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999661137s","start":"2026-04-12T20:46:20.985264Z","end":"2026-04-12T20:46:25.984925Z","steps":["trace[937067124] 'agreement among raft nodes before linearized reading' (duration: 4.999426027s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:25.985121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:20.985242Z","time spent":"4.999864628s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/04/12 20:46:25 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-12T20:46:26.403418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.897821689s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-12T20:46:26.403527Z","caller":"traceutil/trace.go:171","msg":"trace[541240088] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:659; }","duration":"6.660341432s","start":"2026-04-12T20:46:19.743154Z","end":"2026-04-12T20:46:26.403495Z","steps":["trace[541240088] 'read index received' (duration: 2.434812215s)","trace[541240088] 'applied index is now lower than readState.Index' (duration: 4.225527697s)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:46:26.577491Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.777816625s","expected-duration":"1s"} {"level":"info","ts":"2026-04-12T20:46:26.578205Z","caller":"traceutil/trace.go:171","msg":"trace[79383710] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"4.345400037s","start":"2026-04-12T20:46:22.232773Z","end":"2026-04-12T20:46:26.578173Z","steps":["trace[79383710] 'process raft request' (duration: 4.345155376s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:26.578377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:22.232753Z","time spent":"4.345548797s","remote":"127.0.0.1:60310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:46:26.742472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000210099s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-12T20:46:26.742569Z","caller":"traceutil/trace.go:171","msg":"trace[1872440354] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000392078s","start":"2026-04-12T20:46:24.742155Z","end":"2026-04-12T20:46:26.742548Z","steps":["trace[1872440354] 'agreement among raft nodes before linearized reading' (duration: 2.000245948s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:26.742626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:24.742127Z","time spent":"2.000488319s","remote":"127.0.0.1:59984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/12 20:46:26 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-12T20:46:27.762828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001320024s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-12T20:46:27.762917Z","caller":"traceutil/trace.go:171","msg":"trace[588758710] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001446994s","start":"2026-04-12T20:46:25.761451Z","end":"2026-04-12T20:46:27.762898Z","steps":["trace[588758710] 'agreement among raft nodes before linearized reading' (duration: 2.001337304s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:27.762964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:25.76144Z","time spent":"2.001513634s","remote":"127.0.0.1:59984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/12 20:46:27 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-12T20:46:27.967839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.231036641s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:46:27.96794Z","caller":"traceutil/trace.go:171","msg":"trace[343219378] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:663; }","duration":"1.564369128s","start":"2026-04-12T20:46:26.403555Z","end":"2026-04-12T20:46:27.967924Z","steps":["trace[343219378] 'read index received' (duration: 174.294298ms)","trace[343219378] 'applied index is now lower than readState.Index' (duration: 1.39007344s)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:46:27.96804Z","caller":"traceutil/trace.go:171","msg":"trace[1034840911] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"2.714764713s","start":"2026-04-12T20:46:25.25326Z","end":"2026-04-12T20:46:27.968025Z","steps":["trace[1034840911] 'process raft request' (duration: 1.483456201s)","trace[1034840911] 'compare' (duration: 1.2308323s)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:46:27.968113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:25.253234Z","time spent":"2.714837212s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:46:27.968508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.607613373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-12T20:46:27.968544Z","caller":"traceutil/trace.go:171","msg":"trace[1287261212] range","detail":"{range_begin:/registry/masterleases/199.19.213.153; range_end:; response_count:1; response_revision:635; }","duration":"1.607691351s","start":"2026-04-12T20:46:26.360842Z","end":"2026-04-12T20:46:27.968534Z","steps":["trace[1287261212] 'agreement among raft nodes before linearized reading' (duration: 1.607487481s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:27.968575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:26.36082Z","time spent":"1.607748123s","remote":"127.0.0.1:60008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.153\" "} {"level":"warn","ts":"2026-04-12T20:46:27.96863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.379017081s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:46:27.968708Z","caller":"traceutil/trace.go:171","msg":"trace[1617717783] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:635; }","duration":"1.379135813s","start":"2026-04-12T20:46:26.58955Z","end":"2026-04-12T20:46:27.968685Z","steps":["trace[1617717783] 'agreement among raft nodes before linearized reading' (duration: 1.379012862s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:27.968758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:26.589528Z","time spent":"1.379219181s","remote":"127.0.0.1:60318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":27,"request content":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" "} {"level":"warn","ts":"2026-04-12T20:46:27.968736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.7165959s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-djvpj\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-12T20:46:27.968855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.886163164s","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-12T20:46:27.968906Z","caller":"traceutil/trace.go:171","msg":"trace[468654805] range","detail":"{range_begin:/registry/pods/kube-system/cilium-djvpj; range_end:; response_count:1; response_revision:635; }","duration":"7.716818651s","start":"2026-04-12T20:46:20.252067Z","end":"2026-04-12T20:46:27.968886Z","steps":["trace[468654805] 'agreement among raft nodes before linearized reading' (duration: 7.716430689s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:27.968898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.553482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-12T20:46:27.968935Z","caller":"traceutil/trace.go:171","msg":"trace[68437575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"6.886290765s","start":"2026-04-12T20:46:21.082626Z","end":"2026-04-12T20:46:27.968916Z","steps":["trace[68437575] 'agreement among raft nodes before linearized reading' (duration: 6.886015104s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:27.968963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.212762133s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18a5b6f2bd7a19f3\" ","response":"range_response_count:1 size:774"} {"level":"warn","ts":"2026-04-12T20:46:27.96898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.886391134s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-12T20:46:27.968986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:21.082597Z","time spent":"6.886379705s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-12T20:46:27.968973Z","caller":"traceutil/trace.go:171","msg":"trace[436743186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"943.657022ms","start":"2026-04-12T20:46:27.025291Z","end":"2026-04-12T20:46:27.968948Z","steps":["trace[436743186] 'agreement among raft nodes before linearized reading' (duration: 943.404642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:27.968753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.49055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:46:27.96901Z","caller":"traceutil/trace.go:171","msg":"trace[472457163] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18a5b6f2bd7a19f3; range_end:; response_count:1; response_revision:635; }","duration":"2.212840064s","start":"2026-04-12T20:46:25.756152Z","end":"2026-04-12T20:46:27.968993Z","steps":["trace[472457163] 'agreement among raft nodes before linearized reading' (duration: 2.212739123s)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:27.969032Z","caller":"traceutil/trace.go:171","msg":"trace[956477982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:635; }","duration":"6.886459275s","start":"2026-04-12T20:46:21.082549Z","end":"2026-04-12T20:46:27.969008Z","steps":["trace[956477982] 'agreement among raft nodes before linearized reading' (duration: 6.886340324s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:27.969055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:27.025268Z","time spent":"943.775034ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-12T20:46:27.969061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:25.756129Z","time spent":"2.212924374s","remote":"127.0.0.1:60044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":796,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18a5b6f2bd7a19f3\" "} {"level":"warn","ts":"2026-04-12T20:46:27.969071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:21.082527Z","time spent":"6.886536245s","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-12T20:46:27.969103Z","caller":"traceutil/trace.go:171","msg":"trace[1366030270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"205.845161ms","start":"2026-04-12T20:46:27.763212Z","end":"2026-04-12T20:46:27.969057Z","steps":["trace[1366030270] 'agreement among raft nodes before linearized reading' (duration: 205.407709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:27.969007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:20.252049Z","time spent":"7.7168953s","remote":"127.0.0.1:60164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-djvpj\" "} {"level":"warn","ts":"2026-04-12T20:46:28.47697Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8099052692762568300,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T20:46:28.882558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.288729ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-04-12T20:46:28.882841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:27.972596Z","time spent":"910.238541ms","remote":"127.0.0.1:60008","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-12T20:46:28.883059Z","caller":"traceutil/trace.go:171","msg":"trace[782730922] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"909.516599ms","start":"2026-04-12T20:46:27.973503Z","end":"2026-04-12T20:46:28.883019Z","steps":["trace[782730922] 'process raft request' (duration: 909.209378ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:28.883186Z","caller":"traceutil/trace.go:171","msg":"trace[2085198073] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"907.782112ms","start":"2026-04-12T20:46:27.975367Z","end":"2026-04-12T20:46:28.883149Z","steps":["trace[2085198073] 'process raft request' (duration: 907.555791ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:28.883216Z","caller":"traceutil/trace.go:171","msg":"trace[954589998] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"905.368334ms","start":"2026-04-12T20:46:27.977831Z","end":"2026-04-12T20:46:28.8832Z","steps":["trace[954589998] 'process raft request' (duration: 905.245852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:28.88322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:27.973478Z","time spent":"909.64391ms","remote":"127.0.0.1:60044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:46:28.883306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:27.977817Z","time spent":"905.425144ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:46:28.883306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:27.975341Z","time spent":"907.904483ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:46:28.883399Z","caller":"traceutil/trace.go:171","msg":"trace[1777970066] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"907.778933ms","start":"2026-04-12T20:46:27.975613Z","end":"2026-04-12T20:46:28.883391Z","steps":["trace[1777970066] 'process raft request' (duration: 907.365262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:28.883429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:27.975602Z","time spent":"907.809754ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:46:28.88368Z","caller":"traceutil/trace.go:171","msg":"trace[323442247] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"892.940298ms","start":"2026-04-12T20:46:27.990729Z","end":"2026-04-12T20:46:28.88367Z","steps":["trace[323442247] 'process raft request' (duration: 892.375686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:28.883744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:27.990711Z","time spent":"892.994648ms","remote":"127.0.0.1:60164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:46:28.884244Z","caller":"traceutil/trace.go:171","msg":"trace[1270869172] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:665; }","duration":"907.996824ms","start":"2026-04-12T20:46:27.976208Z","end":"2026-04-12T20:46:28.884204Z","steps":["trace[1270869172] 'read index received' (duration: 761.582805ms)","trace[1270869172] 'applied index is now lower than readState.Index' (duration: 146.412309ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:46:28.884987Z","caller":"traceutil/trace.go:171","msg":"trace[22372694] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"500.657833ms","start":"2026-04-12T20:46:28.384311Z","end":"2026-04-12T20:46:28.884969Z","steps":["trace[22372694] 'process raft request' (duration: 499.040715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:28.885118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:28.384268Z","time spent":"500.765563ms","remote":"127.0.0.1:60074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036269,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:46:28.885395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.161951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:46:28.885519Z","caller":"traceutil/trace.go:171","msg":"trace[1571973504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"909.326421ms","start":"2026-04-12T20:46:27.976164Z","end":"2026-04-12T20:46:28.88549Z","steps":["trace[1571973504] 'agreement among raft nodes before linearized reading' (duration: 909.12448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:28.885574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:27.976147Z","time spent":"909.417952ms","remote":"127.0.0.1:59984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-12T20:46:28.900658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.86191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-12T20:46:28.900713Z","caller":"traceutil/trace.go:171","msg":"trace[980844653] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:642; }","duration":"912.952211ms","start":"2026-04-12T20:46:27.987749Z","end":"2026-04-12T20:46:28.900702Z","steps":["trace[980844653] 'agreement among raft nodes before linearized reading' (duration: 912.778409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:28.900739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:27.987712Z","time spent":"913.022922ms","remote":"127.0.0.1:60238","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":"info","ts":"2026-04-12T20:46:30.810508Z","caller":"traceutil/trace.go:171","msg":"trace[1172159843] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"351.162404ms","start":"2026-04-12T20:46:30.459314Z","end":"2026-04-12T20:46:30.810477Z","steps":["trace[1172159843] 'process raft request' (duration: 351.023932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:30.810665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:30.459284Z","time spent":"351.311935ms","remote":"127.0.0.1:60020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:46:30.821025Z","caller":"traceutil/trace.go:171","msg":"trace[1635600315] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"158.740999ms","start":"2026-04-12T20:46:30.662264Z","end":"2026-04-12T20:46:30.821005Z","steps":["trace[1635600315] 'process raft request' (duration: 158.085684ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:33.629174Z","caller":"traceutil/trace.go:171","msg":"trace[405629860] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"144.378626ms","start":"2026-04-12T20:46:33.484767Z","end":"2026-04-12T20:46:33.629146Z","steps":["trace[405629860] 'process raft request' (duration: 144.202523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:43.540592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.039553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:46:43.540945Z","caller":"traceutil/trace.go:171","msg":"trace[1789861884] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:833; }","duration":"391.774878ms","start":"2026-04-12T20:46:43.149146Z","end":"2026-04-12T20:46:43.540921Z","steps":["trace[1789861884] 'read index received' (duration: 197.069702ms)","trace[1789861884] 'applied index is now lower than readState.Index' (duration: 194.703956ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:46:43.540985Z","caller":"traceutil/trace.go:171","msg":"trace[625219453] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"570.232879ms","start":"2026-04-12T20:46:42.970732Z","end":"2026-04-12T20:46:43.540965Z","steps":["trace[625219453] 'process raft request' (duration: 375.642855ms)","trace[625219453] 'compare' (duration: 193.811379ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:46:43.541013Z","caller":"traceutil/trace.go:171","msg":"trace[524749608] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"569.719229ms","start":"2026-04-12T20:46:42.971264Z","end":"2026-04-12T20:46:43.540983Z","steps":["trace[524749608] 'process raft request' (duration: 569.489934ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:43.541035Z","caller":"traceutil/trace.go:171","msg":"trace[1880390798] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"569.619357ms","start":"2026-04-12T20:46:42.971403Z","end":"2026-04-12T20:46:43.541023Z","steps":["trace[1880390798] 'process raft request' (duration: 569.461884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:43.541073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:42.970721Z","time spent":"570.30829ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:46:43.541106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.946461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T20:46:43.541149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:42.971256Z","time spent":"569.820802ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:46:43.541161Z","caller":"traceutil/trace.go:171","msg":"trace[276833055] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:799; }","duration":"392.048642ms","start":"2026-04-12T20:46:43.149097Z","end":"2026-04-12T20:46:43.541146Z","steps":["trace[276833055] 'agreement among raft nodes before linearized reading' (duration: 391.95871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:43.541158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:42.971397Z","time spent":"569.70757ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:46:43.541218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:43.149073Z","time spent":"392.130594ms","remote":"127.0.0.1:46318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 "} {"level":"warn","ts":"2026-04-12T20:46:43.54129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.635516ms","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-12T20:46:43.541315Z","caller":"traceutil/trace.go:171","msg":"trace[1421481635] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:799; }","duration":"241.683237ms","start":"2026-04-12T20:46:43.299624Z","end":"2026-04-12T20:46:43.541307Z","steps":["trace[1421481635] 'agreement among raft nodes before linearized reading' (duration: 241.630266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:43.541324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.713507ms","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":"info","ts":"2026-04-12T20:46:43.54136Z","caller":"traceutil/trace.go:171","msg":"trace[569735869] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:799; }","duration":"241.758258ms","start":"2026-04-12T20:46:43.299591Z","end":"2026-04-12T20:46:43.54135Z","steps":["trace[569735869] 'agreement among raft nodes before linearized reading' (duration: 241.712347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:43.541453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.204486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T20:46:43.541284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.86957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:46:43.541562Z","caller":"traceutil/trace.go:171","msg":"trace[1605823614] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:799; }","duration":"276.173047ms","start":"2026-04-12T20:46:43.265383Z","end":"2026-04-12T20:46:43.541556Z","steps":["trace[1605823614] 'agreement among raft nodes before linearized reading' (duration: 275.86918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:43.541571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.242886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T20:46:43.541581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.857273ms","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":"info","ts":"2026-04-12T20:46:43.541633Z","caller":"traceutil/trace.go:171","msg":"trace[1426410242] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:799; }","duration":"222.927733ms","start":"2026-04-12T20:46:43.318693Z","end":"2026-04-12T20:46:43.541621Z","steps":["trace[1426410242] 'agreement among raft nodes before linearized reading' (duration: 222.853602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:43.541653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.558263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T20:46:43.541706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.619873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T20:46:43.541655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.519523ms","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":"warn","ts":"2026-04-12T20:46:43.541756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.740277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:46:43.541761Z","caller":"traceutil/trace.go:171","msg":"trace[1413806983] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:799; }","duration":"307.640556ms","start":"2026-04-12T20:46:43.234108Z","end":"2026-04-12T20:46:43.541749Z","steps":["trace[1413806983] 'agreement among raft nodes before linearized reading' (duration: 307.501663ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:43.541762Z","caller":"traceutil/trace.go:171","msg":"trace[1765926731] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:799; }","duration":"361.743774ms","start":"2026-04-12T20:46:43.180002Z","end":"2026-04-12T20:46:43.541745Z","steps":["trace[1765926731] 'agreement among raft nodes before linearized reading' (duration: 361.681894ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:43.541798Z","caller":"traceutil/trace.go:171","msg":"trace[186397831] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:799; }","duration":"291.811359ms","start":"2026-04-12T20:46:43.249974Z","end":"2026-04-12T20:46:43.541786Z","steps":["trace[186397831] 'agreement among raft nodes before linearized reading' (duration: 291.763827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:43.541815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:43.234105Z","time spent":"307.693777ms","remote":"127.0.0.1:46336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-12T20:46:43.541823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:43.179979Z","time spent":"361.827607ms","remote":"127.0.0.1:46328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 "} {"level":"warn","ts":"2026-04-12T20:46:43.541841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.495354ms","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-12T20:46:43.541887Z","caller":"traceutil/trace.go:171","msg":"trace[783372511] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:799; }","duration":"222.554427ms","start":"2026-04-12T20:46:43.319323Z","end":"2026-04-12T20:46:43.541878Z","steps":["trace[783372511] 'agreement among raft nodes before linearized reading' (duration: 222.488135ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:43.541729Z","caller":"traceutil/trace.go:171","msg":"trace[586577001] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:799; }","duration":"392.324258ms","start":"2026-04-12T20:46:43.149225Z","end":"2026-04-12T20:46:43.541549Z","steps":["trace[586577001] 'agreement among raft nodes before linearized reading' (duration: 392.017782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:43.542033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.229809ms","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":"warn","ts":"2026-04-12T20:46:43.542042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.940081ms","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-12T20:46:43.542041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.764135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T20:46:43.542069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.929031ms","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-12T20:46:43.542034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.188976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:46:43.542091Z","caller":"traceutil/trace.go:171","msg":"trace[1503294826] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:799; }","duration":"262.824597ms","start":"2026-04-12T20:46:43.279252Z","end":"2026-04-12T20:46:43.542077Z","steps":["trace[1503294826] 'agreement among raft nodes before linearized reading' (duration: 262.751105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:43.54211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.522221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:46:43.542138Z","caller":"traceutil/trace.go:171","msg":"trace[734091797] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:799; }","duration":"361.567421ms","start":"2026-04-12T20:46:43.180563Z","end":"2026-04-12T20:46:43.54213Z","steps":["trace[734091797] 'agreement among raft nodes before linearized reading' (duration: 361.530651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:43.542168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:43.180551Z","time spent":"361.609852ms","remote":"127.0.0.1:46328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 "} {"level":"warn","ts":"2026-04-12T20:46:43.541711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.332859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T20:46:43.541534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.144493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:46:43.541597Z","caller":"traceutil/trace.go:171","msg":"trace[884378999] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:799; }","duration":"262.294407ms","start":"2026-04-12T20:46:43.279295Z","end":"2026-04-12T20:46:43.541589Z","steps":["trace[884378999] 'agreement among raft nodes before linearized reading' (duration: 262.258286ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:43.54228Z","caller":"traceutil/trace.go:171","msg":"trace[443904647] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:799; }","duration":"276.91092ms","start":"2026-04-12T20:46:43.26536Z","end":"2026-04-12T20:46:43.542271Z","steps":["trace[443904647] 'agreement among raft nodes before linearized reading' (duration: 276.331339ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:43.54229Z","caller":"traceutil/trace.go:171","msg":"trace[1651423512] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:799; }","duration":"261.900368ms","start":"2026-04-12T20:46:43.280379Z","end":"2026-04-12T20:46:43.54228Z","steps":["trace[1651423512] 'agreement among raft nodes before linearized reading' (duration: 261.132173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:43.542247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:43.149192Z","time spent":"392.857319ms","remote":"127.0.0.1:46318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"info","ts":"2026-04-12T20:46:43.542101Z","caller":"traceutil/trace.go:171","msg":"trace[1998464110] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:799; }","duration":"307.964842ms","start":"2026-04-12T20:46:43.234125Z","end":"2026-04-12T20:46:43.54209Z","steps":["trace[1998464110] 'agreement among raft nodes before linearized reading' (duration: 307.928501ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:43.542075Z","caller":"traceutil/trace.go:171","msg":"trace[1925672792] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:799; }","duration":"307.988352ms","start":"2026-04-12T20:46:43.234077Z","end":"2026-04-12T20:46:43.542065Z","steps":["trace[1925672792] 'agreement among raft nodes before linearized reading' (duration: 307.922891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:43.542425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:43.234122Z","time spent":"308.288748ms","remote":"127.0.0.1:46336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-12T20:46:43.542103Z","caller":"traceutil/trace.go:171","msg":"trace[2003912498] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:799; }","duration":"292.267788ms","start":"2026-04-12T20:46:43.249827Z","end":"2026-04-12T20:46:43.542095Z","steps":["trace[2003912498] 'agreement among raft nodes before linearized reading' (duration: 292.186686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:43.542434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:43.234069Z","time spent":"308.352269ms","remote":"127.0.0.1:46336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-12T20:46:43.542552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.981972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:46:43.541713Z","caller":"traceutil/trace.go:171","msg":"trace[1373275970] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:799; }","duration":"275.633035ms","start":"2026-04-12T20:46:43.266066Z","end":"2026-04-12T20:46:43.541699Z","steps":["trace[1373275970] 'agreement among raft nodes before linearized reading' (duration: 275.541403ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:43.542591Z","caller":"traceutil/trace.go:171","msg":"trace[550664743] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:799; }","duration":"392.046473ms","start":"2026-04-12T20:46:43.150536Z","end":"2026-04-12T20:46:43.542583Z","steps":["trace[550664743] 'agreement among raft nodes before linearized reading' (duration: 391.992382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:43.542619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:43.15052Z","time spent":"392.091244ms","remote":"127.0.0.1:46318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 "} {"level":"warn","ts":"2026-04-12T20:46:43.54273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.764865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:46:43.542762Z","caller":"traceutil/trace.go:171","msg":"trace[961619782] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:799; }","duration":"362.829916ms","start":"2026-04-12T20:46:43.179924Z","end":"2026-04-12T20:46:43.542754Z","steps":["trace[961619782] 'agreement among raft nodes before linearized reading' (duration: 362.787794ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:43.54207Z","caller":"traceutil/trace.go:171","msg":"trace[1218420374] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:799; }","duration":"223.26863ms","start":"2026-04-12T20:46:43.318793Z","end":"2026-04-12T20:46:43.542062Z","steps":["trace[1218420374] 'agreement among raft nodes before linearized reading' (duration: 223.215369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:43.542789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:43.179904Z","time spent":"362.877196ms","remote":"127.0.0.1:46328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-04-12T20:46:43.541993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.067864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:46:43.5429Z","caller":"traceutil/trace.go:171","msg":"trace[2106264742] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:799; }","duration":"293.006622ms","start":"2026-04-12T20:46:43.249882Z","end":"2026-04-12T20:46:43.542888Z","steps":["trace[2106264742] 'agreement among raft nodes before linearized reading' (duration: 292.087335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:45.495523Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8099052692762568680,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T20:46:45.504941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.688652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T20:46:45.504966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.08344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:46:45.505003Z","caller":"traceutil/trace.go:171","msg":"trace[28714401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:809; }","duration":"760.777143ms","start":"2026-04-12T20:46:44.744209Z","end":"2026-04-12T20:46:45.504986Z","steps":["trace[28714401] 'range keys from in-memory index tree' (duration: 760.63991ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:45.505035Z","caller":"traceutil/trace.go:171","msg":"trace[2128430355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:809; }","duration":"764.232333ms","start":"2026-04-12T20:46:44.740784Z","end":"2026-04-12T20:46:45.505016Z","steps":["trace[2128430355] 'range keys from in-memory index tree' (duration: 764.013289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:45.505044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:44.744198Z","time spent":"760.837874ms","remote":"127.0.0.1:59986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-12T20:46:45.505075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:44.740764Z","time spent":"764.303915ms","remote":"127.0.0.1:59984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-12T20:46:45.505577Z","caller":"traceutil/trace.go:171","msg":"trace[113710729] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"526.681285ms","start":"2026-04-12T20:46:44.978883Z","end":"2026-04-12T20:46:45.505564Z","steps":["trace[113710729] 'process raft request' (duration: 526.551041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:45.505673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:44.978859Z","time spent":"526.756315ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:46:45.507395Z","caller":"traceutil/trace.go:171","msg":"trace[619590779] linearizableReadLoop","detail":"{readStateIndex:849; appliedIndex:848; }","duration":"512.472358ms","start":"2026-04-12T20:46:44.994903Z","end":"2026-04-12T20:46:45.507375Z","steps":["trace[619590779] 'read index received' (duration: 511.14435ms)","trace[619590779] 'applied index is now lower than readState.Index' (duration: 1.327318ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:46:45.507513Z","caller":"traceutil/trace.go:171","msg":"trace[322916288] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"106.25099ms","start":"2026-04-12T20:46:45.40121Z","end":"2026-04-12T20:46:45.507461Z","steps":["trace[322916288] 'process raft request' (duration: 106.056336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:45.507588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.685352ms","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-12T20:46:45.507632Z","caller":"traceutil/trace.go:171","msg":"trace[1083089991] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:811; }","duration":"512.766155ms","start":"2026-04-12T20:46:44.994853Z","end":"2026-04-12T20:46:45.507619Z","steps":["trace[1083089991] 'agreement among raft nodes before linearized reading' (duration: 512.618581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:45.507672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:44.994832Z","time spent":"512.833435ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-12T20:46:50.023697Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8099052692762568724,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-12T20:46:50.168998Z","caller":"traceutil/trace.go:171","msg":"trace[2086746373] linearizableReadLoop","detail":"{readStateIndex:867; appliedIndex:866; }","duration":"645.36828ms","start":"2026-04-12T20:46:49.523611Z","end":"2026-04-12T20:46:50.168979Z","steps":["trace[2086746373] 'read index received' (duration: 645.213236ms)","trace[2086746373] 'applied index is now lower than readState.Index' (duration: 154.544µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:46:50.169131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.495033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T20:46:50.169162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.45367ms","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-12T20:46:50.169172Z","caller":"traceutil/trace.go:171","msg":"trace[1702638167] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:828; }","duration":"645.582065ms","start":"2026-04-12T20:46:49.52358Z","end":"2026-04-12T20:46:50.169162Z","steps":["trace[1702638167] 'agreement among raft nodes before linearized reading' (duration: 645.475722ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:50.169186Z","caller":"traceutil/trace.go:171","msg":"trace[3409900] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:828; }","duration":"640.490861ms","start":"2026-04-12T20:46:49.528689Z","end":"2026-04-12T20:46:50.169179Z","steps":["trace[3409900] 'agreement among raft nodes before linearized reading' (duration: 640.389438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:50.169203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:49.523567Z","time spent":"645.628495ms","remote":"127.0.0.1:60280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-04-12T20:46:50.169211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:49.528682Z","time spent":"640.524442ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-12T20:46:50.169155Z","caller":"traceutil/trace.go:171","msg":"trace[2007073567] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"750.517326ms","start":"2026-04-12T20:46:49.418537Z","end":"2026-04-12T20:46:50.169054Z","steps":["trace[2007073567] 'process raft request' (duration: 750.320861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:50.169597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.33791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-12T20:46:50.169716Z","caller":"traceutil/trace.go:171","msg":"trace[1670370760] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:828; }","duration":"599.493145ms","start":"2026-04-12T20:46:49.570198Z","end":"2026-04-12T20:46:50.169692Z","steps":["trace[1670370760] 'agreement among raft nodes before linearized reading' (duration: 598.962622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:50.169432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.115126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T20:46:50.16981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:49.570188Z","time spent":"599.608478ms","remote":"127.0.0.1:60238","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":"info","ts":"2026-04-12T20:46:50.169824Z","caller":"traceutil/trace.go:171","msg":"trace[1979593988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:828; }","duration":"428.676211ms","start":"2026-04-12T20:46:49.741131Z","end":"2026-04-12T20:46:50.169807Z","steps":["trace[1979593988] 'agreement among raft nodes before linearized reading' (duration: 428.102856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:50.169497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.060557ms","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-12T20:46:50.169991Z","caller":"traceutil/trace.go:171","msg":"trace[272904185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:828; }","duration":"600.620352ms","start":"2026-04-12T20:46:49.569357Z","end":"2026-04-12T20:46:50.169978Z","steps":["trace[272904185] 'agreement among raft nodes before linearized reading' (duration: 599.943785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:50.170104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:49.56934Z","time spent":"600.714794ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-12T20:46:50.169536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.442438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-12T20:46:50.170091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:49.418512Z","time spent":"751.157622ms","remote":"127.0.0.1:60212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2888,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:46:50.169904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:49.741096Z","time spent":"428.794913ms","remote":"127.0.0.1:59984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-12T20:46:50.170448Z","caller":"traceutil/trace.go:171","msg":"trace[1058014038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:828; }","duration":"601.188365ms","start":"2026-04-12T20:46:49.569007Z","end":"2026-04-12T20:46:50.170196Z","steps":["trace[1058014038] 'agreement among raft nodes before linearized reading' (duration: 600.319873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:50.17074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:49.568964Z","time spent":"601.71698ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-12T20:46:50.280565Z","caller":"traceutil/trace.go:171","msg":"trace[63695106] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"105.136387ms","start":"2026-04-12T20:46:50.175398Z","end":"2026-04-12T20:46:50.280535Z","steps":["trace[63695106] 'process raft request' (duration: 95.399872ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:50.28087Z","caller":"traceutil/trace.go:171","msg":"trace[1159590718] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"104.926713ms","start":"2026-04-12T20:46:50.175913Z","end":"2026-04-12T20:46:50.28084Z","steps":["trace[1159590718] 'process raft request' (duration: 104.579244ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:50.281032Z","caller":"traceutil/trace.go:171","msg":"trace[109224983] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"103.558478ms","start":"2026-04-12T20:46:50.177462Z","end":"2026-04-12T20:46:50.28102Z","steps":["trace[109224983] 'process raft request' (duration: 103.499187ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:50.281063Z","caller":"traceutil/trace.go:171","msg":"trace[1121125600] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"104.343719ms","start":"2026-04-12T20:46:50.176712Z","end":"2026-04-12T20:46:50.281056Z","steps":["trace[1121125600] 'process raft request' (duration: 104.030821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:50.623141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.965163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:46:50.623373Z","caller":"traceutil/trace.go:171","msg":"trace[771954422] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"251.48029ms","start":"2026-04-12T20:46:50.371863Z","end":"2026-04-12T20:46:50.623344Z","steps":["trace[771954422] 'process raft request' (duration: 42.194999ms)","trace[771954422] 'compare' (duration: 208.638015ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:46:51.064262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.88097ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:46:51.064386Z","caller":"traceutil/trace.go:171","msg":"trace[618781535] linearizableReadLoop","detail":"{readStateIndex:877; appliedIndex:876; }","duration":"321.727068ms","start":"2026-04-12T20:46:50.742638Z","end":"2026-04-12T20:46:51.064365Z","steps":["trace[618781535] 'read index received' (duration: 21.681693ms)","trace[618781535] 'applied index is now lower than readState.Index' (duration: 300.043875ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:46:51.064508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.871072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:46:51.064424Z","caller":"traceutil/trace.go:171","msg":"trace[1029954303] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"691.507896ms","start":"2026-04-12T20:46:50.372883Z","end":"2026-04-12T20:46:51.064391Z","steps":["trace[1029954303] 'process raft request' (duration: 391.421421ms)","trace[1029954303] 'compare' (duration: 299.726586ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:46:51.06454Z","caller":"traceutil/trace.go:171","msg":"trace[849051788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:838; }","duration":"321.944635ms","start":"2026-04-12T20:46:50.742587Z","end":"2026-04-12T20:46:51.064531Z","steps":["trace[849051788] 'agreement among raft nodes before linearized reading' (duration: 321.832972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:51.064576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:50.742564Z","time spent":"322.005605ms","remote":"127.0.0.1:59984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-12T20:46:51.064647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:50.372859Z","time spent":"691.708372ms","remote":"127.0.0.1:60084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1535,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:46:51.274942Z","caller":"traceutil/trace.go:171","msg":"trace[2121127532] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"205.505413ms","start":"2026-04-12T20:46:51.069412Z","end":"2026-04-12T20:46:51.274917Z","steps":["trace[2121127532] 'process raft request' (duration: 122.869236ms)","trace[2121127532] 'compare' (duration: 82.524844ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:46:51.601902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.094934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:46:51.602092Z","caller":"traceutil/trace.go:171","msg":"trace[912070401] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"322.114339ms","start":"2026-04-12T20:46:51.279959Z","end":"2026-04-12T20:46:51.602073Z","steps":["trace[912070401] 'process raft request' (duration: 114.791489ms)","trace[912070401] 'compare' (duration: 206.900189ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:46:51.602145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:46:51.279938Z","time spent":"322.18527ms","remote":"127.0.0.1:60318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":827,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:46:51.825475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.480558ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-12T20:46:51.837911Z","caller":"traceutil/trace.go:171","msg":"trace[451868343] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"230.808649ms","start":"2026-04-12T20:46:51.607081Z","end":"2026-04-12T20:46:51.837889Z","steps":["trace[451868343] 'process raft request' (duration: 230.655254ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:51.876806Z","caller":"traceutil/trace.go:171","msg":"trace[1263172939] linearizableReadLoop","detail":"{readStateIndex:882; appliedIndex:880; }","duration":"133.656156ms","start":"2026-04-12T20:46:51.743134Z","end":"2026-04-12T20:46:51.87679Z","steps":["trace[1263172939] 'read index received' (duration: 94.632837ms)","trace[1263172939] 'applied index is now lower than readState.Index' (duration: 39.022609ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:46:51.876959Z","caller":"traceutil/trace.go:171","msg":"trace[352487173] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"269.669903ms","start":"2026-04-12T20:46:51.607271Z","end":"2026-04-12T20:46:51.876941Z","steps":["trace[352487173] 'process raft request' (duration: 269.459228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:51.876972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.81744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:46:51.877038Z","caller":"traceutil/trace.go:171","msg":"trace[1755894727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:842; }","duration":"133.939323ms","start":"2026-04-12T20:46:51.743082Z","end":"2026-04-12T20:46:51.877021Z","steps":["trace[1755894727] 'agreement among raft nodes before linearized reading' (duration: 133.78938ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:52.100314Z","caller":"traceutil/trace.go:171","msg":"trace[1917260818] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"218.038558ms","start":"2026-04-12T20:46:51.882253Z","end":"2026-04-12T20:46:52.100292Z","steps":["trace[1917260818] 'process raft request' (duration: 217.993738ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:52.100374Z","caller":"traceutil/trace.go:171","msg":"trace[537061897] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"218.08978ms","start":"2026-04-12T20:46:51.882253Z","end":"2026-04-12T20:46:52.100343Z","steps":["trace[537061897] 'process raft request' (duration: 123.473043ms)","trace[537061897] 'compare' (duration: 94.38207ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:46:52.70636Z","caller":"traceutil/trace.go:171","msg":"trace[2018009813] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"116.654505ms","start":"2026-04-12T20:46:52.589677Z","end":"2026-04-12T20:46:52.706331Z","steps":["trace[2018009813] 'process raft request' (duration: 114.825697ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:52.830214Z","caller":"traceutil/trace.go:171","msg":"trace[844629024] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"118.521663ms","start":"2026-04-12T20:46:52.711651Z","end":"2026-04-12T20:46:52.830173Z","steps":["trace[844629024] 'process raft request' (duration: 95.335366ms)","trace[844629024] 'compare' (duration: 22.90618ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:46:54.42979Z","caller":"traceutil/trace.go:171","msg":"trace[1473668063] linearizableReadLoop","detail":"{readStateIndex:923; appliedIndex:921; }","duration":"128.495188ms","start":"2026-04-12T20:46:54.301268Z","end":"2026-04-12T20:46:54.429763Z","steps":["trace[1473668063] 'read index received' (duration: 15.848626ms)","trace[1473668063] 'applied index is now lower than readState.Index' (duration: 112.645482ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:46:54.429913Z","caller":"traceutil/trace.go:171","msg":"trace[2058370531] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"129.613019ms","start":"2026-04-12T20:46:54.300253Z","end":"2026-04-12T20:46:54.429866Z","steps":["trace[2058370531] 'process raft request' (duration: 129.372653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:54.430018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.718686ms","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-12T20:46:54.430064Z","caller":"traceutil/trace.go:171","msg":"trace[349826153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:883; }","duration":"128.792777ms","start":"2026-04-12T20:46:54.301257Z","end":"2026-04-12T20:46:54.43005Z","steps":["trace[349826153] 'agreement among raft nodes before linearized reading' (duration: 128.627523ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:46:59.943474Z","caller":"traceutil/trace.go:171","msg":"trace[2126311441] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"122.237167ms","start":"2026-04-12T20:46:59.821216Z","end":"2026-04-12T20:46:59.943453Z","steps":["trace[2126311441] 'read index received' (duration: 122.0465ms)","trace[2126311441] 'applied index is now lower than readState.Index' (duration: 187.707µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:46:59.943557Z","caller":"traceutil/trace.go:171","msg":"trace[376569378] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"127.848884ms","start":"2026-04-12T20:46:59.815669Z","end":"2026-04-12T20:46:59.943518Z","steps":["trace[376569378] 'process raft request' (duration: 127.659639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:46:59.943678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.430493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:46:59.943767Z","caller":"traceutil/trace.go:171","msg":"trace[1206777702] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:941; }","duration":"122.570107ms","start":"2026-04-12T20:46:59.821177Z","end":"2026-04-12T20:46:59.943747Z","steps":["trace[1206777702] 'agreement among raft nodes before linearized reading' (duration: 122.362492ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:47:00.427714Z","caller":"traceutil/trace.go:171","msg":"trace[404882495] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"154.442365ms","start":"2026-04-12T20:47:00.273259Z","end":"2026-04-12T20:47:00.427701Z","steps":["trace[404882495] 'process raft request' (duration: 154.255159ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:47:00.427696Z","caller":"traceutil/trace.go:171","msg":"trace[573982797] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1004; }","duration":"129.42368ms","start":"2026-04-12T20:47:00.298245Z","end":"2026-04-12T20:47:00.427669Z","steps":["trace[573982797] 'read index received' (duration: 129.216953ms)","trace[573982797] 'applied index is now lower than readState.Index' (duration: 205.817µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:47:00.427879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.619767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-04-12T20:47:00.427949Z","caller":"traceutil/trace.go:171","msg":"trace[1472025451] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:964; }","duration":"129.741869ms","start":"2026-04-12T20:47:00.298186Z","end":"2026-04-12T20:47:00.427928Z","steps":["trace[1472025451] 'agreement among raft nodes before linearized reading' (duration: 129.563584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:47:00.428008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.986181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:47:00.428115Z","caller":"traceutil/trace.go:171","msg":"trace[849072228] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:964; }","duration":"104.137465ms","start":"2026-04-12T20:47:00.32396Z","end":"2026-04-12T20:47:00.428097Z","steps":["trace[849072228] 'agreement among raft nodes before linearized reading' (duration: 103.97706ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:47:16.925789Z","caller":"traceutil/trace.go:171","msg":"trace[397877343] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1083; }","duration":"246.754056ms","start":"2026-04-12T20:47:16.679001Z","end":"2026-04-12T20:47:16.925755Z","steps":["trace[397877343] 'read index received' (duration: 244.012612ms)","trace[397877343] 'applied index is now lower than readState.Index' (duration: 2.740224ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:47:16.925864Z","caller":"traceutil/trace.go:171","msg":"trace[576373315] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"297.001023ms","start":"2026-04-12T20:47:16.628842Z","end":"2026-04-12T20:47:16.925843Z","steps":["trace[576373315] 'process raft request' (duration: 294.2715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:47:16.926036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.925868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:47:16.926087Z","caller":"traceutil/trace.go:171","msg":"trace[1781143525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1038; }","duration":"184.045712ms","start":"2026-04-12T20:47:16.742023Z","end":"2026-04-12T20:47:16.926069Z","steps":["trace[1781143525] 'agreement among raft nodes before linearized reading' (duration: 183.947238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:47:16.926087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.062128ms","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-12T20:47:16.926173Z","caller":"traceutil/trace.go:171","msg":"trace[721863402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1038; }","duration":"247.215075ms","start":"2026-04-12T20:47:16.678938Z","end":"2026-04-12T20:47:16.926153Z","steps":["trace[721863402] 'agreement among raft nodes before linearized reading' (duration: 246.972775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:47:16.926271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.04096ms","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-12T20:47:16.926386Z","caller":"traceutil/trace.go:171","msg":"trace[509639789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1038; }","duration":"182.195805ms","start":"2026-04-12T20:47:16.744164Z","end":"2026-04-12T20:47:16.92636Z","steps":["trace[509639789] 'agreement among raft nodes before linearized reading' (duration: 181.909813ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:47:19.069606Z","caller":"traceutil/trace.go:171","msg":"trace[465642311] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"133.492529ms","start":"2026-04-12T20:47:18.936086Z","end":"2026-04-12T20:47:19.069578Z","steps":["trace[465642311] 'process raft request' (duration: 133.216888ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:47:21.320255Z","caller":"traceutil/trace.go:171","msg":"trace[2001999587] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1102; }","duration":"132.277099ms","start":"2026-04-12T20:47:21.187947Z","end":"2026-04-12T20:47:21.320224Z","steps":["trace[2001999587] 'read index received' (duration: 41.321698ms)","trace[2001999587] 'applied index is now lower than readState.Index' (duration: 90.95369ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:47:21.320381Z","caller":"traceutil/trace.go:171","msg":"trace[1388065473] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"135.262719ms","start":"2026-04-12T20:47:21.185076Z","end":"2026-04-12T20:47:21.320338Z","steps":["trace[1388065473] 'process raft request' (duration: 44.183473ms)","trace[1388065473] 'compare' (duration: 90.792693ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:47:21.320512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.53663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-12T20:47:21.320553Z","caller":"traceutil/trace.go:171","msg":"trace[194103179] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1057; }","duration":"132.661354ms","start":"2026-04-12T20:47:21.187879Z","end":"2026-04-12T20:47:21.32054Z","steps":["trace[194103179] 'agreement among raft nodes before linearized reading' (duration: 132.450634ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:47:23.452976Z","caller":"traceutil/trace.go:171","msg":"trace[129614070] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"123.739157ms","start":"2026-04-12T20:47:23.329155Z","end":"2026-04-12T20:47:23.452894Z","steps":["trace[129614070] 'process raft request' (duration: 24.843156ms)","trace[129614070] 'compare' (duration: 98.676202ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:47:29.802048Z","caller":"traceutil/trace.go:171","msg":"trace[445242869] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"150.813599ms","start":"2026-04-12T20:47:29.651207Z","end":"2026-04-12T20:47:29.80202Z","steps":["trace[445242869] 'process raft request' (duration: 53.055542ms)","trace[445242869] 'compare' (duration: 97.498994ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:47:29.802708Z","caller":"traceutil/trace.go:171","msg":"trace[343086632] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"117.958967ms","start":"2026-04-12T20:47:29.684736Z","end":"2026-04-12T20:47:29.802695Z","steps":["trace[343086632] 'process raft request' (duration: 117.220912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:47:34.308118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.403619ms","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-12T20:47:34.308199Z","caller":"traceutil/trace.go:171","msg":"trace[1788295903] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1095; }","duration":"492.511604ms","start":"2026-04-12T20:47:33.815665Z","end":"2026-04-12T20:47:34.308176Z","steps":["trace[1788295903] 'range keys from in-memory index tree' (duration: 492.203499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:47:34.30824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:47:33.815653Z","time spent":"492.577467ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-12T20:47:34.308223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.561525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:47:34.308304Z","caller":"traceutil/trace.go:171","msg":"trace[1244130396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1095; }","duration":"564.686883ms","start":"2026-04-12T20:47:33.743597Z","end":"2026-04-12T20:47:34.308284Z","steps":["trace[1244130396] 'range keys from in-memory index tree' (duration: 564.478362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:47:34.308353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:47:33.743577Z","time spent":"564.767636ms","remote":"127.0.0.1:59984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-12T20:47:34.308552Z","caller":"traceutil/trace.go:171","msg":"trace[286897596] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"390.314172ms","start":"2026-04-12T20:47:33.918217Z","end":"2026-04-12T20:47:34.308531Z","steps":["trace[286897596] 'process raft request' (duration: 379.843743ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:47:34.308545Z","caller":"traceutil/trace.go:171","msg":"trace[2002987965] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1144; }","duration":"128.487625ms","start":"2026-04-12T20:47:34.180001Z","end":"2026-04-12T20:47:34.308489Z","steps":["trace[2002987965] 'read index received' (duration: 118.005155ms)","trace[2002987965] 'applied index is now lower than readState.Index' (duration: 10.48033ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:47:34.308627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.303267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-12T20:47:34.308671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:47:33.918197Z","time spent":"390.405266ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:47:34.308696Z","caller":"traceutil/trace.go:171","msg":"trace[1372110568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1095; }","duration":"654.38984ms","start":"2026-04-12T20:47:33.654282Z","end":"2026-04-12T20:47:34.308672Z","steps":["trace[1372110568] 'range keys from in-memory index tree' (duration: 654.194581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:47:34.308733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:47:33.654268Z","time spent":"654.455204ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-12T20:47:34.30874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.716697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1084"} {"level":"info","ts":"2026-04-12T20:47:34.308794Z","caller":"traceutil/trace.go:171","msg":"trace[1899580285] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:1096; }","duration":"128.835382ms","start":"2026-04-12T20:47:34.179946Z","end":"2026-04-12T20:47:34.308782Z","steps":["trace[1899580285] 'agreement among raft nodes before linearized reading' (duration: 128.686245ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:47:38.517105Z","caller":"traceutil/trace.go:171","msg":"trace[587449081] linearizableReadLoop","detail":"{readStateIndex:1161; appliedIndex:1160; }","duration":"181.210678ms","start":"2026-04-12T20:47:38.335872Z","end":"2026-04-12T20:47:38.517083Z","steps":["trace[587449081] 'read index received' (duration: 181.023508ms)","trace[587449081] 'applied index is now lower than readState.Index' (duration: 186.17µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:47:38.51727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.095569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:47:38.517315Z","caller":"traceutil/trace.go:171","msg":"trace[1625873258] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1111; }","duration":"130.193204ms","start":"2026-04-12T20:47:38.387108Z","end":"2026-04-12T20:47:38.517302Z","steps":["trace[1625873258] 'agreement among raft nodes before linearized reading' (duration: 130.102909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:47:38.517312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.423748ms","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-12T20:47:38.517385Z","caller":"traceutil/trace.go:171","msg":"trace[1157816754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1111; }","duration":"181.536574ms","start":"2026-04-12T20:47:38.335829Z","end":"2026-04-12T20:47:38.517366Z","steps":["trace[1157816754] 'agreement among raft nodes before linearized reading' (duration: 181.341423ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:47:38.517265Z","caller":"traceutil/trace.go:171","msg":"trace[1497002159] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"183.661042ms","start":"2026-04-12T20:47:38.333483Z","end":"2026-04-12T20:47:38.517144Z","steps":["trace[1497002159] 'process raft request' (duration: 183.463542ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:47:51.663677Z","caller":"traceutil/trace.go:171","msg":"trace[1840477261] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"105.883483ms","start":"2026-04-12T20:47:51.557762Z","end":"2026-04-12T20:47:51.663645Z","steps":["trace[1840477261] 'process raft request' (duration: 87.615103ms)","trace[1840477261] 'compare' (duration: 18.141464ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:47:52.799572Z","caller":"traceutil/trace.go:171","msg":"trace[173613400] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"131.977484ms","start":"2026-04-12T20:47:52.667573Z","end":"2026-04-12T20:47:52.799551Z","steps":["trace[173613400] 'process raft request' (duration: 131.848887ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:47:52.804084Z","caller":"traceutil/trace.go:171","msg":"trace[1430367644] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"136.435132ms","start":"2026-04-12T20:47:52.667632Z","end":"2026-04-12T20:47:52.804067Z","steps":["trace[1430367644] 'process raft request' (duration: 136.134615ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:09.425316Z","caller":"traceutil/trace.go:171","msg":"trace[1737314733] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1267; }","duration":"165.554078ms","start":"2026-04-12T20:48:09.259735Z","end":"2026-04-12T20:48:09.425289Z","steps":["trace[1737314733] 'read index received' (duration: 165.390157ms)","trace[1737314733] 'applied index is now lower than readState.Index' (duration: 162.361µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:48:09.425385Z","caller":"traceutil/trace.go:171","msg":"trace[844812803] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"256.251769ms","start":"2026-04-12T20:48:09.169086Z","end":"2026-04-12T20:48:09.425338Z","steps":["trace[844812803] 'process raft request' (duration: 256.036606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:09.425545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.751279ms","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-12T20:48:09.425595Z","caller":"traceutil/trace.go:171","msg":"trace[837367412] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1212; }","duration":"165.906417ms","start":"2026-04-12T20:48:09.259676Z","end":"2026-04-12T20:48:09.425582Z","steps":["trace[837367412] 'agreement among raft nodes before linearized reading' (duration: 165.730458ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:10.909404Z","caller":"traceutil/trace.go:171","msg":"trace[251913149] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1271; }","duration":"165.726264ms","start":"2026-04-12T20:48:10.743647Z","end":"2026-04-12T20:48:10.909374Z","steps":["trace[251913149] 'read index received' (duration: 165.701322ms)","trace[251913149] 'applied index is now lower than readState.Index' (duration: 22.582µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:48:10.909558Z","caller":"traceutil/trace.go:171","msg":"trace[1887221837] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"276.759429ms","start":"2026-04-12T20:48:10.632781Z","end":"2026-04-12T20:48:10.90954Z","steps":["trace[1887221837] 'process raft request' (duration: 276.470732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:10.909592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.938585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:48:10.909646Z","caller":"traceutil/trace.go:171","msg":"trace[1449707818] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1215; }","duration":"166.01056ms","start":"2026-04-12T20:48:10.743622Z","end":"2026-04-12T20:48:10.909633Z","steps":["trace[1449707818] 'agreement among raft nodes before linearized reading' (duration: 165.897903ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:10.91231Z","caller":"traceutil/trace.go:171","msg":"trace[1293259390] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"106.095828ms","start":"2026-04-12T20:48:10.806194Z","end":"2026-04-12T20:48:10.91229Z","steps":["trace[1293259390] 'process raft request' (duration: 105.867874ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:12.311284Z","caller":"traceutil/trace.go:171","msg":"trace[1268169078] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"352.062359ms","start":"2026-04-12T20:48:11.959195Z","end":"2026-04-12T20:48:12.311257Z","steps":["trace[1268169078] 'process raft request' (duration: 351.90681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:12.311462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:48:11.959179Z","time spent":"352.192177ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:48:13.206369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.913814ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:48:13.206639Z","caller":"traceutil/trace.go:171","msg":"trace[747541538] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"203.958592ms","start":"2026-04-12T20:48:13.00267Z","end":"2026-04-12T20:48:13.206629Z","steps":["trace[747541538] 'process raft request' (duration: 97.723048ms)","trace[747541538] 'compare' (duration: 105.819729ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:48:13.20649Z","caller":"traceutil/trace.go:171","msg":"trace[1571200379] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"203.679107ms","start":"2026-04-12T20:48:13.002798Z","end":"2026-04-12T20:48:13.206477Z","steps":["trace[1571200379] 'process raft request' (duration: 203.645994ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:18.53954Z","caller":"traceutil/trace.go:171","msg":"trace[16321290] linearizableReadLoop","detail":"{readStateIndex:1299; appliedIndex:1298; }","duration":"146.317188ms","start":"2026-04-12T20:48:18.393195Z","end":"2026-04-12T20:48:18.539513Z","steps":["trace[16321290] 'read index received' (duration: 146.120405ms)","trace[16321290] 'applied index is now lower than readState.Index' (duration: 195.773µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:48:18.539603Z","caller":"traceutil/trace.go:171","msg":"trace[1984728297] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"187.153189ms","start":"2026-04-12T20:48:18.352413Z","end":"2026-04-12T20:48:18.539566Z","steps":["trace[1984728297] 'process raft request' (duration: 186.876162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:18.539713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.485247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:48:18.53976Z","caller":"traceutil/trace.go:171","msg":"trace[1126722122] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1241; }","duration":"146.601654ms","start":"2026-04-12T20:48:18.393143Z","end":"2026-04-12T20:48:18.539745Z","steps":["trace[1126722122] 'agreement among raft nodes before linearized reading' (duration: 146.492907ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:19.510631Z","caller":"traceutil/trace.go:171","msg":"trace[951210301] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"141.009537ms","start":"2026-04-12T20:48:19.369594Z","end":"2026-04-12T20:48:19.510604Z","steps":["trace[951210301] 'process raft request' (duration: 129.682864ms)","trace[951210301] 'compare' (duration: 11.203044ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:48:29.876345Z","caller":"traceutil/trace.go:171","msg":"trace[1601084265] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"106.349655ms","start":"2026-04-12T20:48:29.769972Z","end":"2026-04-12T20:48:29.876321Z","steps":["trace[1601084265] 'process raft request' (duration: 106.071826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:30.070138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.586582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-12T20:48:30.070239Z","caller":"traceutil/trace.go:171","msg":"trace[790764136] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1291; }","duration":"101.731571ms","start":"2026-04-12T20:48:29.968484Z","end":"2026-04-12T20:48:30.070215Z","steps":["trace[790764136] 'range keys from in-memory index tree' (duration: 101.367388ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:33.121581Z","caller":"traceutil/trace.go:171","msg":"trace[1693575093] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"296.697199ms","start":"2026-04-12T20:48:32.824846Z","end":"2026-04-12T20:48:33.121543Z","steps":["trace[1693575093] 'process raft request' (duration: 296.503528ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:33.358491Z","caller":"traceutil/trace.go:171","msg":"trace[1890918728] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"229.779072ms","start":"2026-04-12T20:48:33.128688Z","end":"2026-04-12T20:48:33.358467Z","steps":["trace[1890918728] 'process raft request' (duration: 229.580739ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:33.362081Z","caller":"traceutil/trace.go:171","msg":"trace[1832087890] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"170.603956ms","start":"2026-04-12T20:48:33.19146Z","end":"2026-04-12T20:48:33.362064Z","steps":["trace[1832087890] 'process raft request' (duration: 170.5097ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:33.526553Z","caller":"traceutil/trace.go:171","msg":"trace[447243684] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1374; }","duration":"107.606363ms","start":"2026-04-12T20:48:33.418926Z","end":"2026-04-12T20:48:33.526533Z","steps":["trace[447243684] 'read index received' (duration: 107.446662ms)","trace[447243684] 'applied index is now lower than readState.Index' (duration: 158.801µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:48:33.52661Z","caller":"traceutil/trace.go:171","msg":"trace[1324073205] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"110.169129ms","start":"2026-04-12T20:48:33.416418Z","end":"2026-04-12T20:48:33.526587Z","steps":["trace[1324073205] 'process raft request' (duration: 110.008029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:33.526772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.829067ms","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-12T20:48:33.526965Z","caller":"traceutil/trace.go:171","msg":"trace[1743655568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1313; }","duration":"108.044881ms","start":"2026-04-12T20:48:33.418883Z","end":"2026-04-12T20:48:33.526928Z","steps":["trace[1743655568] 'agreement among raft nodes before linearized reading' (duration: 107.732542ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:33.69159Z","caller":"traceutil/trace.go:171","msg":"trace[1509623345] linearizableReadLoop","detail":"{readStateIndex:1377; appliedIndex:1376; }","duration":"119.613715ms","start":"2026-04-12T20:48:33.571953Z","end":"2026-04-12T20:48:33.691566Z","steps":["trace[1509623345] 'read index received' (duration: 50.048379ms)","trace[1509623345] 'applied index is now lower than readState.Index' (duration: 69.564086ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:48:33.691756Z","caller":"traceutil/trace.go:171","msg":"trace[1245805656] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"160.775373ms","start":"2026-04-12T20:48:33.530949Z","end":"2026-04-12T20:48:33.691724Z","steps":["trace[1245805656] 'process raft request' (duration: 91.121192ms)","trace[1245805656] 'compare' (duration: 69.370153ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:48:33.691982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.962088ms","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-12T20:48:33.692053Z","caller":"traceutil/trace.go:171","msg":"trace[1112883530] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1315; }","duration":"120.112668ms","start":"2026-04-12T20:48:33.57192Z","end":"2026-04-12T20:48:33.692033Z","steps":["trace[1112883530] 'agreement among raft nodes before linearized reading' (duration: 119.757425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:48:36.794536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.677663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-12T20:48:36.794617Z","caller":"traceutil/trace.go:171","msg":"trace[1768362952] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1331; }","duration":"113.830004ms","start":"2026-04-12T20:48:36.680767Z","end":"2026-04-12T20:48:36.794597Z","steps":["trace[1768362952] 'count revisions from in-memory index tree' (duration: 113.555765ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:41.731781Z","caller":"traceutil/trace.go:171","msg":"trace[870776137] linearizableReadLoop","detail":"{readStateIndex:1416; appliedIndex:1415; }","duration":"130.42754ms","start":"2026-04-12T20:48:41.601317Z","end":"2026-04-12T20:48:41.731745Z","steps":["trace[870776137] 'read index received' (duration: 76.739565ms)","trace[870776137] 'applied index is now lower than readState.Index' (duration: 53.684635ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:48:41.732112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.776224ms","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-12T20:48:41.732166Z","caller":"traceutil/trace.go:171","msg":"trace[1335608683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1353; }","duration":"130.87995ms","start":"2026-04-12T20:48:41.601271Z","end":"2026-04-12T20:48:41.732151Z","steps":["trace[1335608683] 'agreement among raft nodes before linearized reading' (duration: 130.619933ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:48:41.98798Z","caller":"traceutil/trace.go:171","msg":"trace[977442195] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"205.772603ms","start":"2026-04-12T20:48:41.782173Z","end":"2026-04-12T20:48:41.987946Z","steps":["trace[977442195] 'process raft request' (duration: 205.584681ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:49:03.164766Z","caller":"traceutil/trace.go:171","msg":"trace[350981047] linearizableReadLoop","detail":"{readStateIndex:1498; appliedIndex:1497; }","duration":"206.262077ms","start":"2026-04-12T20:49:02.958482Z","end":"2026-04-12T20:49:03.164744Z","steps":["trace[350981047] 'read index received' (duration: 206.077564ms)","trace[350981047] 'applied index is now lower than readState.Index' (duration: 183.693µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:49:03.164829Z","caller":"traceutil/trace.go:171","msg":"trace[831975577] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"221.472297ms","start":"2026-04-12T20:49:02.943329Z","end":"2026-04-12T20:49:03.164802Z","steps":["trace[831975577] 'process raft request' (duration: 221.278334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:03.164997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.501942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-2kzd9\" ","response":"range_response_count:1 size:4694"} {"level":"info","ts":"2026-04-12T20:49:03.165038Z","caller":"traceutil/trace.go:171","msg":"trace[339609776] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-2kzd9; range_end:; response_count:1; response_revision:1429; }","duration":"206.590918ms","start":"2026-04-12T20:49:02.958436Z","end":"2026-04-12T20:49:03.165027Z","steps":["trace[339609776] 'agreement among raft nodes before linearized reading' (duration: 206.444878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:03.165102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.583101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-4sprc\" ","response":"range_response_count:1 size:4908"} {"level":"info","ts":"2026-04-12T20:49:03.165144Z","caller":"traceutil/trace.go:171","msg":"trace[1166058471] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-4sprc; range_end:; response_count:1; response_revision:1429; }","duration":"172.711828ms","start":"2026-04-12T20:49:02.992422Z","end":"2026-04-12T20:49:03.165134Z","steps":["trace[1166058471] 'agreement among raft nodes before linearized reading' (duration: 172.565308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:49:03.165157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.860923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-12T20:49:03.165216Z","caller":"traceutil/trace.go:171","msg":"trace[1675775191] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1429; }","duration":"111.95488ms","start":"2026-04-12T20:49:03.053247Z","end":"2026-04-12T20:49:03.165202Z","steps":["trace[1675775191] 'agreement among raft nodes before linearized reading' (duration: 111.850713ms)"],"step_count":1}