{"level":"warn","ts":"2026-05-14T03:05:44.256296Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-14T03:05:44.256386Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.238: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.238:2380","--initial-cluster=instance=https://199.19.213.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.238:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-14T03:05:44.256451Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-14T03:05:44.256463Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.238:2380"]} {"level":"info","ts":"2026-05-14T03:05:44.256481Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-14T03:05:44.257096Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.238:2379"]} {"level":"info","ts":"2026-05-14T03:05:44.257169Z","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.238:2380"],"listen-peer-urls":["https://199.19.213.238:2380"],"advertise-client-urls":["https://199.19.213.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.238:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-14T03:05:44.264999Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.62445ms"} {"level":"info","ts":"2026-05-14T03:05:44.28054Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8cf6035374726417","cluster-id":"e837e6e114b087e2"} {"level":"info","ts":"2026-05-14T03:05:44.280645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 switched to configuration voters=()"} {"level":"info","ts":"2026-05-14T03:05:44.28067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became follower at term 0"} {"level":"info","ts":"2026-05-14T03:05:44.280728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8cf6035374726417 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-14T03:05:44.280748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became follower at term 1"} {"level":"info","ts":"2026-05-14T03:05:44.280791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 switched to configuration voters=(10157309666551555095)"} {"level":"warn","ts":"2026-05-14T03:05:44.28848Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-14T03:05:44.289751Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-14T03:05:44.291576Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-14T03:05:44.295035Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8cf6035374726417","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-14T03:05:44.295338Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8cf6035374726417","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-14T03:05:44.29548Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-14T03:05:44.295527Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-14T03:05:44.295544Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-14T03:05:44.298429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 switched to configuration voters=(10157309666551555095)"} {"level":"info","ts":"2026-05-14T03:05:44.298629Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e837e6e114b087e2","local-member-id":"8cf6035374726417","added-peer-id":"8cf6035374726417","added-peer-peer-urls":["https://199.19.213.238:2380"]} {"level":"info","ts":"2026-05-14T03:05:44.304249Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-14T03:05:44.304357Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.238:2380"} {"level":"info","ts":"2026-05-14T03:05:44.304408Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.238:2380"} {"level":"info","ts":"2026-05-14T03:05:44.304596Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8cf6035374726417","initial-advertise-peer-urls":["https://199.19.213.238:2380"],"listen-peer-urls":["https://199.19.213.238:2380"],"advertise-client-urls":["https://199.19.213.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-14T03:05:44.304644Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-14T03:05:45.281827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 is starting a new election at term 1"} {"level":"info","ts":"2026-05-14T03:05:45.281896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-14T03:05:45.281938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 received MsgPreVoteResp from 8cf6035374726417 at term 1"} {"level":"info","ts":"2026-05-14T03:05:45.281957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became candidate at term 2"} {"level":"info","ts":"2026-05-14T03:05:45.281968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 received MsgVoteResp from 8cf6035374726417 at term 2"} {"level":"info","ts":"2026-05-14T03:05:45.281986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became leader at term 2"} {"level":"info","ts":"2026-05-14T03:05:45.282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8cf6035374726417 elected leader 8cf6035374726417 at term 2"} {"level":"info","ts":"2026-05-14T03:05:45.282886Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8cf6035374726417","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.238:2379]}","request-path":"/0/members/8cf6035374726417/attributes","cluster-id":"e837e6e114b087e2","publish-timeout":"7s"} {"level":"info","ts":"2026-05-14T03:05:45.282884Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T03:05:45.283034Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T03:05:45.283058Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T03:05:45.283211Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-14T03:05:45.283287Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-14T03:05:45.285087Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e837e6e114b087e2","local-member-id":"8cf6035374726417","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T03:05:45.285159Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T03:05:45.285176Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T03:05:45.285493Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.238:2379"} {"level":"info","ts":"2026-05-14T03:05:45.286505Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-14T03:05:56.975296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.925006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-14T03:05:56.975443Z","caller":"traceutil/trace.go:171","msg":"trace[1484718737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:392; }","duration":"861.104291ms","start":"2026-05-14T03:05:56.114301Z","end":"2026-05-14T03:05:56.975405Z","steps":["trace[1484718737] 'range keys from in-memory index tree' (duration: 860.799384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:05:56.975499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:05:56.11428Z","time spent":"861.208964ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T03:05:56.976211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.632233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-b8zz5\" ","response":"range_response_count:1 size:4466"} {"level":"info","ts":"2026-05-14T03:05:56.976323Z","caller":"traceutil/trace.go:171","msg":"trace[1747059964] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-b8zz5; range_end:; response_count:1; response_revision:392; }","duration":"716.789365ms","start":"2026-05-14T03:05:56.259511Z","end":"2026-05-14T03:05:56.976301Z","steps":["trace[1747059964] 'range keys from in-memory index tree' (duration: 716.542341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:05:56.976379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:05:56.259493Z","time spent":"716.870837ms","remote":"127.0.0.1:41564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":4490,"request content":"key:\"/registry/pods/kube-system/kube-proxy-b8zz5\" "} {"level":"info","ts":"2026-05-14T03:05:56.982733Z","caller":"traceutil/trace.go:171","msg":"trace[774058095] linearizableReadLoop","detail":"{readStateIndex:402; appliedIndex:401; }","duration":"109.76065ms","start":"2026-05-14T03:05:56.872956Z","end":"2026-05-14T03:05:56.982717Z","steps":["trace[774058095] 'read index received' (duration: 109.635238ms)","trace[774058095] 'applied index is now lower than readState.Index' (duration: 124.392µs)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:05:56.982834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.872492ms","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-05-14T03:05:56.982812Z","caller":"traceutil/trace.go:171","msg":"trace[318422676] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"183.778418ms","start":"2026-05-14T03:05:56.799004Z","end":"2026-05-14T03:05:56.982782Z","steps":["trace[318422676] 'process raft request' (duration: 183.606455ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:05:56.982869Z","caller":"traceutil/trace.go:171","msg":"trace[1367286379] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:393; }","duration":"109.908153ms","start":"2026-05-14T03:05:56.872951Z","end":"2026-05-14T03:05:56.982859Z","steps":["trace[1367286379] 'agreement among raft nodes before linearized reading' (duration: 109.841442ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:06:02.778152Z","caller":"traceutil/trace.go:171","msg":"trace[743122621] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:423; }","duration":"462.879374ms","start":"2026-05-14T03:06:02.315204Z","end":"2026-05-14T03:06:02.778084Z","steps":["trace[743122621] 'read index received' (duration: 462.758181ms)","trace[743122621] 'applied index is now lower than readState.Index' (duration: 120.183µs)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:06:02.778277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.050157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-05-14T03:06:02.778309Z","caller":"traceutil/trace.go:171","msg":"trace[27799346] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:414; }","duration":"463.125919ms","start":"2026-05-14T03:06:02.315172Z","end":"2026-05-14T03:06:02.778298Z","steps":["trace[27799346] 'agreement among raft nodes before linearized reading' (duration: 463.045637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:02.778342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:02.315159Z","time spent":"463.175789ms","remote":"127.0.0.1:41826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4080,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-05-14T03:06:02.778446Z","caller":"traceutil/trace.go:171","msg":"trace[373372758] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"757.920751ms","start":"2026-05-14T03:06:02.020497Z","end":"2026-05-14T03:06:02.778418Z","steps":["trace[373372758] 'process raft request' (duration: 757.410461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:02.778974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:02.020482Z","time spent":"758.020432ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:06:07.239676Z","caller":"traceutil/trace.go:171","msg":"trace[158349108] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"209.300733ms","start":"2026-05-14T03:06:07.030354Z","end":"2026-05-14T03:06:07.239655Z","steps":["trace[158349108] 'process raft request' (duration: 209.189861ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:06:07.239697Z","caller":"traceutil/trace.go:171","msg":"trace[2084377040] linearizableReadLoop","detail":"{readStateIndex:464; appliedIndex:464; }","duration":"207.011548ms","start":"2026-05-14T03:06:07.03264Z","end":"2026-05-14T03:06:07.239652Z","steps":["trace[2084377040] 'read index received' (duration: 206.994797ms)","trace[2084377040] 'applied index is now lower than readState.Index' (duration: 13.921µs)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:06:07.239814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.165591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-14T03:06:07.239855Z","caller":"traceutil/trace.go:171","msg":"trace[1062083933] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:453; }","duration":"207.279773ms","start":"2026-05-14T03:06:07.032566Z","end":"2026-05-14T03:06:07.239846Z","steps":["trace[1062083933] 'agreement among raft nodes before linearized reading' (duration: 207.196582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:07.269734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.653647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-14T03:06:07.269801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.802303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:06:07.269816Z","caller":"traceutil/trace.go:171","msg":"trace[1887265405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:454; }","duration":"135.771498ms","start":"2026-05-14T03:06:07.134026Z","end":"2026-05-14T03:06:07.269797Z","steps":["trace[1887265405] 'agreement among raft nodes before linearized reading' (duration: 135.620775ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:06:07.269851Z","caller":"traceutil/trace.go:171","msg":"trace[1083154337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:454; }","duration":"129.877674ms","start":"2026-05-14T03:06:07.139955Z","end":"2026-05-14T03:06:07.269833Z","steps":["trace[1083154337] 'agreement among raft nodes before linearized reading' (duration: 129.784642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:07.517323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.622428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T03:06:07.518363Z","caller":"traceutil/trace.go:171","msg":"trace[546814300] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"243.240925ms","start":"2026-05-14T03:06:07.274197Z","end":"2026-05-14T03:06:07.517438Z","steps":["trace[546814300] 'process raft request' (duration: 90.213931ms)","trace[546814300] 'compare' (duration: 152.056815ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:06:08.838023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:08.411908Z","time spent":"426.107112ms","remote":"127.0.0.1:41404","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-14T03:06:09.419061Z","caller":"traceutil/trace.go:171","msg":"trace[1290484955] linearizableReadLoop","detail":"{readStateIndex:470; appliedIndex:468; }","duration":"277.422882ms","start":"2026-05-14T03:06:09.141616Z","end":"2026-05-14T03:06:09.419039Z","steps":["trace[1290484955] 'read index received' (duration: 249.584299ms)","trace[1290484955] 'applied index is now lower than readState.Index' (duration: 27.837583ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:06:09.419106Z","caller":"traceutil/trace.go:171","msg":"trace[842754789] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"579.442345ms","start":"2026-05-14T03:06:08.839632Z","end":"2026-05-14T03:06:09.419074Z","steps":["trace[842754789] 'process raft request' (duration: 551.444638ms)","trace[842754789] 'compare' (duration: 27.739072ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:06:09.419263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.626066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T03:06:09.419283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:08.839612Z","time spent":"579.578717ms","remote":"127.0.0.1:41404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:06:09.419314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.110249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-14T03:06:09.419329Z","caller":"traceutil/trace.go:171","msg":"trace[924071495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:458; }","duration":"277.724328ms","start":"2026-05-14T03:06:09.141579Z","end":"2026-05-14T03:06:09.419303Z","steps":["trace[924071495] 'agreement among raft nodes before linearized reading' (duration: 277.539904ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:06:09.419355Z","caller":"traceutil/trace.go:171","msg":"trace[1210849699] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:458; }","duration":"130.240772ms","start":"2026-05-14T03:06:09.289097Z","end":"2026-05-14T03:06:09.419338Z","steps":["trace[1210849699] 'agreement among raft nodes before linearized reading' (duration: 130.116729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:09.419371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.601085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-14T03:06:09.419514Z","caller":"traceutil/trace.go:171","msg":"trace[1542646124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:458; }","duration":"175.801269ms","start":"2026-05-14T03:06:09.243695Z","end":"2026-05-14T03:06:09.419497Z","steps":["trace[1542646124] 'agreement among raft nodes before linearized reading' (duration: 175.511624ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:06:09.419795Z","caller":"traceutil/trace.go:171","msg":"trace[431006418] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"577.491926ms","start":"2026-05-14T03:06:08.841616Z","end":"2026-05-14T03:06:09.419108Z","steps":["trace[431006418] 'process raft request' (duration: 577.366234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:09.419956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:08.841598Z","time spent":"578.250321ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:06:17.79061Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7212407207634164899,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T03:06:18.289502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.519096ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T03:06:18.289613Z","caller":"traceutil/trace.go:171","msg":"trace[1961635189] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"1.00368212s","start":"2026-05-14T03:06:17.28591Z","end":"2026-05-14T03:06:18.289592Z","steps":["trace[1961635189] 'process raft request' (duration: 892.99193ms)","trace[1961635189] 'compare' (duration: 110.034667ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:06:18.2897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:17.285875Z","time spent":"1.003788243s","remote":"127.0.0.1:41424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:06:18.837594Z","caller":"traceutil/trace.go:171","msg":"trace[608468208] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"1.506354217s","start":"2026-05-14T03:06:17.331214Z","end":"2026-05-14T03:06:18.837568Z","steps":["trace[608468208] 'process raft request' (duration: 1.505896458s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:18.837815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:17.331196Z","time spent":"1.50654483s","remote":"127.0.0.1:41424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:06:18.838091Z","caller":"traceutil/trace.go:171","msg":"trace[1395873108] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:595; }","duration":"1.548480049s","start":"2026-05-14T03:06:17.289578Z","end":"2026-05-14T03:06:18.838058Z","steps":["trace[1395873108] 'read index received' (duration: 889.248858ms)","trace[1395873108] 'applied index is now lower than readState.Index' (duration: 659.229691ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:06:18.838246Z","caller":"traceutil/trace.go:171","msg":"trace[1202394282] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.455922373s","start":"2026-05-14T03:06:17.382281Z","end":"2026-05-14T03:06:18.838204Z","steps":["trace[1202394282] 'process raft request' (duration: 1.45523402s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:18.838354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.548758334s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"warn","ts":"2026-05-14T03:06:18.838386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:17.382256Z","time spent":"1.456051794s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:06:18.838497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.381348218s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-14T03:06:18.838413Z","caller":"traceutil/trace.go:171","msg":"trace[568072439] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:582; }","duration":"1.548836686s","start":"2026-05-14T03:06:17.289555Z","end":"2026-05-14T03:06:18.838392Z","steps":["trace[568072439] 'agreement among raft nodes before linearized reading' (duration: 1.548668462s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:18.838616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.199264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.238\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-14T03:06:18.838602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.243414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:06:18.838658Z","caller":"traceutil/trace.go:171","msg":"trace[1409082126] range","detail":"{range_begin:/registry/masterleases/199.19.213.238; range_end:; response_count:1; response_revision:582; }","duration":"427.276525ms","start":"2026-05-14T03:06:18.411372Z","end":"2026-05-14T03:06:18.838649Z","steps":["trace[1409082126] 'agreement among raft nodes before linearized reading' (duration: 427.208094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:18.838661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.352723339s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-14T03:06:18.838693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:18.411349Z","time spent":"427.334547ms","remote":"127.0.0.1:41404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.238\" "} {"level":"warn","ts":"2026-05-14T03:06:18.838691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.356920131s","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-05-14T03:06:18.838749Z","caller":"traceutil/trace.go:171","msg":"trace[1082323226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"696.358855ms","start":"2026-05-14T03:06:18.142301Z","end":"2026-05-14T03:06:18.838659Z","steps":["trace[1082323226] 'agreement among raft nodes before linearized reading' (duration: 696.242343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:18.838772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:17.289546Z","time spent":"1.549171952s","remote":"127.0.0.1:41424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":80727,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-05-14T03:06:18.838762Z","caller":"traceutil/trace.go:171","msg":"trace[1447037589] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"1.357030154s","start":"2026-05-14T03:06:17.481713Z","end":"2026-05-14T03:06:18.838743Z","steps":["trace[1447037589] 'agreement among raft nodes before linearized reading' (duration: 1.356900381s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:18.838982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:17.481701Z","time spent":"1.357268988s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-14T03:06:18.838815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:18.14227Z","time spent":"696.531559ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T03:06:18.839038Z","caller":"traceutil/trace.go:171","msg":"trace[1599381561] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:582; }","duration":"1.38142871s","start":"2026-05-14T03:06:17.457102Z","end":"2026-05-14T03:06:18.838531Z","steps":["trace[1599381561] 'agreement among raft nodes before linearized reading' (duration: 1.381354298s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:18.839151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:17.457083Z","time spent":"1.382053852s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-14T03:06:18.838713Z","caller":"traceutil/trace.go:171","msg":"trace[983047988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"1.352794331s","start":"2026-05-14T03:06:17.485902Z","end":"2026-05-14T03:06:18.838696Z","steps":["trace[983047988] 'agreement among raft nodes before linearized reading' (duration: 1.352698669s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:18.83926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:17.485891Z","time spent":"1.353358471s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T03:06:18.838761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270396864s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-14T03:06:18.839384Z","caller":"traceutil/trace.go:171","msg":"trace[692255673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"1.271063116s","start":"2026-05-14T03:06:17.568298Z","end":"2026-05-14T03:06:18.839361Z","steps":["trace[692255673] 'agreement among raft nodes before linearized reading' (duration: 1.270438745s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:18.83943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:17.568274Z","time spent":"1.271144848s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-14T03:06:25.726813Z","caller":"traceutil/trace.go:171","msg":"trace[568893819] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"357.995523ms","start":"2026-05-14T03:06:25.368794Z","end":"2026-05-14T03:06:25.72679Z","steps":["trace[568893819] 'read index received' (duration: 357.82874ms)","trace[568893819] 'applied index is now lower than readState.Index' (duration: 165.643µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:06:25.726914Z","caller":"traceutil/trace.go:171","msg":"trace[1163419182] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"374.70823ms","start":"2026-05-14T03:06:25.352141Z","end":"2026-05-14T03:06:25.726849Z","steps":["trace[1163419182] 'process raft request' (duration: 374.504536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:25.72702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.196638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:06:25.727078Z","caller":"traceutil/trace.go:171","msg":"trace[567459566] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:629; }","duration":"358.30593ms","start":"2026-05-14T03:06:25.368759Z","end":"2026-05-14T03:06:25.727065Z","steps":["trace[567459566] 'agreement among raft nodes before linearized reading' (duration: 358.131366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:25.727132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:25.368742Z","time spent":"358.377421ms","remote":"127.0.0.1:38836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-14T03:06:25.72723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.708571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:06:25.727289Z","caller":"traceutil/trace.go:171","msg":"trace[1452308003] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:629; }","duration":"251.822043ms","start":"2026-05-14T03:06:25.475451Z","end":"2026-05-14T03:06:25.727273Z","steps":["trace[1452308003] 'agreement among raft nodes before linearized reading' (duration: 251.705141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:25.727259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.285352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T03:06:25.72713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.192728ms","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-05-14T03:06:25.727399Z","caller":"traceutil/trace.go:171","msg":"trace[431321564] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:629; }","duration":"358.512834ms","start":"2026-05-14T03:06:25.368875Z","end":"2026-05-14T03:06:25.727387Z","steps":["trace[431321564] 'agreement among raft nodes before linearized reading' (duration: 358.209038ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:06:25.727359Z","caller":"traceutil/trace.go:171","msg":"trace[81906947] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:629; }","duration":"212.420274ms","start":"2026-05-14T03:06:25.514924Z","end":"2026-05-14T03:06:25.727345Z","steps":["trace[81906947] 'agreement among raft nodes before linearized reading' (duration: 212.1881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:25.727436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:25.368844Z","time spent":"358.581046ms","remote":"127.0.0.1:38836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-14T03:06:25.727381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.732782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T03:06:25.727409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.790731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T03:06:25.727511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.519727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:06:25.727567Z","caller":"traceutil/trace.go:171","msg":"trace[1541738512] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:629; }","duration":"252.580488ms","start":"2026-05-14T03:06:25.474973Z","end":"2026-05-14T03:06:25.727554Z","steps":["trace[1541738512] 'agreement among raft nodes before linearized reading' (duration: 252.499127ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:06:25.727561Z","caller":"traceutil/trace.go:171","msg":"trace[976966499] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:629; }","duration":"230.019197ms","start":"2026-05-14T03:06:25.497517Z","end":"2026-05-14T03:06:25.727536Z","steps":["trace[976966499] 'agreement among raft nodes before linearized reading' (duration: 229.6528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:25.72768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.647977ms","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-05-14T03:06:25.727718Z","caller":"traceutil/trace.go:171","msg":"trace[190771218] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:629; }","duration":"175.710188ms","start":"2026-05-14T03:06:25.552Z","end":"2026-05-14T03:06:25.72771Z","steps":["trace[190771218] 'agreement among raft nodes before linearized reading' (duration: 175.643587ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:06:25.727571Z","caller":"traceutil/trace.go:171","msg":"trace[1365195509] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:629; }","duration":"175.982774ms","start":"2026-05-14T03:06:25.551545Z","end":"2026-05-14T03:06:25.727528Z","steps":["trace[1365195509] 'agreement among raft nodes before linearized reading' (duration: 175.726159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:25.727934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.33994ms","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-05-14T03:06:25.727981Z","caller":"traceutil/trace.go:171","msg":"trace[860214395] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:629; }","duration":"176.445162ms","start":"2026-05-14T03:06:25.551525Z","end":"2026-05-14T03:06:25.72797Z","steps":["trace[860214395] 'agreement among raft nodes before linearized reading' (duration: 176.376021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:25.728027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.810711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T03:06:25.728122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.13399ms","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-05-14T03:06:25.728148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.121059ms","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-05-14T03:06:25.728179Z","caller":"traceutil/trace.go:171","msg":"trace[740999648] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:629; }","duration":"189.233002ms","start":"2026-05-14T03:06:25.538937Z","end":"2026-05-14T03:06:25.72817Z","steps":["trace[740999648] 'agreement among raft nodes before linearized reading' (duration: 189.15664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:25.728196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.730952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:06:25.728209Z","caller":"traceutil/trace.go:171","msg":"trace[325198055] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:629; }","duration":"189.15699ms","start":"2026-05-14T03:06:25.539017Z","end":"2026-05-14T03:06:25.728174Z","steps":["trace[325198055] 'agreement among raft nodes before linearized reading' (duration: 189.106239ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:06:25.728236Z","caller":"traceutil/trace.go:171","msg":"trace[464608340] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:629; }","duration":"229.774473ms","start":"2026-05-14T03:06:25.498451Z","end":"2026-05-14T03:06:25.728226Z","steps":["trace[464608340] 'agreement among raft nodes before linearized reading' (duration: 229.711052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:25.728308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.371852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:06:25.728346Z","caller":"traceutil/trace.go:171","msg":"trace[1983738570] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:629; }","duration":"213.445064ms","start":"2026-05-14T03:06:25.514891Z","end":"2026-05-14T03:06:25.728336Z","steps":["trace[1983738570] 'agreement among raft nodes before linearized reading' (duration: 213.386343ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:06:25.728311Z","caller":"traceutil/trace.go:171","msg":"trace[165194395] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:629; }","duration":"253.186738ms","start":"2026-05-14T03:06:25.475052Z","end":"2026-05-14T03:06:25.728239Z","steps":["trace[165194395] 'agreement among raft nodes before linearized reading' (duration: 252.20062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:25.728486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.477645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:06:25.728528Z","caller":"traceutil/trace.go:171","msg":"trace[907446274] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:629; }","duration":"213.548656ms","start":"2026-05-14T03:06:25.51497Z","end":"2026-05-14T03:06:25.728518Z","steps":["trace[907446274] 'agreement among raft nodes before linearized reading' (duration: 213.488855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:25.72888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:25.352114Z","time spent":"376.685909ms","remote":"127.0.0.1:41564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:06:25.728343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.70506ms","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-05-14T03:06:25.730001Z","caller":"traceutil/trace.go:171","msg":"trace[1592662896] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:629; }","duration":"232.425854ms","start":"2026-05-14T03:06:25.49756Z","end":"2026-05-14T03:06:25.729986Z","steps":["trace[1592662896] 'agreement among raft nodes before linearized reading' (duration: 230.686789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:27.643185Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7212407207634165072,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T03:06:28.14415Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7212407207634165072,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T03:06:28.263838Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.302731264s","expected-duration":"1s"} {"level":"info","ts":"2026-05-14T03:06:28.26431Z","caller":"traceutil/trace.go:171","msg":"trace[1133280048] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"1.303284495s","start":"2026-05-14T03:06:26.961001Z","end":"2026-05-14T03:06:28.264285Z","steps":["trace[1133280048] 'process raft request' (duration: 1.303095512s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:28.264462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:26.960983Z","time spent":"1.303394407s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:06:28.293262Z","caller":"traceutil/trace.go:171","msg":"trace[1620560524] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"1.331790491s","start":"2026-05-14T03:06:26.961446Z","end":"2026-05-14T03:06:28.293237Z","steps":["trace[1620560524] 'process raft request' (duration: 1.331618778s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:28.293433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:26.96143Z","time spent":"1.331903793s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:06:28.293519Z","caller":"traceutil/trace.go:171","msg":"trace[1176932723] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"1.331580317s","start":"2026-05-14T03:06:26.961899Z","end":"2026-05-14T03:06:28.293479Z","steps":["trace[1176932723] 'process raft request' (duration: 1.331288341s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:28.293622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:26.961874Z","time spent":"1.331700171s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:06:28.293725Z","caller":"traceutil/trace.go:171","msg":"trace[196858166] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.331541086s","start":"2026-05-14T03:06:26.962174Z","end":"2026-05-14T03:06:28.293715Z","steps":["trace[196858166] 'process raft request' (duration: 1.331189709s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:28.293775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:26.962163Z","time spent":"1.331587397s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:06:28.293866Z","caller":"traceutil/trace.go:171","msg":"trace[72205071] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:647; }","duration":"1.1507791s","start":"2026-05-14T03:06:27.142812Z","end":"2026-05-14T03:06:28.293591Z","steps":["trace[72205071] 'read index received' (duration: 1.121306385s)","trace[72205071] 'applied index is now lower than readState.Index' (duration: 29.471535ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:06:28.293975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.151150458s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:06:28.294046Z","caller":"traceutil/trace.go:171","msg":"trace[77903419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"1.151271719s","start":"2026-05-14T03:06:27.142757Z","end":"2026-05-14T03:06:28.294029Z","steps":["trace[77903419] 'agreement among raft nodes before linearized reading' (duration: 1.151148117s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:28.29411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:27.142734Z","time spent":"1.151363162s","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T03:06:39.098805Z","caller":"traceutil/trace.go:171","msg":"trace[328402477] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"199.499562ms","start":"2026-05-14T03:06:38.899285Z","end":"2026-05-14T03:06:39.098784Z","steps":["trace[328402477] 'process raft request' (duration: 199.233307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:44.860655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.066114ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:06:44.860801Z","caller":"traceutil/trace.go:171","msg":"trace[978235498] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:781; }","duration":"381.206237ms","start":"2026-05-14T03:06:44.479561Z","end":"2026-05-14T03:06:44.860767Z","steps":["trace[978235498] 'range keys from in-memory index tree' (duration: 381.052224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:44.864701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.04285ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-05-14T03:06:44.864921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.339202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:06:44.86499Z","caller":"traceutil/trace.go:171","msg":"trace[2051326299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:781; }","duration":"722.452834ms","start":"2026-05-14T03:06:44.142523Z","end":"2026-05-14T03:06:44.864976Z","steps":["trace[2051326299] 'agreement among raft nodes before linearized reading' (duration: 722.336032ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:06:44.864986Z","caller":"traceutil/trace.go:171","msg":"trace[986134826] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:803; }","duration":"722.196799ms","start":"2026-05-14T03:06:44.142571Z","end":"2026-05-14T03:06:44.864768Z","steps":["trace[986134826] 'read index received' (duration: 454.047667ms)","trace[986134826] 'applied index is now lower than readState.Index' (duration: 268.147962ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:06:44.865025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:44.142502Z","time spent":"722.512985ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T03:06:44.865508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.365508ms","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-05-14T03:06:44.865537Z","caller":"traceutil/trace.go:171","msg":"trace[237748682] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:781; }","duration":"468.44256ms","start":"2026-05-14T03:06:44.397087Z","end":"2026-05-14T03:06:44.86553Z","steps":["trace[237748682] 'agreement among raft nodes before linearized reading' (duration: 468.343157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:44.865557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:44.397056Z","time spent":"468.496021ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-14T03:06:44.865721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.337308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-14T03:06:44.865712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.750406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-l9dxp\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-14T03:06:44.865752Z","caller":"traceutil/trace.go:171","msg":"trace[234213184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:781; }","duration":"445.38812ms","start":"2026-05-14T03:06:44.420357Z","end":"2026-05-14T03:06:44.865745Z","steps":["trace[234213184] 'agreement among raft nodes before linearized reading' (duration: 445.303508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:44.865716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.829572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-14T03:06:44.865781Z","caller":"traceutil/trace.go:171","msg":"trace[2068705779] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-l9dxp; range_end:; response_count:1; response_revision:781; }","duration":"427.840607ms","start":"2026-05-14T03:06:44.43792Z","end":"2026-05-14T03:06:44.865761Z","steps":["trace[2068705779] 'agreement among raft nodes before linearized reading' (duration: 427.577562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:44.865828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.047842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-14T03:06:44.865837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:44.437914Z","time spent":"427.914059ms","remote":"127.0.0.1:41564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3989,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-l9dxp\" "} {"level":"info","ts":"2026-05-14T03:06:44.865854Z","caller":"traceutil/trace.go:171","msg":"trace[737096221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:781; }","duration":"446.109164ms","start":"2026-05-14T03:06:44.419738Z","end":"2026-05-14T03:06:44.865847Z","steps":["trace[737096221] 'agreement among raft nodes before linearized reading' (duration: 446.045852ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:06:44.865838Z","caller":"traceutil/trace.go:171","msg":"trace[204203909] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:781; }","duration":"399.025545ms","start":"2026-05-14T03:06:44.466785Z","end":"2026-05-14T03:06:44.86581Z","steps":["trace[204203909] 'agreement among raft nodes before linearized reading' (duration: 398.679427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:44.865895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:44.419713Z","time spent":"446.176635ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T03:06:44.865918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:44.466773Z","time spent":"399.131648ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T03:06:44.865772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:44.420346Z","time spent":"445.42164ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T03:06:44.866163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.116864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-14T03:06:44.866283Z","caller":"traceutil/trace.go:171","msg":"trace[969439122] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:781; }","duration":"445.288037ms","start":"2026-05-14T03:06:44.420915Z","end":"2026-05-14T03:06:44.866204Z","steps":["trace[969439122] 'agreement among raft nodes before linearized reading' (duration: 444.521522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:06:44.86754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:06:44.420905Z","time spent":"445.567582ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T03:07:19.720083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.318898ms","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-05-14T03:07:19.720176Z","caller":"traceutil/trace.go:171","msg":"trace[1432175494] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1002; }","duration":"491.479331ms","start":"2026-05-14T03:07:19.228676Z","end":"2026-05-14T03:07:19.720156Z","steps":["trace[1432175494] 'count revisions from in-memory index tree' (duration: 491.233466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:19.720238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:19.228654Z","time spent":"491.572783ms","remote":"127.0.0.1:41654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-05-14T03:07:20.607367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.965543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T03:07:20.607438Z","caller":"traceutil/trace.go:171","msg":"trace[620754068] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1035; }","duration":"464.049346ms","start":"2026-05-14T03:07:20.143379Z","end":"2026-05-14T03:07:20.607428Z","steps":["trace[620754068] 'read index received' (duration: 44.131µs)","trace[620754068] 'applied index is now lower than readState.Index' (duration: 464.004465ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:07:20.607509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.137417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:07:20.607521Z","caller":"traceutil/trace.go:171","msg":"trace[910093626] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1004; }","duration":"464.149167ms","start":"2026-05-14T03:07:20.143367Z","end":"2026-05-14T03:07:20.607516Z","steps":["trace[910093626] 'agreement among raft nodes before linearized reading' (duration: 464.086906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:20.607538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:20.143308Z","time spent":"464.224589ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T03:07:20.607642Z","caller":"traceutil/trace.go:171","msg":"trace[1369680188] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"531.997602ms","start":"2026-05-14T03:07:20.0756Z","end":"2026-05-14T03:07:20.607597Z","steps":["trace[1369680188] 'process raft request' (duration: 45.750293ms)","trace[1369680188] 'compare' (duration: 485.718569ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:07:20.607826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:20.075574Z","time spent":"532.155145ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:07:41.607006Z","caller":"traceutil/trace.go:171","msg":"trace[973975042] linearizableReadLoop","detail":"{readStateIndex:1107; appliedIndex:1106; }","duration":"447.165406ms","start":"2026-05-14T03:07:41.159802Z","end":"2026-05-14T03:07:41.606967Z","steps":["trace[973975042] 'read index received' (duration: 446.912822ms)","trace[973975042] 'applied index is now lower than readState.Index' (duration: 251.244µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:07:41.60707Z","caller":"traceutil/trace.go:171","msg":"trace[1569553947] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"453.221904ms","start":"2026-05-14T03:07:41.153822Z","end":"2026-05-14T03:07:41.607044Z","steps":["trace[1569553947] 'process raft request' (duration: 452.926488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:41.607238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:41.15381Z","time spent":"453.335737ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:07:41.607261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.32695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-14T03:07:41.607302Z","caller":"traceutil/trace.go:171","msg":"trace[2057563221] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1071; }","duration":"443.398112ms","start":"2026-05-14T03:07:41.163893Z","end":"2026-05-14T03:07:41.607291Z","steps":["trace[2057563221] 'agreement among raft nodes before linearized reading' (duration: 443.25227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:41.60727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.418381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-14T03:07:41.60731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.892332ms","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":"warn","ts":"2026-05-14T03:07:41.607334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:41.163887Z","time spent":"443.439814ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-14T03:07:41.607349Z","caller":"traceutil/trace.go:171","msg":"trace[433153418] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1071; }","duration":"441.956265ms","start":"2026-05-14T03:07:41.165381Z","end":"2026-05-14T03:07:41.607337Z","steps":["trace[433153418] 'agreement among raft nodes before linearized reading' (duration: 441.846012ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:41.607351Z","caller":"traceutil/trace.go:171","msg":"trace[727420908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1071; }","duration":"447.547444ms","start":"2026-05-14T03:07:41.159786Z","end":"2026-05-14T03:07:41.607333Z","steps":["trace[727420908] 'agreement among raft nodes before linearized reading' (duration: 447.283848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:41.607378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:41.165372Z","time spent":"442.001046ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T03:07:41.607394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:41.159779Z","time spent":"447.606045ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T03:07:41.607399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.494062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-14T03:07:41.607479Z","caller":"traceutil/trace.go:171","msg":"trace[375241158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1071; }","duration":"408.613915ms","start":"2026-05-14T03:07:41.198844Z","end":"2026-05-14T03:07:41.607458Z","steps":["trace[375241158] 'agreement among raft nodes before linearized reading' (duration: 408.357999ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:41.607521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:41.198826Z","time spent":"408.686386ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T03:07:43.643963Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7212407207634166035,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T03:07:44.145062Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7212407207634166035,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T03:07:44.21787Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.213557244s","expected-duration":"1s"} {"level":"info","ts":"2026-05-14T03:07:44.2185Z","caller":"traceutil/trace.go:171","msg":"trace[1491375528] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"1.214236366s","start":"2026-05-14T03:07:43.004189Z","end":"2026-05-14T03:07:44.218425Z","steps":["trace[1491375528] 'process raft request' (duration: 1.213869049s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:44.21897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:43.004166Z","time spent":"1.214457751s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:07:44.308667Z","caller":"traceutil/trace.go:171","msg":"trace[653813319] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1112; }","duration":"1.165715891s","start":"2026-05-14T03:07:43.142922Z","end":"2026-05-14T03:07:44.308638Z","steps":["trace[653813319] 'read index received' (duration: 1.075217714s)","trace[653813319] 'applied index is now lower than readState.Index' (duration: 90.496247ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:07:44.308762Z","caller":"traceutil/trace.go:171","msg":"trace[552812911] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"411.784436ms","start":"2026-05-14T03:07:43.896938Z","end":"2026-05-14T03:07:44.308723Z","steps":["trace[552812911] 'process raft request' (duration: 411.527801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:44.30892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:43.896919Z","time spent":"411.916948ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:07:44.372535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229595876s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T03:07:44.372578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.721533ms","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-05-14T03:07:44.372603Z","caller":"traceutil/trace.go:171","msg":"trace[486182870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1078; }","duration":"1.229700088s","start":"2026-05-14T03:07:43.142883Z","end":"2026-05-14T03:07:44.372583Z","steps":["trace[486182870] 'agreement among raft nodes before linearized reading' (duration: 1.165865523s)","trace[486182870] 'range keys from in-memory index tree' (duration: 63.730633ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:07:44.372634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.856669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-14T03:07:44.372655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:43.142866Z","time spent":"1.229781351s","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T03:07:44.372671Z","caller":"traceutil/trace.go:171","msg":"trace[1251597544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1078; }","duration":"760.92913ms","start":"2026-05-14T03:07:43.611729Z","end":"2026-05-14T03:07:44.372659Z","steps":["trace[1251597544] 'agreement among raft nodes before linearized reading' (duration: 697.041053ms)","trace[1251597544] 'range keys from in-memory index tree' (duration: 63.744103ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:07:44.372657Z","caller":"traceutil/trace.go:171","msg":"trace[1214539237] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1078; }","duration":"702.838026ms","start":"2026-05-14T03:07:43.669798Z","end":"2026-05-14T03:07:44.372636Z","steps":["trace[1214539237] 'agreement among raft nodes before linearized reading' (duration: 638.97762ms)","trace[1214539237] 'range keys from in-memory index tree' (duration: 63.640622ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:07:44.372695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:43.611709Z","time spent":"760.981392ms","remote":"127.0.0.1:41630","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-05-14T03:07:44.372719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:43.66979Z","time spent":"702.918158ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T03:07:44.372704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.667767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-14T03:07:44.372784Z","caller":"traceutil/trace.go:171","msg":"trace[1596709673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1078; }","duration":"735.78472ms","start":"2026-05-14T03:07:43.636977Z","end":"2026-05-14T03:07:44.372761Z","steps":["trace[1596709673] 'agreement among raft nodes before linearized reading' (duration: 671.811791ms)","trace[1596709673] 'range keys from in-memory index tree' (duration: 63.801236ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:07:44.372826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:43.636955Z","time spent":"735.863121ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T03:07:44.372945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.092991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T03:07:44.372975Z","caller":"traceutil/trace.go:171","msg":"trace[498919074] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1078; }","duration":"703.132052ms","start":"2026-05-14T03:07:43.669832Z","end":"2026-05-14T03:07:44.372964Z","steps":["trace[498919074] 'agreement among raft nodes before linearized reading' (duration: 638.961789ms)","trace[498919074] 'range keys from in-memory index tree' (duration: 64.0999ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:07:44.373003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:43.669828Z","time spent":"703.169122ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T03:07:44.373115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.766065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-14T03:07:44.373195Z","caller":"traceutil/trace.go:171","msg":"trace[1843119463] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1078; }","duration":"703.880807ms","start":"2026-05-14T03:07:43.669294Z","end":"2026-05-14T03:07:44.373175Z","steps":["trace[1843119463] 'agreement among raft nodes before linearized reading' (duration: 639.49102ms)","trace[1843119463] 'range keys from in-memory index tree' (duration: 63.59455ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:07:44.373238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:43.669284Z","time spent":"703.938448ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-14T03:07:44.501465Z","caller":"traceutil/trace.go:171","msg":"trace[1793246759] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"124.266135ms","start":"2026-05-14T03:07:44.377165Z","end":"2026-05-14T03:07:44.501431Z","steps":["trace[1793246759] 'process raft request' (duration: 118.304288ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:44.501752Z","caller":"traceutil/trace.go:171","msg":"trace[946274631] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"123.352767ms","start":"2026-05-14T03:07:44.378388Z","end":"2026-05-14T03:07:44.501741Z","steps":["trace[946274631] 'process raft request' (duration: 123.119872ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:44.501952Z","caller":"traceutil/trace.go:171","msg":"trace[735553903] linearizableReadLoop","detail":"{readStateIndex:1120; appliedIndex:1116; }","duration":"120.718795ms","start":"2026-05-14T03:07:44.381204Z","end":"2026-05-14T03:07:44.501923Z","steps":["trace[735553903] 'read index received' (duration: 30.218049ms)","trace[735553903] 'applied index is now lower than readState.Index' (duration: 90.498796ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:07:44.501984Z","caller":"traceutil/trace.go:171","msg":"trace[1068811377] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"123.50083ms","start":"2026-05-14T03:07:44.378469Z","end":"2026-05-14T03:07:44.50197Z","steps":["trace[1068811377] 'process raft request' (duration: 123.235175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:44.50206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.843928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:07:44.502104Z","caller":"traceutil/trace.go:171","msg":"trace[911791991] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1083; }","duration":"120.926779ms","start":"2026-05-14T03:07:44.381163Z","end":"2026-05-14T03:07:44.50209Z","steps":["trace[911791991] 'agreement among raft nodes before linearized reading' (duration: 120.840018ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:44.501646Z","caller":"traceutil/trace.go:171","msg":"trace[1088959972] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"124.356537ms","start":"2026-05-14T03:07:44.377234Z","end":"2026-05-14T03:07:44.50159Z","steps":["trace[1088959972] 'process raft request' (duration: 124.03207ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:46.832205Z","caller":"traceutil/trace.go:171","msg":"trace[611876831] linearizableReadLoop","detail":"{readStateIndex:1121; appliedIndex:1120; }","duration":"416.282833ms","start":"2026-05-14T03:07:46.415899Z","end":"2026-05-14T03:07:46.832182Z","steps":["trace[611876831] 'read index received' (duration: 416.072069ms)","trace[611876831] 'applied index is now lower than readState.Index' (duration: 209.864µs)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:07:46.832506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.59159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-14T03:07:46.832557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.175481ms","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":"info","ts":"2026-05-14T03:07:46.832584Z","caller":"traceutil/trace.go:171","msg":"trace[2132455271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1084; }","duration":"416.698161ms","start":"2026-05-14T03:07:46.41585Z","end":"2026-05-14T03:07:46.832548Z","steps":["trace[2132455271] 'agreement among raft nodes before linearized reading' (duration: 416.495057ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:46.83264Z","caller":"traceutil/trace.go:171","msg":"trace[928342639] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1084; }","duration":"210.316145ms","start":"2026-05-14T03:07:46.622302Z","end":"2026-05-14T03:07:46.832618Z","steps":["trace[928342639] 'agreement among raft nodes before linearized reading' (duration: 210.176322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:46.832665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:46.415816Z","time spent":"416.816795ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T03:07:46.832728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.218136ms","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-05-14T03:07:46.83277Z","caller":"traceutil/trace.go:171","msg":"trace[754535019] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1084; }","duration":"326.303878ms","start":"2026-05-14T03:07:46.506457Z","end":"2026-05-14T03:07:46.83276Z","steps":["trace[754535019] 'agreement among raft nodes before linearized reading' (duration: 326.188316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:46.832726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.122319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T03:07:46.832799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:46.506429Z","time spent":"326.364389ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-14T03:07:46.832653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.780507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-14T03:07:46.832838Z","caller":"traceutil/trace.go:171","msg":"trace[1604817148] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1084; }","duration":"194.322443ms","start":"2026-05-14T03:07:46.638488Z","end":"2026-05-14T03:07:46.832811Z","steps":["trace[1604817148] 'agreement among raft nodes before linearized reading' (duration: 194.101358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:46.832854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.313948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-14T03:07:46.832915Z","caller":"traceutil/trace.go:171","msg":"trace[709615073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1084; }","duration":"327.175415ms","start":"2026-05-14T03:07:46.505688Z","end":"2026-05-14T03:07:46.832864Z","steps":["trace[709615073] 'agreement among raft nodes before linearized reading' (duration: 326.693525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:46.832948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.319378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-14T03:07:46.832288Z","caller":"traceutil/trace.go:171","msg":"trace[1016674038] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"519.003598ms","start":"2026-05-14T03:07:46.313264Z","end":"2026-05-14T03:07:46.832268Z","steps":["trace[1016674038] 'process raft request' (duration: 518.760093ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:46.833041Z","caller":"traceutil/trace.go:171","msg":"trace[1398806308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1084; }","duration":"326.44926ms","start":"2026-05-14T03:07:46.506581Z","end":"2026-05-14T03:07:46.833031Z","steps":["trace[1398806308] 'agreement among raft nodes before linearized reading' (duration: 326.258216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:46.833103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:46.506549Z","time spent":"326.543542ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T03:07:46.833121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:46.313242Z","time spent":"519.809814ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:07:46.833104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:46.505656Z","time spent":"327.367089ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-14T03:07:46.832954Z","caller":"traceutil/trace.go:171","msg":"trace[533522625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1084; }","duration":"326.473741ms","start":"2026-05-14T03:07:46.506457Z","end":"2026-05-14T03:07:46.832931Z","steps":["trace[533522625] 'agreement among raft nodes before linearized reading' (duration: 326.171605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:46.833264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:46.506435Z","time spent":"326.819977ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-14T03:07:47.130088Z","caller":"traceutil/trace.go:171","msg":"trace[867939607] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"292.992547ms","start":"2026-05-14T03:07:46.837067Z","end":"2026-05-14T03:07:47.130059Z","steps":["trace[867939607] 'process raft request' (duration: 220.634175ms)","trace[867939607] 'compare' (duration: 72.157798ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:07:47.270191Z","caller":"traceutil/trace.go:171","msg":"trace[661675382] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"431.781056ms","start":"2026-05-14T03:07:46.838388Z","end":"2026-05-14T03:07:47.270169Z","steps":["trace[661675382] 'process raft request' (duration: 431.691114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:47.270662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:46.838359Z","time spent":"432.248235ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:07:47.270266Z","caller":"traceutil/trace.go:171","msg":"trace[1134907049] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"432.881548ms","start":"2026-05-14T03:07:46.837347Z","end":"2026-05-14T03:07:47.270229Z","steps":["trace[1134907049] 'process raft request' (duration: 426.266278ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:47.270379Z","caller":"traceutil/trace.go:171","msg":"trace[257327662] linearizableReadLoop","detail":"{readStateIndex:1126; appliedIndex:1121; }","duration":"287.312347ms","start":"2026-05-14T03:07:46.983054Z","end":"2026-05-14T03:07:47.270367Z","steps":["trace[257327662] 'read index received' (duration: 74.763438ms)","trace[257327662] 'applied index is now lower than readState.Index' (duration: 212.547919ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:07:47.270469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.398579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:07:47.271008Z","caller":"traceutil/trace.go:171","msg":"trace[1109107243] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1089; }","duration":"287.98476ms","start":"2026-05-14T03:07:46.983008Z","end":"2026-05-14T03:07:47.270993Z","steps":["trace[1109107243] 'agreement among raft nodes before linearized reading' (duration: 287.395868ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:47.270507Z","caller":"traceutil/trace.go:171","msg":"trace[942907599] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"431.677554ms","start":"2026-05-14T03:07:46.83882Z","end":"2026-05-14T03:07:47.270498Z","steps":["trace[942907599] 'process raft request' (duration: 431.295137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:47.271227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:46.838802Z","time spent":"432.341457ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:07:47.270534Z","caller":"traceutil/trace.go:171","msg":"trace[179710639] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"431.483851ms","start":"2026-05-14T03:07:46.839043Z","end":"2026-05-14T03:07:47.270527Z","steps":["trace[179710639] 'process raft request' (duration: 431.267716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:07:47.271469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:46.839033Z","time spent":"432.289326ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:07:47.270819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:46.837339Z","time spent":"433.435018ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:07:57.465231Z","caller":"traceutil/trace.go:171","msg":"trace[703275147] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"137.279639ms","start":"2026-05-14T03:07:57.327929Z","end":"2026-05-14T03:07:57.465208Z","steps":["trace[703275147] 'process raft request' (duration: 137.217777ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:57.465727Z","caller":"traceutil/trace.go:171","msg":"trace[941624230] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"138.305309ms","start":"2026-05-14T03:07:57.327406Z","end":"2026-05-14T03:07:57.465711Z","steps":["trace[941624230] 'process raft request' (duration: 137.704137ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:07:57.465882Z","caller":"traceutil/trace.go:171","msg":"trace[2053549552] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"138.532063ms","start":"2026-05-14T03:07:57.32734Z","end":"2026-05-14T03:07:57.465872Z","steps":["trace[2053549552] 'process raft request' (duration: 137.650915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:00.792192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.323025809s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-14T03:08:00.792278Z","caller":"traceutil/trace.go:171","msg":"trace[1306293047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1177; }","duration":"1.323152571s","start":"2026-05-14T03:07:59.469105Z","end":"2026-05-14T03:08:00.792258Z","steps":["trace[1306293047] 'range keys from in-memory index tree' (duration: 1.322868486s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:00.792263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.322807665s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-14T03:08:00.792325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:59.469099Z","time spent":"1.323216052s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-14T03:08:00.792331Z","caller":"traceutil/trace.go:171","msg":"trace[271223322] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1177; }","duration":"1.322902017s","start":"2026-05-14T03:07:59.469411Z","end":"2026-05-14T03:08:00.792313Z","steps":["trace[271223322] 'range keys from in-memory index tree' (duration: 1.322667802s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:00.792412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.323506048s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-14T03:08:00.792438Z","caller":"traceutil/trace.go:171","msg":"trace[133387117] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1177; }","duration":"1.323531858s","start":"2026-05-14T03:07:59.468898Z","end":"2026-05-14T03:08:00.79243Z","steps":["trace[133387117] 'range keys from in-memory index tree' (duration: 1.323389096s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:00.792439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:59.469403Z","time spent":"1.323019799s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T03:08:00.792467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:07:59.468848Z","time spent":"1.32361193s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T03:08:00.792827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.995559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:08:00.792905Z","caller":"traceutil/trace.go:171","msg":"trace[969070019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1177; }","duration":"475.180373ms","start":"2026-05-14T03:08:00.317703Z","end":"2026-05-14T03:08:00.792884Z","steps":["trace[969070019] 'range keys from in-memory index tree' (duration: 474.917838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:00.792949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:00.317685Z","time spent":"475.254384ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T03:08:01.643269Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7212407207634166266,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T03:08:01.73596Z","caller":"traceutil/trace.go:171","msg":"trace[735387667] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"939.426273ms","start":"2026-05-14T03:08:00.796504Z","end":"2026-05-14T03:08:01.73593Z","steps":["trace[735387667] 'process raft request' (duration: 939.29809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:01.736118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:00.796484Z","time spent":"939.529114ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:08:01.773894Z","caller":"traceutil/trace.go:171","msg":"trace[1090291744] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"977.303702ms","start":"2026-05-14T03:08:00.796563Z","end":"2026-05-14T03:08:01.773867Z","steps":["trace[1090291744] 'process raft request' (duration: 977.128519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:01.774095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:00.796541Z","time spent":"977.428854ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:08:01.774166Z","caller":"traceutil/trace.go:171","msg":"trace[662203254] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"977.361223ms","start":"2026-05-14T03:08:00.796753Z","end":"2026-05-14T03:08:01.774114Z","steps":["trace[662203254] 'process raft request' (duration: 977.079178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:01.774277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:00.796732Z","time spent":"977.485456ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:08:01.77439Z","caller":"traceutil/trace.go:171","msg":"trace[1344021031] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"899.338121ms","start":"2026-05-14T03:08:00.875042Z","end":"2026-05-14T03:08:01.77438Z","steps":["trace[1344021031] 'process raft request' (duration: 898.983134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:01.774437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:00.875027Z","time spent":"899.390802ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:08:01.774367Z","caller":"traceutil/trace.go:171","msg":"trace[1223707092] linearizableReadLoop","detail":"{readStateIndex:1224; appliedIndex:1219; }","duration":"631.723598ms","start":"2026-05-14T03:08:01.142623Z","end":"2026-05-14T03:08:01.774347Z","steps":["trace[1223707092] 'read index received' (duration: 593.119475ms)","trace[1223707092] 'applied index is now lower than readState.Index' (duration: 38.602843ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:08:01.774469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.8455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:08:01.774516Z","caller":"traceutil/trace.go:171","msg":"trace[1733043529] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"249.698153ms","start":"2026-05-14T03:08:01.524804Z","end":"2026-05-14T03:08:01.774502Z","steps":["trace[1733043529] 'process raft request' (duration: 249.456569ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:08:01.774549Z","caller":"traceutil/trace.go:171","msg":"trace[424701813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1182; }","duration":"631.973323ms","start":"2026-05-14T03:08:01.142556Z","end":"2026-05-14T03:08:01.774529Z","steps":["trace[424701813] 'agreement among raft nodes before linearized reading' (duration: 631.84859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:01.774616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:01.142536Z","time spent":"632.063485ms","remote":"127.0.0.1:41370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-14T03:08:01.774626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.1145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-14T03:08:01.77466Z","caller":"traceutil/trace.go:171","msg":"trace[1743928510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1182; }","duration":"521.169771ms","start":"2026-05-14T03:08:01.253483Z","end":"2026-05-14T03:08:01.774653Z","steps":["trace[1743928510] 'agreement among raft nodes before linearized reading' (duration: 521.060249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:01.774678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:01.253471Z","time spent":"521.203172ms","remote":"127.0.0.1:41630","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-05-14T03:08:01.774762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.570142ms","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-05-14T03:08:01.774819Z","caller":"traceutil/trace.go:171","msg":"trace[963245675] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1182; }","duration":"430.676074ms","start":"2026-05-14T03:08:01.344137Z","end":"2026-05-14T03:08:01.774813Z","steps":["trace[963245675] 'agreement among raft nodes before linearized reading' (duration: 430.596283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:01.774836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.851733ms","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-05-14T03:08:01.774883Z","caller":"traceutil/trace.go:171","msg":"trace[416786852] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1182; }","duration":"337.949446ms","start":"2026-05-14T03:08:01.43692Z","end":"2026-05-14T03:08:01.774869Z","steps":["trace[416786852] 'agreement among raft nodes before linearized reading' (duration: 337.777612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:01.774932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:01.436905Z","time spent":"338.014947ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-14T03:08:01.77507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:01.344108Z","time spent":"430.724416ms","remote":"127.0.0.1:38836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-05-14T03:08:14.08659Z","caller":"traceutil/trace.go:171","msg":"trace[1755485000] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"294.405786ms","start":"2026-05-14T03:08:13.792161Z","end":"2026-05-14T03:08:14.086566Z","steps":["trace[1755485000] 'process raft request' (duration: 294.247603ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:08:14.087793Z","caller":"traceutil/trace.go:171","msg":"trace[1676142989] linearizableReadLoop","detail":"{readStateIndex:1264; appliedIndex:1262; }","duration":"251.829744ms","start":"2026-05-14T03:08:13.835937Z","end":"2026-05-14T03:08:14.087767Z","steps":["trace[1676142989] 'read index received' (duration: 250.498548ms)","trace[1676142989] 'applied index is now lower than readState.Index' (duration: 1.330196ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:08:14.087841Z","caller":"traceutil/trace.go:171","msg":"trace[368696261] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"257.719509ms","start":"2026-05-14T03:08:13.830088Z","end":"2026-05-14T03:08:14.087807Z","steps":["trace[368696261] 'process raft request' (duration: 257.536926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:14.088017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.067719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-14T03:08:14.08806Z","caller":"traceutil/trace.go:171","msg":"trace[352676623] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1219; }","duration":"252.154261ms","start":"2026-05-14T03:08:13.835893Z","end":"2026-05-14T03:08:14.088048Z","steps":["trace[352676623] 'agreement among raft nodes before linearized reading' (duration: 251.999468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:14.088081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.906398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-14T03:08:14.088083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.95549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-14T03:08:14.088147Z","caller":"traceutil/trace.go:171","msg":"trace[574786926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1219; }","duration":"229.000709ms","start":"2026-05-14T03:08:13.85913Z","end":"2026-05-14T03:08:14.08813Z","steps":["trace[574786926] 'agreement among raft nodes before linearized reading' (duration: 228.841206ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:08:14.088159Z","caller":"traceutil/trace.go:171","msg":"trace[1542044229] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1219; }","duration":"247.081443ms","start":"2026-05-14T03:08:13.841059Z","end":"2026-05-14T03:08:14.088141Z","steps":["trace[1542044229] 'agreement among raft nodes before linearized reading' (duration: 246.863818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:14.088241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.099012ms","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-05-14T03:08:14.088302Z","caller":"traceutil/trace.go:171","msg":"trace[522624300] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1219; }","duration":"229.193913ms","start":"2026-05-14T03:08:13.859092Z","end":"2026-05-14T03:08:14.088286Z","steps":["trace[522624300] 'agreement among raft nodes before linearized reading' (duration: 228.99825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:14.088239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.061537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:08:14.088833Z","caller":"traceutil/trace.go:171","msg":"trace[907346435] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1219; }","duration":"111.73625ms","start":"2026-05-14T03:08:13.977063Z","end":"2026-05-14T03:08:14.088799Z","steps":["trace[907346435] 'agreement among raft nodes before linearized reading' (duration: 110.970776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:16.839259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.227507ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T03:08:16.839451Z","caller":"traceutil/trace.go:171","msg":"trace[650455682] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1281; }","duration":"133.160403ms","start":"2026-05-14T03:08:16.706275Z","end":"2026-05-14T03:08:16.839435Z","steps":["trace[650455682] 'read index received' (duration: 3.602809ms)","trace[650455682] 'applied index is now lower than readState.Index' (duration: 129.556693ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:08:16.839543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.259204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:08:16.839526Z","caller":"traceutil/trace.go:171","msg":"trace[184861538] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"662.942438ms","start":"2026-05-14T03:08:16.176547Z","end":"2026-05-14T03:08:16.839489Z","steps":["trace[184861538] 'process raft request' (duration: 533.379814ms)","trace[184861538] 'compare' (duration: 129.083355ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:08:16.839575Z","caller":"traceutil/trace.go:171","msg":"trace[2050536446] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1238; }","duration":"133.327685ms","start":"2026-05-14T03:08:16.706236Z","end":"2026-05-14T03:08:16.839564Z","steps":["trace[2050536446] 'agreement among raft nodes before linearized reading' (duration: 133.249563ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:08:16.839602Z","caller":"traceutil/trace.go:171","msg":"trace[1415488303] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"662.04299ms","start":"2026-05-14T03:08:16.177457Z","end":"2026-05-14T03:08:16.8395Z","steps":["trace[1415488303] 'process raft request' (duration: 661.890477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:16.839681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:16.176542Z","time spent":"663.05791ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:08:16.839763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:16.177432Z","time spent":"662.256003ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:08:17.842797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.37812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:08:17.842886Z","caller":"traceutil/trace.go:171","msg":"trace[1263611139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1238; }","duration":"700.494653ms","start":"2026-05-14T03:08:17.14237Z","end":"2026-05-14T03:08:17.842865Z","steps":["trace[1263611139] 'range keys from in-memory index tree' (duration: 700.286048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:17.842938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:17.142355Z","time spent":"700.564684ms","remote":"127.0.0.1:41374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T03:08:18.245462Z","caller":"traceutil/trace.go:171","msg":"trace[334047247] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"124.216769ms","start":"2026-05-14T03:08:18.121193Z","end":"2026-05-14T03:08:18.24541Z","steps":["trace[334047247] 'process raft request' (duration: 124.016886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:18.467173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.209325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T03:08:18.467287Z","caller":"traceutil/trace.go:171","msg":"trace[1917337085] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"180.316414ms","start":"2026-05-14T03:08:18.286951Z","end":"2026-05-14T03:08:18.467267Z","steps":["trace[1917337085] 'process raft request' (duration: 63.926825ms)","trace[1917337085] 'compare' (duration: 116.086872ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:08:18.522083Z","caller":"traceutil/trace.go:171","msg":"trace[102512662] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"234.565162ms","start":"2026-05-14T03:08:18.287491Z","end":"2026-05-14T03:08:18.522056Z","steps":["trace[102512662] 'process raft request' (duration: 234.44034ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:08:18.522688Z","caller":"traceutil/trace.go:171","msg":"trace[444799717] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1287; }","duration":"103.837395ms","start":"2026-05-14T03:08:18.418816Z","end":"2026-05-14T03:08:18.522654Z","steps":["trace[444799717] 'read index received' (duration: 103.831385ms)","trace[444799717] 'applied index is now lower than readState.Index' (duration: 5.27µs)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:08:18.522855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.028779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-14T03:08:18.522895Z","caller":"traceutil/trace.go:171","msg":"trace[1632084351] range","detail":"{range_begin:/registry/masterleases/199.19.213.238; range_end:; response_count:1; response_revision:1242; }","duration":"104.11463ms","start":"2026-05-14T03:08:18.41877Z","end":"2026-05-14T03:08:18.522885Z","steps":["trace[1632084351] 'agreement among raft nodes before linearized reading' (duration: 103.958647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:18.685015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.214525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-14T03:08:18.685257Z","caller":"traceutil/trace.go:171","msg":"trace[1878812178] linearizableReadLoop","detail":"{readStateIndex:1289; appliedIndex:1287; }","duration":"153.671799ms","start":"2026-05-14T03:08:18.531568Z","end":"2026-05-14T03:08:18.68524Z","steps":["trace[1878812178] 'read index received' (duration: 51.159669ms)","trace[1878812178] 'applied index is now lower than readState.Index' (duration: 102.51082ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:08:18.685336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.762551ms","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-05-14T03:08:18.68537Z","caller":"traceutil/trace.go:171","msg":"trace[2026466483] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1243; }","duration":"153.818801ms","start":"2026-05-14T03:08:18.53154Z","end":"2026-05-14T03:08:18.685359Z","steps":["trace[2026466483] 'agreement among raft nodes before linearized reading' (duration: 153.7546ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:08:18.685672Z","caller":"traceutil/trace.go:171","msg":"trace[1443494010] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"265.143751ms","start":"2026-05-14T03:08:18.420491Z","end":"2026-05-14T03:08:18.685634Z","steps":["trace[1443494010] 'process raft request' (duration: 162.227384ms)","trace[1443494010] 'compare' (duration: 101.97458ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:08:20.531655Z","caller":"traceutil/trace.go:171","msg":"trace[1806259943] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"500.831115ms","start":"2026-05-14T03:08:20.030796Z","end":"2026-05-14T03:08:20.531627Z","steps":["trace[1806259943] 'process raft request' (duration: 500.57802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:20.532165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:20.030769Z","time spent":"500.961078ms","remote":"127.0.0.1:41450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T03:08:20.551442Z","caller":"traceutil/trace.go:171","msg":"trace[1338991846] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1292; }","duration":"263.084982ms","start":"2026-05-14T03:08:20.28834Z","end":"2026-05-14T03:08:20.551425Z","steps":["trace[1338991846] 'read index received' (duration: 242.957424ms)","trace[1338991846] 'applied index is now lower than readState.Index' (duration: 20.126818ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:08:20.55174Z","caller":"traceutil/trace.go:171","msg":"trace[1599970166] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"300.380462ms","start":"2026-05-14T03:08:20.251348Z","end":"2026-05-14T03:08:20.551729Z","steps":["trace[1599970166] 'process raft request' (duration: 299.948764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:20.551844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:20.251326Z","time spent":"300.437824ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:08:20.552032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.705004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-14T03:08:20.55213Z","caller":"traceutil/trace.go:171","msg":"trace[1954026916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1248; }","duration":"263.861017ms","start":"2026-05-14T03:08:20.288259Z","end":"2026-05-14T03:08:20.552121Z","steps":["trace[1954026916] 'agreement among raft nodes before linearized reading' (duration: 263.702093ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:08:20.646751Z","caller":"traceutil/trace.go:171","msg":"trace[233492250] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"103.453349ms","start":"2026-05-14T03:08:20.543247Z","end":"2026-05-14T03:08:20.6467Z","steps":["trace[233492250] 'process raft request' (duration: 90.681572ms)","trace[233492250] 'compare' (duration: 12.598633ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:08:20.646859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.354487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-14T03:08:20.646913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.575221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T03:08:20.64697Z","caller":"traceutil/trace.go:171","msg":"trace[890726340] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1249; }","duration":"175.53338ms","start":"2026-05-14T03:08:20.47142Z","end":"2026-05-14T03:08:20.646953Z","steps":["trace[890726340] 'agreement among raft nodes before linearized reading' (duration: 175.253305ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:08:20.647Z","caller":"traceutil/trace.go:171","msg":"trace[1883542540] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1249; }","duration":"132.728063ms","start":"2026-05-14T03:08:20.514252Z","end":"2026-05-14T03:08:20.64698Z","steps":["trace[1883542540] 'agreement among raft nodes before linearized reading' (duration: 132.539389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:20.647195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.081419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-14T03:08:20.647315Z","caller":"traceutil/trace.go:171","msg":"trace[313326333] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1249; }","duration":"120.375036ms","start":"2026-05-14T03:08:20.526914Z","end":"2026-05-14T03:08:20.647289Z","steps":["trace[313326333] 'agreement among raft nodes before linearized reading' (duration: 119.829304ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:08:20.691808Z","caller":"traceutil/trace.go:171","msg":"trace[336431119] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"132.797456ms","start":"2026-05-14T03:08:20.558984Z","end":"2026-05-14T03:08:20.691782Z","steps":["trace[336431119] 'process raft request' (duration: 132.627402ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:08:21.739171Z","caller":"traceutil/trace.go:171","msg":"trace[2115441178] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"341.575049ms","start":"2026-05-14T03:08:21.397569Z","end":"2026-05-14T03:08:21.739144Z","steps":["trace[2115441178] 'process raft request' (duration: 341.334785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:21.739352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:21.397545Z","time spent":"341.702701ms","remote":"127.0.0.1:41560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5457,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:08:22.420332Z","caller":"traceutil/trace.go:171","msg":"trace[1230964700] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"673.451009ms","start":"2026-05-14T03:08:21.746859Z","end":"2026-05-14T03:08:22.42031Z","steps":["trace[1230964700] 'process raft request' (duration: 673.318207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:22.420497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:21.746838Z","time spent":"673.549532ms","remote":"127.0.0.1:41764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T03:08:22.439548Z","caller":"traceutil/trace.go:171","msg":"trace[108764323] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"109.331353ms","start":"2026-05-14T03:08:22.330189Z","end":"2026-05-14T03:08:22.43952Z","steps":["trace[108764323] 'process raft request' (duration: 109.285073ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:08:22.440095Z","caller":"traceutil/trace.go:171","msg":"trace[1517207818] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"203.92995ms","start":"2026-05-14T03:08:22.236145Z","end":"2026-05-14T03:08:22.440075Z","steps":["trace[1517207818] 'process raft request' (duration: 203.188095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:22.786619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.221039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T03:08:22.78677Z","caller":"traceutil/trace.go:171","msg":"trace[631227104] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"227.372843ms","start":"2026-05-14T03:08:22.559376Z","end":"2026-05-14T03:08:22.786749Z","steps":["trace[631227104] 'process raft request' (duration: 42.92681ms)","trace[631227104] 'compare' (duration: 183.79964ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:08:23.008588Z","caller":"traceutil/trace.go:171","msg":"trace[18661332] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"216.93286ms","start":"2026-05-14T03:08:22.791622Z","end":"2026-05-14T03:08:23.008555Z","steps":["trace[18661332] 'process raft request' (duration: 216.844709ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:08:23.008792Z","caller":"traceutil/trace.go:171","msg":"trace[721683897] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"217.221997ms","start":"2026-05-14T03:08:22.791558Z","end":"2026-05-14T03:08:23.00878Z","steps":["trace[721683897] 'process raft request' (duration: 177.158043ms)","trace[721683897] 'compare' (duration: 39.609405ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:08:23.008899Z","caller":"traceutil/trace.go:171","msg":"trace[789009988] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1306; }","duration":"132.700553ms","start":"2026-05-14T03:08:22.876177Z","end":"2026-05-14T03:08:23.008878Z","steps":["trace[789009988] 'read index received' (duration: 7.463405ms)","trace[789009988] 'applied index is now lower than readState.Index' (duration: 125.234898ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:08:23.009136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.944878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-14T03:08:23.009214Z","caller":"traceutil/trace.go:171","msg":"trace[1496789918] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1264; }","duration":"133.066181ms","start":"2026-05-14T03:08:22.876133Z","end":"2026-05-14T03:08:23.0092Z","steps":["trace[1496789918] 'agreement among raft nodes before linearized reading' (duration: 132.853627ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:08:23.009258Z","caller":"traceutil/trace.go:171","msg":"trace[1606273569] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"139.332701ms","start":"2026-05-14T03:08:22.869915Z","end":"2026-05-14T03:08:23.009248Z","steps":["trace[1606273569] 'process raft request' (duration: 138.588487ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:08:31.065467Z","caller":"traceutil/trace.go:171","msg":"trace[2075962250] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1380; }","duration":"436.342858ms","start":"2026-05-14T03:08:30.629098Z","end":"2026-05-14T03:08:31.065441Z","steps":["trace[2075962250] 'read index received' (duration: 436.250566ms)","trace[2075962250] 'applied index is now lower than readState.Index' (duration: 91.172µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:08:31.065494Z","caller":"traceutil/trace.go:171","msg":"trace[1560676292] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"713.059905ms","start":"2026-05-14T03:08:30.35241Z","end":"2026-05-14T03:08:31.06547Z","steps":["trace[1560676292] 'process raft request' (duration: 712.857041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:31.065636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:30.352383Z","time spent":"713.172256ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:08:31.566145Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7212407207634166579,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T03:08:32.066919Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7212407207634166579,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T03:08:32.23022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.6011193s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-14T03:08:32.230306Z","caller":"traceutil/trace.go:171","msg":"trace[1775364108] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1333; }","duration":"1.601274752s","start":"2026-05-14T03:08:30.629009Z","end":"2026-05-14T03:08:32.230284Z","steps":["trace[1775364108] 'agreement among raft nodes before linearized reading' (duration: 436.549442ms)","trace[1775364108] 'range keys from in-memory index tree' (duration: 1.164497515s)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:08:32.230347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:30.628974Z","time spent":"1.601363764s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-14T03:08:32.387802Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.322492378s","expected-duration":"1s"} {"level":"info","ts":"2026-05-14T03:08:32.388084Z","caller":"traceutil/trace.go:171","msg":"trace[414309744] linearizableReadLoop","detail":"{readStateIndex:1382; appliedIndex:1381; }","duration":"1.322529249s","start":"2026-05-14T03:08:31.065537Z","end":"2026-05-14T03:08:32.388066Z","steps":["trace[414309744] 'read index received' (duration: 1.322486738s)","trace[414309744] 'applied index is now lower than readState.Index' (duration: 41.4µs)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:08:32.388373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.385638857s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-14T03:08:32.388431Z","caller":"traceutil/trace.go:171","msg":"trace[2078564150] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1334; }","duration":"1.385741429s","start":"2026-05-14T03:08:31.00268Z","end":"2026-05-14T03:08:32.388422Z","steps":["trace[2078564150] 'agreement among raft nodes before linearized reading' (duration: 1.385561826s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:32.388472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:31.002659Z","time spent":"1.38580512s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T03:08:32.38854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.34596476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-14T03:08:32.388429Z","caller":"traceutil/trace.go:171","msg":"trace[1370291681] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"1.573557588s","start":"2026-05-14T03:08:30.814852Z","end":"2026-05-14T03:08:32.38841Z","steps":["trace[1370291681] 'process raft request' (duration: 1.5731196s)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:08:32.388606Z","caller":"traceutil/trace.go:171","msg":"trace[1407195897] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1334; }","duration":"1.346054752s","start":"2026-05-14T03:08:31.042537Z","end":"2026-05-14T03:08:32.388592Z","steps":["trace[1407195897] 'agreement among raft nodes before linearized reading' (duration: 1.34591926s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:32.388674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:31.042522Z","time spent":"1.346144024s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-14T03:08:32.388735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:30.814817Z","time spent":"1.573849764s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:08:32.388357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.346238595s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-14T03:08:32.388763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.538619442s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:08:32.388853Z","caller":"traceutil/trace.go:171","msg":"trace[257998133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1334; }","duration":"1.346760856s","start":"2026-05-14T03:08:31.042084Z","end":"2026-05-14T03:08:32.388845Z","steps":["trace[257998133] 'agreement among raft nodes before linearized reading' (duration: 1.346086272s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:32.388914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:31.042017Z","time spent":"1.346888799s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-14T03:08:32.38889Z","caller":"traceutil/trace.go:171","msg":"trace[933823219] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1334; }","duration":"1.538810256s","start":"2026-05-14T03:08:30.850062Z","end":"2026-05-14T03:08:32.388872Z","steps":["trace[933823219] 'agreement among raft nodes before linearized reading' (duration: 1.538582491s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:32.388986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:30.85004Z","time spent":"1.538931248s","remote":"127.0.0.1:38708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-14T03:08:32.390238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.310985276s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-14T03:08:32.390272Z","caller":"traceutil/trace.go:171","msg":"trace[828168702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1335; }","duration":"1.311019996s","start":"2026-05-14T03:08:31.079243Z","end":"2026-05-14T03:08:32.390263Z","steps":["trace[828168702] 'agreement among raft nodes before linearized reading' (duration: 1.310932305s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:32.390292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:31.079182Z","time spent":"1.311106088s","remote":"127.0.0.1:41630","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-05-14T03:08:32.390401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.88512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T03:08:32.390404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.164558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-14T03:08:32.390424Z","caller":"traceutil/trace.go:171","msg":"trace[758134173] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1335; }","duration":"949.949781ms","start":"2026-05-14T03:08:31.440468Z","end":"2026-05-14T03:08:32.390418Z","steps":["trace[758134173] 'agreement among raft nodes before linearized reading' (duration: 949.912291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:32.390445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:31.440444Z","time spent":"949.994722ms","remote":"127.0.0.1:38758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-05-14T03:08:32.390469Z","caller":"traceutil/trace.go:171","msg":"trace[385935029] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1335; }","duration":"492.24596ms","start":"2026-05-14T03:08:31.898187Z","end":"2026-05-14T03:08:32.390433Z","steps":["trace[385935029] 'agreement among raft nodes before linearized reading' (duration: 492.061056ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:08:32.390515Z","caller":"traceutil/trace.go:171","msg":"trace[2122408326] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"155.743619ms","start":"2026-05-14T03:08:32.234753Z","end":"2026-05-14T03:08:32.390497Z","steps":["trace[2122408326] 'process raft request' (duration: 155.3528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:32.39051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:31.89816Z","time spent":"492.342021ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-14T03:08:32.390585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.27295986s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T03:08:32.390639Z","caller":"traceutil/trace.go:171","msg":"trace[1882619206] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1335; }","duration":"1.273019621s","start":"2026-05-14T03:08:31.117608Z","end":"2026-05-14T03:08:32.390628Z","steps":["trace[1882619206] 'agreement among raft nodes before linearized reading' (duration: 1.272868418s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:32.390672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033613907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-14T03:08:32.390678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.682095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T03:08:32.390676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:31.117574Z","time spent":"1.273094863s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-14T03:08:32.390701Z","caller":"traceutil/trace.go:171","msg":"trace[297664623] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1335; }","duration":"1.033672447s","start":"2026-05-14T03:08:31.357022Z","end":"2026-05-14T03:08:32.390694Z","steps":["trace[297664623] 'agreement among raft nodes before linearized reading' (duration: 1.033619236s)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:08:32.390704Z","caller":"traceutil/trace.go:171","msg":"trace[1744578082] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1335; }","duration":"247.739966ms","start":"2026-05-14T03:08:32.142957Z","end":"2026-05-14T03:08:32.390697Z","steps":["trace[1744578082] 'agreement among raft nodes before linearized reading' (duration: 247.706446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:08:32.390728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:08:31.357005Z","time spent":"1.033714538s","remote":"127.0.0.1:41808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-05-14T03:09:09.411063Z","caller":"traceutil/trace.go:171","msg":"trace[2013088101] transaction","detail":"{read_only:false; response_revision:1510; number_of_response:1; }","duration":"172.715896ms","start":"2026-05-14T03:09:09.238316Z","end":"2026-05-14T03:09:09.411032Z","steps":["trace[2013088101] 'process raft request' (duration: 172.512802ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:09:17.671102Z","caller":"traceutil/trace.go:171","msg":"trace[920684319] linearizableReadLoop","detail":"{readStateIndex:1603; appliedIndex:1602; }","duration":"140.962873ms","start":"2026-05-14T03:09:17.530111Z","end":"2026-05-14T03:09:17.671074Z","steps":["trace[920684319] 'read index received' (duration: 100.220437ms)","trace[920684319] 'applied index is now lower than readState.Index' (duration: 40.741236ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:09:17.671275Z","caller":"traceutil/trace.go:171","msg":"trace[2034445631] transaction","detail":"{read_only:false; response_revision:1546; number_of_response:1; }","duration":"163.138902ms","start":"2026-05-14T03:09:17.508049Z","end":"2026-05-14T03:09:17.671188Z","steps":["trace[2034445631] 'process raft request' (duration: 122.288583ms)","trace[2034445631] 'compare' (duration: 40.619754ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:09:17.671585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.448422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-14T03:09:17.671664Z","caller":"traceutil/trace.go:171","msg":"trace[2092093959] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1546; }","duration":"141.588495ms","start":"2026-05-14T03:09:17.530055Z","end":"2026-05-14T03:09:17.671643Z","steps":["trace[2092093959] 'agreement among raft nodes before linearized reading' (duration: 141.115926ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:09:22.857408Z","caller":"traceutil/trace.go:171","msg":"trace[144349586] linearizableReadLoop","detail":"{readStateIndex:1625; appliedIndex:1624; }","duration":"152.977966ms","start":"2026-05-14T03:09:22.704331Z","end":"2026-05-14T03:09:22.857309Z","steps":["trace[144349586] 'read index received' (duration: 149.07696ms)","trace[144349586] 'applied index is now lower than readState.Index' (duration: 3.898366ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:09:22.857435Z","caller":"traceutil/trace.go:171","msg":"trace[1762484438] transaction","detail":"{read_only:false; response_revision:1567; number_of_response:1; }","duration":"153.100628ms","start":"2026-05-14T03:09:22.704295Z","end":"2026-05-14T03:09:22.857395Z","steps":["trace[1762484438] 'process raft request' (duration: 149.099221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:22.857694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.344402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-14T03:09:22.857743Z","caller":"traceutil/trace.go:171","msg":"trace[2057397716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1567; }","duration":"153.410404ms","start":"2026-05-14T03:09:22.704323Z","end":"2026-05-14T03:09:22.857733Z","steps":["trace[2057397716] 'agreement among raft nodes before linearized reading' (duration: 153.19634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:22.859144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.645922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-14T03:09:22.859417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.957253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-14T03:09:22.859471Z","caller":"traceutil/trace.go:171","msg":"trace[549967931] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1568; }","duration":"149.00615ms","start":"2026-05-14T03:09:22.71045Z","end":"2026-05-14T03:09:22.859456Z","steps":["trace[549967931] 'agreement among raft nodes before linearized reading' (duration: 148.596931ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:09:22.859505Z","caller":"traceutil/trace.go:171","msg":"trace[1624525654] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1568; }","duration":"140.083225ms","start":"2026-05-14T03:09:22.719403Z","end":"2026-05-14T03:09:22.859486Z","steps":["trace[1624525654] 'agreement among raft nodes before linearized reading' (duration: 139.819419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:22.859614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.621795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-14T03:09:22.859267Z","caller":"traceutil/trace.go:171","msg":"trace[1657911764] transaction","detail":"{read_only:false; response_revision:1568; number_of_response:1; }","duration":"154.789011ms","start":"2026-05-14T03:09:22.70445Z","end":"2026-05-14T03:09:22.85924Z","steps":["trace[1657911764] 'process raft request' (duration: 154.490285ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:09:22.859732Z","caller":"traceutil/trace.go:171","msg":"trace[1206117197] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1568; }","duration":"141.724718ms","start":"2026-05-14T03:09:22.717961Z","end":"2026-05-14T03:09:22.859686Z","steps":["trace[1206117197] 'agreement among raft nodes before linearized reading' (duration: 141.480443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:32.98363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.099676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-14T03:09:32.98372Z","caller":"traceutil/trace.go:171","msg":"trace[1595406191] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1612; }","duration":"107.198208ms","start":"2026-05-14T03:09:32.8765Z","end":"2026-05-14T03:09:32.983698Z","steps":["trace[1595406191] 'range keys from in-memory index tree' (duration: 106.962984ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:09:38.245834Z","caller":"traceutil/trace.go:171","msg":"trace[1634340809] linearizableReadLoop","detail":"{readStateIndex:1700; appliedIndex:1698; }","duration":"178.193955ms","start":"2026-05-14T03:09:38.067613Z","end":"2026-05-14T03:09:38.245807Z","steps":["trace[1634340809] 'read index received' (duration: 86.67455ms)","trace[1634340809] 'applied index is now lower than readState.Index' (duration: 91.518415ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:09:38.245917Z","caller":"traceutil/trace.go:171","msg":"trace[756520705] transaction","detail":"{read_only:false; response_revision:1639; number_of_response:1; }","duration":"188.892572ms","start":"2026-05-14T03:09:38.056983Z","end":"2026-05-14T03:09:38.245875Z","steps":["trace[756520705] 'process raft request' (duration: 151.955916ms)","trace[756520705] 'compare' (duration: 36.748243ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:09:38.246084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.43992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T03:09:38.246173Z","caller":"traceutil/trace.go:171","msg":"trace[2013546270] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1639; }","duration":"178.572613ms","start":"2026-05-14T03:09:38.067589Z","end":"2026-05-14T03:09:38.246161Z","steps":["trace[2013546270] 'agreement among raft nodes before linearized reading' (duration: 178.397719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:09:38.246155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.092859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:09:38.246246Z","caller":"traceutil/trace.go:171","msg":"trace[742739881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1639; }","duration":"103.254542ms","start":"2026-05-14T03:09:38.142973Z","end":"2026-05-14T03:09:38.246228Z","steps":["trace[742739881] 'agreement among raft nodes before linearized reading' (duration: 103.075879ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:09:39.730456Z","caller":"traceutil/trace.go:171","msg":"trace[1162710568] transaction","detail":"{read_only:false; response_revision:1649; number_of_response:1; }","duration":"128.4059ms","start":"2026-05-14T03:09:39.602016Z","end":"2026-05-14T03:09:39.730422Z","steps":["trace[1162710568] 'process raft request' (duration: 128.219516ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:10:13.427011Z","caller":"traceutil/trace.go:171","msg":"trace[1066114318] linearizableReadLoop","detail":"{readStateIndex:1864; appliedIndex:1863; }","duration":"283.949815ms","start":"2026-05-14T03:10:13.143034Z","end":"2026-05-14T03:10:13.426984Z","steps":["trace[1066114318] 'read index received' (duration: 218.339423ms)","trace[1066114318] 'applied index is now lower than readState.Index' (duration: 65.609052ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:10:13.427144Z","caller":"traceutil/trace.go:171","msg":"trace[1641332624] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"308.363119ms","start":"2026-05-14T03:10:13.118738Z","end":"2026-05-14T03:10:13.427101Z","steps":["trace[1641332624] 'process raft request' (duration: 242.685645ms)","trace[1641332624] 'compare' (duration: 65.409199ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T03:10:13.427213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.162479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:10:13.427287Z","caller":"traceutil/trace.go:171","msg":"trace[505449486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1796; }","duration":"284.277562ms","start":"2026-05-14T03:10:13.142989Z","end":"2026-05-14T03:10:13.427266Z","steps":["trace[505449486] 'agreement among raft nodes before linearized reading' (duration: 284.143769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:10:13.427336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T03:10:13.118721Z","time spent":"308.525621ms","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T03:10:13.42756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.231182ms","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":"warn","ts":"2026-05-14T03:10:13.427644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.148392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1084"} {"level":"warn","ts":"2026-05-14T03:10:13.427616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.653991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-14T03:10:13.427566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.495477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-14T03:10:13.427734Z","caller":"traceutil/trace.go:171","msg":"trace[1112790012] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1796; }","duration":"152.402925ms","start":"2026-05-14T03:10:13.275261Z","end":"2026-05-14T03:10:13.427664Z","steps":["trace[1112790012] 'agreement among raft nodes before linearized reading' (duration: 152.156781ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:10:13.427802Z","caller":"traceutil/trace.go:171","msg":"trace[256496561] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1796; }","duration":"151.747141ms","start":"2026-05-14T03:10:13.276035Z","end":"2026-05-14T03:10:13.427783Z","steps":["trace[256496561] 'agreement among raft nodes before linearized reading' (duration: 151.391965ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:10:13.42778Z","caller":"traceutil/trace.go:171","msg":"trace[25568822] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1796; }","duration":"117.822164ms","start":"2026-05-14T03:10:13.30994Z","end":"2026-05-14T03:10:13.427762Z","steps":["trace[25568822] 'agreement among raft nodes before linearized reading' (duration: 117.540829ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:10:13.427692Z","caller":"traceutil/trace.go:171","msg":"trace[51192153] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:1796; }","duration":"131.219663ms","start":"2026-05-14T03:10:13.296457Z","end":"2026-05-14T03:10:13.427677Z","steps":["trace[51192153] 'agreement among raft nodes before linearized reading' (duration: 131.157292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:10:13.427811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.61468ms","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-05-14T03:10:13.428001Z","caller":"traceutil/trace.go:171","msg":"trace[2038406732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1796; }","duration":"133.823294ms","start":"2026-05-14T03:10:13.294165Z","end":"2026-05-14T03:10:13.427988Z","steps":["trace[2038406732] 'agreement among raft nodes before linearized reading' (duration: 133.207193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:10:13.427783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.92756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-14T03:10:13.428203Z","caller":"traceutil/trace.go:171","msg":"trace[1366944742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1796; }","duration":"160.427291ms","start":"2026-05-14T03:10:13.267756Z","end":"2026-05-14T03:10:13.428183Z","steps":["trace[1366944742] 'agreement among raft nodes before linearized reading' (duration: 159.768648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:10:13.427576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.658411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-05-14T03:10:13.42831Z","caller":"traceutil/trace.go:171","msg":"trace[795640651] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1796; }","duration":"217.465107ms","start":"2026-05-14T03:10:13.210828Z","end":"2026-05-14T03:10:13.428293Z","steps":["trace[795640651] 'agreement among raft nodes before linearized reading' (duration: 216.516008ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:10:13.664984Z","caller":"traceutil/trace.go:171","msg":"trace[177246558] linearizableReadLoop","detail":"{readStateIndex:1870; appliedIndex:1864; }","duration":"193.214326ms","start":"2026-05-14T03:10:13.471744Z","end":"2026-05-14T03:10:13.664958Z","steps":["trace[177246558] 'read index received' (duration: 43.390021ms)","trace[177246558] 'applied index is now lower than readState.Index' (duration: 149.820335ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:10:13.6652Z","caller":"traceutil/trace.go:171","msg":"trace[1212835759] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"231.780975ms","start":"2026-05-14T03:10:13.433408Z","end":"2026-05-14T03:10:13.665189Z","steps":["trace[1212835759] 'process raft request' (duration: 212.47589ms)","trace[1212835759] 'compare' (duration: 18.359446ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:10:13.665229Z","caller":"traceutil/trace.go:171","msg":"trace[1986261945] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"231.707843ms","start":"2026-05-14T03:10:13.433502Z","end":"2026-05-14T03:10:13.66521Z","steps":["trace[1986261945] 'process raft request' (duration: 231.228224ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:10:13.665286Z","caller":"traceutil/trace.go:171","msg":"trace[1306052140] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"231.468827ms","start":"2026-05-14T03:10:13.433806Z","end":"2026-05-14T03:10:13.665274Z","steps":["trace[1306052140] 'process raft request' (duration: 231.025649ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:10:13.665227Z","caller":"traceutil/trace.go:171","msg":"trace[1961812750] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"231.340515ms","start":"2026-05-14T03:10:13.433858Z","end":"2026-05-14T03:10:13.665199Z","steps":["trace[1961812750] 'process raft request' (duration: 231.012709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:10:13.665435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.659785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-14T03:10:13.665457Z","caller":"traceutil/trace.go:171","msg":"trace[1979373590] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"231.267274ms","start":"2026-05-14T03:10:13.43416Z","end":"2026-05-14T03:10:13.665428Z","steps":["trace[1979373590] 'process raft request' (duration: 230.753955ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:10:13.665523Z","caller":"traceutil/trace.go:171","msg":"trace[108023276] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1802; }","duration":"193.786876ms","start":"2026-05-14T03:10:13.471714Z","end":"2026-05-14T03:10:13.665501Z","steps":["trace[108023276] 'agreement among raft nodes before linearized reading' (duration: 193.519352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:10:13.803731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.984652ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-14T03:10:13.803935Z","caller":"traceutil/trace.go:171","msg":"trace[53078636] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"133.531948ms","start":"2026-05-14T03:10:13.670387Z","end":"2026-05-14T03:10:13.803919Z","steps":["trace[53078636] 'process raft request' (duration: 133.423446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T03:10:13.804118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.357929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T03:10:13.804191Z","caller":"traceutil/trace.go:171","msg":"trace[803437326] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1803; }","duration":"274.494112ms","start":"2026-05-14T03:10:13.52968Z","end":"2026-05-14T03:10:13.804174Z","steps":["trace[803437326] 'agreement among raft nodes before linearized reading' (duration: 274.343409ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T03:10:13.803993Z","caller":"traceutil/trace.go:171","msg":"trace[2093013181] linearizableReadLoop","detail":"{readStateIndex:1871; appliedIndex:1870; }","duration":"138.914923ms","start":"2026-05-14T03:10:13.665043Z","end":"2026-05-14T03:10:13.803958Z","steps":["trace[2093013181] 'read index received' (duration: 28.814498ms)","trace[2093013181] 'applied index is now lower than readState.Index' (duration: 110.095865ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T03:11:45.557551Z","caller":"traceutil/trace.go:171","msg":"trace[1115475072] transaction","detail":"{read_only:false; response_revision:2251; number_of_response:1; }","duration":"114.979122ms","start":"2026-05-14T03:11:45.442548Z","end":"2026-05-14T03:11:45.557527Z","steps":["trace[1115475072] 'process raft request' (duration: 114.789469ms)"],"step_count":1}