{"level":"warn","ts":"2026-07-30T21:05:28.540389Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T21:05:28.540485Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.181: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.181:2380","--initial-cluster=instance=https://199.19.213.181:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.181:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.181: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-07-30T21:05:28.540574Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T21:05:28.540597Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.181:2380"]} {"level":"info","ts":"2026-07-30T21:05:28.540623Z","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-07-30T21:05:28.541526Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.181:2379"]} {"level":"info","ts":"2026-07-30T21:05:28.541599Z","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.181:2380"],"listen-peer-urls":["https://199.19.213.181:2380"],"advertise-client-urls":["https://199.19.213.181:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.181:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.181: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-07-30T21:05:28.546436Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.586028ms"} {"level":"info","ts":"2026-07-30T21:05:28.555392Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1a532052a1358225","cluster-id":"b34324311008a1d3"} {"level":"info","ts":"2026-07-30T21:05:28.555503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 switched to configuration voters=()"} {"level":"info","ts":"2026-07-30T21:05:28.555651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became follower at term 0"} {"level":"info","ts":"2026-07-30T21:05:28.55568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1a532052a1358225 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-30T21:05:28.555733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became follower at term 1"} {"level":"info","ts":"2026-07-30T21:05:28.555815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 switched to configuration voters=(1896895407317156389)"} {"level":"warn","ts":"2026-07-30T21:05:28.562565Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-30T21:05:28.564408Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-30T21:05:28.565843Z","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-07-30T21:05:28.569476Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1a532052a1358225","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-30T21:05:28.569859Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1a532052a1358225","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-30T21:05:28.569913Z","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-07-30T21:05:28.570043Z","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-07-30T21:05:28.570062Z","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-07-30T21:05:28.571529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 switched to configuration voters=(1896895407317156389)"} {"level":"info","ts":"2026-07-30T21:05:28.571793Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b34324311008a1d3","local-member-id":"1a532052a1358225","added-peer-id":"1a532052a1358225","added-peer-peer-urls":["https://199.19.213.181:2380"]} {"level":"info","ts":"2026-07-30T21:05:28.575299Z","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-07-30T21:05:28.57558Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1a532052a1358225","initial-advertise-peer-urls":["https://199.19.213.181:2380"],"listen-peer-urls":["https://199.19.213.181:2380"],"advertise-client-urls":["https://199.19.213.181:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.181:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-30T21:05:28.575619Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-30T21:05:28.575753Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.181:2380"} {"level":"info","ts":"2026-07-30T21:05:28.575775Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.181:2380"} {"level":"info","ts":"2026-07-30T21:05:29.357041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 is starting a new election at term 1"} {"level":"info","ts":"2026-07-30T21:05:29.357106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-30T21:05:29.357141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 received MsgPreVoteResp from 1a532052a1358225 at term 1"} {"level":"info","ts":"2026-07-30T21:05:29.357161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became candidate at term 2"} {"level":"info","ts":"2026-07-30T21:05:29.357172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 received MsgVoteResp from 1a532052a1358225 at term 2"} {"level":"info","ts":"2026-07-30T21:05:29.357188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became leader at term 2"} {"level":"info","ts":"2026-07-30T21:05:29.3572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1a532052a1358225 elected leader 1a532052a1358225 at term 2"} {"level":"info","ts":"2026-07-30T21:05:29.358846Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1a532052a1358225","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.181:2379]}","request-path":"/0/members/1a532052a1358225/attributes","cluster-id":"b34324311008a1d3","publish-timeout":"7s"} {"level":"info","ts":"2026-07-30T21:05:29.362228Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T21:05:29.362409Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T21:05:29.362532Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T21:05:29.362567Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-30T21:05:29.362615Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-30T21:05:29.36481Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-30T21:05:29.367081Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.181:2379"} {"level":"info","ts":"2026-07-30T21:05:29.385053Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b34324311008a1d3","local-member-id":"1a532052a1358225","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T21:05:29.385184Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T21:05:29.385232Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T21:06:01.978767Z","caller":"traceutil/trace.go:171","msg":"trace[1198467547] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"376.494767ms","start":"2026-07-30T21:06:01.602248Z","end":"2026-07-30T21:06:01.978742Z","steps":["trace[1198467547] 'process raft request' (duration: 376.378294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:01.979219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:01.602218Z","time spent":"376.623599ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:06:01.984904Z","caller":"traceutil/trace.go:171","msg":"trace[244476158] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"343.975254ms","start":"2026-07-30T21:06:01.640913Z","end":"2026-07-30T21:06:01.984888Z","steps":["trace[244476158] 'process raft request' (duration: 343.901702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:01.985015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:01.640888Z","time spent":"344.083586ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:06:05.625643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.246538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:06:05.625672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.967612ms","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-07-30T21:06:05.625728Z","caller":"traceutil/trace.go:171","msg":"trace[944430400] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:529; }","duration":"649.409092ms","start":"2026-07-30T21:06:04.976303Z","end":"2026-07-30T21:06:05.625712Z","steps":["trace[944430400] 'range keys from in-memory index tree' (duration: 649.185597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:05.625757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:04.976284Z","time spent":"649.465583ms","remote":"127.0.0.1:35872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-07-30T21:06:05.625755Z","caller":"traceutil/trace.go:171","msg":"trace[1852080648] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:529; }","duration":"115.105606ms","start":"2026-07-30T21:06:05.510627Z","end":"2026-07-30T21:06:05.625733Z","steps":["trace[1852080648] 'range keys from in-memory index tree' (duration: 114.85118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:05.625765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.433665ms","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-07-30T21:06:05.625903Z","caller":"traceutil/trace.go:171","msg":"trace[700347049] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:529; }","duration":"510.606189ms","start":"2026-07-30T21:06:05.115264Z","end":"2026-07-30T21:06:05.62587Z","steps":["trace[700347049] 'range keys from in-memory index tree' (duration: 510.292051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:05.625973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:05.115239Z","time spent":"510.717852ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-30T21:06:05.736773Z","caller":"traceutil/trace.go:171","msg":"trace[455869634] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"105.571713ms","start":"2026-07-30T21:06:05.631162Z","end":"2026-07-30T21:06:05.736734Z","steps":["trace[455869634] 'process raft request' (duration: 102.658978ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:11.819198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.785767ms","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-07-30T21:06:11.819304Z","caller":"traceutil/trace.go:171","msg":"trace[1019970762] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"118.940511ms","start":"2026-07-30T21:06:11.700342Z","end":"2026-07-30T21:06:11.819282Z","steps":["trace[1019970762] 'range keys from in-memory index tree' (duration: 118.282054ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:06:12.001393Z","caller":"traceutil/trace.go:171","msg":"trace[689036281] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"178.881949ms","start":"2026-07-30T21:06:11.822485Z","end":"2026-07-30T21:06:12.001367Z","steps":["trace[689036281] 'process raft request' (duration: 178.656133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:12.518592Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378077398147527024,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T21:06:12.904074Z","caller":"traceutil/trace.go:171","msg":"trace[9636018] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.081342403s","start":"2026-07-30T21:06:11.822697Z","end":"2026-07-30T21:06:12.904039Z","steps":["trace[9636018] 'process raft request' (duration: 1.080380268s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:12.904221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:11.822688Z","time spent":"1.081458766s","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:06:12.904346Z","caller":"traceutil/trace.go:171","msg":"trace[184531218] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.022033161s","start":"2026-07-30T21:06:11.882293Z","end":"2026-07-30T21:06:12.904327Z","steps":["trace[184531218] 'process raft request' (duration: 1.021305462s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:12.904487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:11.882262Z","time spent":"1.022152775s","remote":"127.0.0.1:35872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:06:12.915177Z","caller":"traceutil/trace.go:171","msg":"trace[809943093] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:656; }","duration":"897.434003ms","start":"2026-07-30T21:06:12.017722Z","end":"2026-07-30T21:06:12.915156Z","steps":["trace[809943093] 'read index received' (duration: 885.36645ms)","trace[809943093] 'applied index is now lower than readState.Index' (duration: 12.066433ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:06:12.915478Z","caller":"traceutil/trace.go:171","msg":"trace[1846924284] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"632.918139ms","start":"2026-07-30T21:06:12.282544Z","end":"2026-07-30T21:06:12.915462Z","steps":["trace[1846924284] 'process raft request' (duration: 632.497628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:12.915592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:12.282525Z","time spent":"632.998942ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:06:12.915933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.203143ms","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-07-30T21:06:12.915979Z","caller":"traceutil/trace.go:171","msg":"trace[455633224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:637; }","duration":"898.269625ms","start":"2026-07-30T21:06:12.017695Z","end":"2026-07-30T21:06:12.915965Z","steps":["trace[455633224] 'agreement among raft nodes before linearized reading' (duration: 898.07485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:12.916018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:12.01768Z","time spent":"898.327767ms","remote":"127.0.0.1:36040","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-07-30T21:06:12.916292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.837004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:06:12.916326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.042691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jq2cw\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-30T21:06:12.916351Z","caller":"traceutil/trace.go:171","msg":"trace[1745184288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"503.944277ms","start":"2026-07-30T21:06:12.412391Z","end":"2026-07-30T21:06:12.916336Z","steps":["trace[1745184288] 'agreement among raft nodes before linearized reading' (duration: 503.830794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:12.916387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:12.412361Z","time spent":"504.019429ms","remote":"127.0.0.1:35790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T21:06:12.916384Z","caller":"traceutil/trace.go:171","msg":"trace[55316421] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jq2cw; range_end:; response_count:1; response_revision:637; }","duration":"591.122662ms","start":"2026-07-30T21:06:12.325242Z","end":"2026-07-30T21:06:12.916365Z","steps":["trace[55316421] 'agreement among raft nodes before linearized reading' (duration: 590.961478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:12.91643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:12.325227Z","time spent":"591.191124ms","remote":"127.0.0.1:35948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-jq2cw\" "} {"level":"warn","ts":"2026-07-30T21:06:12.916493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.840494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.181\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-30T21:06:12.916537Z","caller":"traceutil/trace.go:171","msg":"trace[1851232678] range","detail":"{range_begin:/registry/masterleases/199.19.213.181; range_end:; response_count:1; response_revision:637; }","duration":"588.901995ms","start":"2026-07-30T21:06:12.327622Z","end":"2026-07-30T21:06:12.916524Z","steps":["trace[1851232678] 'agreement among raft nodes before linearized reading' (duration: 588.824313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:12.916575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:12.327614Z","time spent":"588.950997ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.181\" "} {"level":"info","ts":"2026-07-30T21:06:13.060247Z","caller":"traceutil/trace.go:171","msg":"trace[850154521] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"138.782137ms","start":"2026-07-30T21:06:12.921449Z","end":"2026-07-30T21:06:13.060231Z","steps":["trace[850154521] 'process raft request' (duration: 138.5058ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:06:13.060333Z","caller":"traceutil/trace.go:171","msg":"trace[1214668231] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"132.285998ms","start":"2026-07-30T21:06:12.928038Z","end":"2026-07-30T21:06:13.060324Z","steps":["trace[1214668231] 'process raft request' (duration: 132.008801ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:06:13.060463Z","caller":"traceutil/trace.go:171","msg":"trace[956417940] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"103.057238ms","start":"2026-07-30T21:06:12.957399Z","end":"2026-07-30T21:06:13.060456Z","steps":["trace[956417940] 'process raft request' (duration: 102.684048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:15.193913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.019543106s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:06:15.194201Z","caller":"traceutil/trace.go:171","msg":"trace[1825621839] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"2.132908753s","start":"2026-07-30T21:06:13.061272Z","end":"2026-07-30T21:06:15.194181Z","steps":["trace[1825621839] 'process raft request' (duration: 112.867774ms)","trace[1825621839] 'compare' (duration: 2.014511255s)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:06:15.194324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:13.061261Z","time spent":"2.133013915s","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:06:15.201005Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.026904406s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T21:06:15.201353Z","caller":"traceutil/trace.go:171","msg":"trace[1075598802] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:663; }","duration":"2.129065682s","start":"2026-07-30T21:06:13.072264Z","end":"2026-07-30T21:06:15.20133Z","steps":["trace[1075598802] 'read index received' (duration: 101.888688ms)","trace[1075598802] 'applied index is now lower than readState.Index' (duration: 2.027176124s)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:06:15.201475Z","caller":"traceutil/trace.go:171","msg":"trace[1289308607] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"2.136869874s","start":"2026-07-30T21:06:13.064595Z","end":"2026-07-30T21:06:15.201465Z","steps":["trace[1289308607] 'process raft request' (duration: 2.136620038s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:15.20158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.129304268s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:06:15.201597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:13.064575Z","time spent":"2.136931825s","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:06:15.20162Z","caller":"traceutil/trace.go:171","msg":"trace[1053556653] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:642; }","duration":"2.12936878s","start":"2026-07-30T21:06:13.072238Z","end":"2026-07-30T21:06:15.201607Z","steps":["trace[1053556653] 'agreement among raft nodes before linearized reading' (duration: 2.129277428s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:15.201649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:13.072226Z","time spent":"2.129416911s","remote":"127.0.0.1:36008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-30T21:06:15.203358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.792154667s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:06:15.203394Z","caller":"traceutil/trace.go:171","msg":"trace[1523691921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"1.792234549s","start":"2026-07-30T21:06:13.41115Z","end":"2026-07-30T21:06:15.203384Z","steps":["trace[1523691921] 'agreement among raft nodes before linearized reading' (duration: 1.792162007s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:15.203495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:13.411126Z","time spent":"1.792361872s","remote":"127.0.0.1:35804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T21:06:15.203715Z","caller":"traceutil/trace.go:171","msg":"trace[1269763511] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"111.357245ms","start":"2026-07-30T21:06:15.092337Z","end":"2026-07-30T21:06:15.203694Z","steps":["trace[1269763511] 'process raft request' (duration: 110.890533ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:15.203944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.489269ms","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-07-30T21:06:15.204151Z","caller":"traceutil/trace.go:171","msg":"trace[1575631434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:643; }","duration":"138.723255ms","start":"2026-07-30T21:06:15.06541Z","end":"2026-07-30T21:06:15.204133Z","steps":["trace[1575631434] 'agreement among raft nodes before linearized reading' (duration: 138.313334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:15.203989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.198545029s","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-07-30T21:06:15.204094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.676331ms","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-07-30T21:06:15.204862Z","caller":"traceutil/trace.go:171","msg":"trace[974866595] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:643; }","duration":"297.476222ms","start":"2026-07-30T21:06:14.907374Z","end":"2026-07-30T21:06:15.20485Z","steps":["trace[974866595] 'agreement among raft nodes before linearized reading' (duration: 296.65982ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:06:15.20427Z","caller":"traceutil/trace.go:171","msg":"trace[1077067723] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:643; }","duration":"1.198827407s","start":"2026-07-30T21:06:14.005391Z","end":"2026-07-30T21:06:15.204218Z","steps":["trace[1077067723] 'agreement among raft nodes before linearized reading' (duration: 1.198452497s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:15.205147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:14.005374Z","time spent":"1.199750121s","remote":"127.0.0.1:36040","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-07-30T21:06:16.931947Z","caller":"traceutil/trace.go:171","msg":"trace[840897908] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"463.28594ms","start":"2026-07-30T21:06:16.468642Z","end":"2026-07-30T21:06:16.931928Z","steps":["trace[840897908] 'process raft request' (duration: 463.179448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:16.932062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:16.46863Z","time spent":"463.375652ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T21:06:27.834653Z","caller":"traceutil/trace.go:171","msg":"trace[695694645] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"410.128298ms","start":"2026-07-30T21:06:27.424495Z","end":"2026-07-30T21:06:27.834624Z","steps":["trace[695694645] 'process raft request' (duration: 409.953174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:27.834869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:27.424476Z","time spent":"410.301382ms","remote":"127.0.0.1:35948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5035,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:06:31.343704Z","caller":"traceutil/trace.go:171","msg":"trace[826516213] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:836; }","duration":"324.228417ms","start":"2026-07-30T21:06:31.01945Z","end":"2026-07-30T21:06:31.343678Z","steps":["trace[826516213] 'read index received' (duration: 324.055792ms)","trace[826516213] 'applied index is now lower than readState.Index' (duration: 171.385µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:06:31.343856Z","caller":"traceutil/trace.go:171","msg":"trace[970291667] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"903.665496ms","start":"2026-07-30T21:06:30.440174Z","end":"2026-07-30T21:06:31.343839Z","steps":["trace[970291667] 'process raft request' (duration: 903.356128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:31.344593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.128789ms","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-07-30T21:06:31.344677Z","caller":"traceutil/trace.go:171","msg":"trace[447675708] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:810; }","duration":"325.253353ms","start":"2026-07-30T21:06:31.019404Z","end":"2026-07-30T21:06:31.344657Z","steps":["trace[447675708] 'agreement among raft nodes before linearized reading' (duration: 325.105899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:06:31.344745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:31.019385Z","time spent":"325.319255ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T21:06:31.345329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:06:30.440151Z","time spent":"903.734797ms","remote":"127.0.0.1:35948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:07:06.047385Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378077398147527817,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T21:07:06.293236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.209599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:07:06.293391Z","caller":"traceutil/trace.go:171","msg":"trace[818826777] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"778.541122ms","start":"2026-07-30T21:07:05.514828Z","end":"2026-07-30T21:07:06.293369Z","steps":["trace[818826777] 'process raft request' (duration: 612.083616ms)","trace[818826777] 'compare' (duration: 166.049286ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:07:06.293454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:05.514818Z","time spent":"778.605694ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:07:07.112733Z","caller":"traceutil/trace.go:171","msg":"trace[891818779] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1059; }","duration":"1.566162093s","start":"2026-07-30T21:07:05.546543Z","end":"2026-07-30T21:07:07.112705Z","steps":["trace[891818779] 'read index received' (duration: 580.382464ms)","trace[891818779] 'applied index is now lower than readState.Index' (duration: 985.778649ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:07:07.112845Z","caller":"traceutil/trace.go:171","msg":"trace[1756043467] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"1.561605864s","start":"2026-07-30T21:07:05.551227Z","end":"2026-07-30T21:07:07.112833Z","steps":["trace[1756043467] 'process raft request' (duration: 1.561259775s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:07.112973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:05.551203Z","time spent":"1.561668345s","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:07:07.112991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.596625795s","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-07-30T21:07:07.113064Z","caller":"traceutil/trace.go:171","msg":"trace[1690166214] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1025; }","duration":"1.596736617s","start":"2026-07-30T21:07:05.51631Z","end":"2026-07-30T21:07:07.113046Z","steps":["trace[1690166214] 'agreement among raft nodes before linearized reading' (duration: 1.596530491s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:07.113103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:05.516292Z","time spent":"1.596802379s","remote":"127.0.0.1:36040","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":"info","ts":"2026-07-30T21:07:07.113158Z","caller":"traceutil/trace.go:171","msg":"trace[503041362] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"1.478170815s","start":"2026-07-30T21:07:05.634943Z","end":"2026-07-30T21:07:07.113114Z","steps":["trace[503041362] 'process raft request' (duration: 1.477713174s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:07.113296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.597020595s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-30T21:07:07.113307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:05.634922Z","time spent":"1.478286149s","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:07:07.113345Z","caller":"traceutil/trace.go:171","msg":"trace[1362002256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1025; }","duration":"1.597077796s","start":"2026-07-30T21:07:05.516253Z","end":"2026-07-30T21:07:07.113331Z","steps":["trace[1362002256] 'agreement among raft nodes before linearized reading' (duration: 1.596943713s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:07.113537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:05.516247Z","time spent":"1.597274361s","remote":"127.0.0.1:36040","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-07-30T21:07:07.315253Z","caller":"traceutil/trace.go:171","msg":"trace[1765873362] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1062; }","duration":"202.431232ms","start":"2026-07-30T21:07:07.112801Z","end":"2026-07-30T21:07:07.315232Z","steps":["trace[1765873362] 'read index received' (duration: 115.869762ms)","trace[1765873362] 'applied index is now lower than readState.Index' (duration: 86.56077ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:07:07.31534Z","caller":"traceutil/trace.go:171","msg":"trace[732103076] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"885.104983ms","start":"2026-07-30T21:07:06.43019Z","end":"2026-07-30T21:07:07.315295Z","steps":["trace[732103076] 'process raft request' (duration: 798.549704ms)","trace[732103076] 'compare' (duration: 86.346904ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:07:07.315434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.64712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:07:07.31546Z","caller":"traceutil/trace.go:171","msg":"trace[837247499] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1027; }","duration":"251.725522ms","start":"2026-07-30T21:07:07.063727Z","end":"2026-07-30T21:07:07.315453Z","steps":["trace[837247499] 'agreement among raft nodes before linearized reading' (duration: 251.597099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:07.315436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.532144459s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:07:07.3155Z","caller":"traceutil/trace.go:171","msg":"trace[1033826791] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1027; }","duration":"1.532270022s","start":"2026-07-30T21:07:05.783216Z","end":"2026-07-30T21:07:07.315487Z","steps":["trace[1033826791] 'agreement among raft nodes before linearized reading' (duration: 1.532097098s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:07.315498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:06.430161Z","time spent":"885.252927ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:07:07.31553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:05.783192Z","time spent":"1.532330874s","remote":"127.0.0.1:36252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-07-30T21:07:07.315382Z","caller":"traceutil/trace.go:171","msg":"trace[1882994290] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"197.280267ms","start":"2026-07-30T21:07:07.118093Z","end":"2026-07-30T21:07:07.315374Z","steps":["trace[1882994290] 'process raft request' (duration: 197.113553ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:07:07.315705Z","caller":"traceutil/trace.go:171","msg":"trace[849073589] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"197.3911ms","start":"2026-07-30T21:07:07.118292Z","end":"2026-07-30T21:07:07.315684Z","steps":["trace[849073589] 'process raft request' (duration: 197.052411ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:07:30.800842Z","caller":"traceutil/trace.go:171","msg":"trace[1557872437] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"370.024701ms","start":"2026-07-30T21:07:30.430789Z","end":"2026-07-30T21:07:30.800813Z","steps":["trace[1557872437] 'process raft request' (duration: 369.869137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:30.801092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:30.430777Z","time spent":"370.239476ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:07:31.707179Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378077398147528022,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T21:07:31.953457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.194611ms","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-07-30T21:07:31.953634Z","caller":"traceutil/trace.go:171","msg":"trace[1646625079] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1102; }","duration":"673.368686ms","start":"2026-07-30T21:07:31.280229Z","end":"2026-07-30T21:07:31.953598Z","steps":["trace[1646625079] 'range keys from in-memory index tree' (duration: 673.176851ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:07:31.954149Z","caller":"traceutil/trace.go:171","msg":"trace[263594531] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1144; }","duration":"748.040154ms","start":"2026-07-30T21:07:31.20608Z","end":"2026-07-30T21:07:31.95412Z","steps":["trace[263594531] 'read index received' (duration: 741.587323ms)","trace[263594531] 'applied index is now lower than readState.Index' (duration: 6.451331ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:07:31.954279Z","caller":"traceutil/trace.go:171","msg":"trace[1000829026] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"761.4359ms","start":"2026-07-30T21:07:31.192832Z","end":"2026-07-30T21:07:31.954268Z","steps":["trace[1000829026] 'process raft request' (duration: 754.865045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:31.954407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:31.192805Z","time spent":"761.502151ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:07:31.954543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.140594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:07:31.95493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.33366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-30T21:07:31.954999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.904977ms","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-07-30T21:07:31.955051Z","caller":"traceutil/trace.go:171","msg":"trace[396051831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1103; }","duration":"748.98897ms","start":"2026-07-30T21:07:31.206046Z","end":"2026-07-30T21:07:31.955035Z","steps":["trace[396051831] 'agreement among raft nodes before linearized reading' (duration: 748.805665ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:07:31.955052Z","caller":"traceutil/trace.go:171","msg":"trace[732157449] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1103; }","duration":"313.502944ms","start":"2026-07-30T21:07:31.641524Z","end":"2026-07-30T21:07:31.955027Z","steps":["trace[732157449] 'agreement among raft nodes before linearized reading' (duration: 313.32395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:31.955063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.143854ms","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-07-30T21:07:31.955109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:31.641504Z","time spent":"313.591156ms","remote":"127.0.0.1:35942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-07-30T21:07:31.955126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.515741ms","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-07-30T21:07:31.955185Z","caller":"traceutil/trace.go:171","msg":"trace[1640116743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1103; }","duration":"505.599623ms","start":"2026-07-30T21:07:31.449572Z","end":"2026-07-30T21:07:31.955172Z","steps":["trace[1640116743] 'agreement among raft nodes before linearized reading' (duration: 505.400868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:31.955224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:31.449555Z","time spent":"505.662534ms","remote":"127.0.0.1:36040","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-07-30T21:07:31.955168Z","caller":"traceutil/trace.go:171","msg":"trace[963138892] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1103; }","duration":"504.296188ms","start":"2026-07-30T21:07:31.450856Z","end":"2026-07-30T21:07:31.955152Z","steps":["trace[963138892] 'agreement among raft nodes before linearized reading' (duration: 504.061811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:31.955434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:31.450846Z","time spent":"504.568245ms","remote":"127.0.0.1:36040","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-07-30T21:07:31.955085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.914841ms","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-07-30T21:07:31.955634Z","caller":"traceutil/trace.go:171","msg":"trace[9386404] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1103; }","duration":"287.500706ms","start":"2026-07-30T21:07:31.668116Z","end":"2026-07-30T21:07:31.955617Z","steps":["trace[9386404] 'agreement among raft nodes before linearized reading' (duration: 286.825148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:31.95507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.20019ms","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":"info","ts":"2026-07-30T21:07:31.955738Z","caller":"traceutil/trace.go:171","msg":"trace[822759242] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1103; }","duration":"497.914338ms","start":"2026-07-30T21:07:31.457807Z","end":"2026-07-30T21:07:31.955721Z","steps":["trace[822759242] 'agreement among raft nodes before linearized reading' (duration: 497.184579ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:07:31.954685Z","caller":"traceutil/trace.go:171","msg":"trace[1647233625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1103; }","duration":"544.265457ms","start":"2026-07-30T21:07:31.410375Z","end":"2026-07-30T21:07:31.954641Z","steps":["trace[1647233625] 'agreement among raft nodes before linearized reading' (duration: 544.082782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:31.955946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:31.410299Z","time spent":"545.629573ms","remote":"127.0.0.1:35804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T21:07:31.955796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:31.457781Z","time spent":"497.996511ms","remote":"127.0.0.1:54884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-30T21:07:31.955106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:31.206033Z","time spent":"749.061011ms","remote":"127.0.0.1:36040","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-07-30T21:07:33.460276Z","caller":"traceutil/trace.go:171","msg":"trace[932615181] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"651.621849ms","start":"2026-07-30T21:07:32.808631Z","end":"2026-07-30T21:07:33.460252Z","steps":["trace[932615181] 'process raft request' (duration: 651.466545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:33.4604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:32.808608Z","time spent":"651.726703ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:07:34.249579Z","caller":"traceutil/trace.go:171","msg":"trace[410692329] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"289.637682ms","start":"2026-07-30T21:07:33.959904Z","end":"2026-07-30T21:07:34.249541Z","steps":["trace[410692329] 'process raft request' (duration: 289.470198ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:07:34.35727Z","caller":"traceutil/trace.go:171","msg":"trace[1010736824] linearizableReadLoop","detail":"{readStateIndex:1154; appliedIndex:1152; }","duration":"320.630302ms","start":"2026-07-30T21:07:34.036621Z","end":"2026-07-30T21:07:34.357252Z","steps":["trace[1010736824] 'read index received' (duration: 212.848358ms)","trace[1010736824] 'applied index is now lower than readState.Index' (duration: 107.781264ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:07:34.35738Z","caller":"traceutil/trace.go:171","msg":"trace[1959567476] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"332.425084ms","start":"2026-07-30T21:07:34.024907Z","end":"2026-07-30T21:07:34.357333Z","steps":["trace[1959567476] 'process raft request' (duration: 332.233319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:34.357439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.816837ms","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-07-30T21:07:34.357461Z","caller":"traceutil/trace.go:171","msg":"trace[498796558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1111; }","duration":"320.892549ms","start":"2026-07-30T21:07:34.036562Z","end":"2026-07-30T21:07:34.357454Z","steps":["trace[498796558] 'agreement among raft nodes before linearized reading' (duration: 320.787726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:34.357486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:34.036543Z","time spent":"320.93515ms","remote":"127.0.0.1:36040","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-07-30T21:07:34.357493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:34.02488Z","time spent":"332.560218ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:07:34.357602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.957052ms","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-07-30T21:07:34.357633Z","caller":"traceutil/trace.go:171","msg":"trace[512310568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1111; }","duration":"295.008444ms","start":"2026-07-30T21:07:34.062616Z","end":"2026-07-30T21:07:34.357624Z","steps":["trace[512310568] 'agreement among raft nodes before linearized reading' (duration: 294.908511ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:34.357619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.661734ms","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-07-30T21:07:34.357666Z","caller":"traceutil/trace.go:171","msg":"trace[1562242722] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1111; }","duration":"294.723386ms","start":"2026-07-30T21:07:34.062932Z","end":"2026-07-30T21:07:34.357655Z","steps":["trace[1562242722] 'agreement among raft nodes before linearized reading' (duration: 294.591592ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:34.357848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.245107ms","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-07-30T21:07:34.357874Z","caller":"traceutil/trace.go:171","msg":"trace[557099413] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1111; }","duration":"296.319029ms","start":"2026-07-30T21:07:34.061543Z","end":"2026-07-30T21:07:34.357862Z","steps":["trace[557099413] 'agreement among raft nodes before linearized reading' (duration: 295.925818ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:07:50.504275Z","caller":"traceutil/trace.go:171","msg":"trace[557756746] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"115.243432ms","start":"2026-07-30T21:07:50.388993Z","end":"2026-07-30T21:07:50.504236Z","steps":["trace[557756746] 'process raft request' (duration: 115.001716ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:07:51.871172Z","caller":"traceutil/trace.go:171","msg":"trace[1949983393] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"278.63233ms","start":"2026-07-30T21:07:51.592516Z","end":"2026-07-30T21:07:51.871149Z","steps":["trace[1949983393] 'process raft request' (duration: 278.498307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:52.992425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.15659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T21:07:52.993017Z","caller":"traceutil/trace.go:171","msg":"trace[6976429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1219; }","duration":"466.568178ms","start":"2026-07-30T21:07:52.526394Z","end":"2026-07-30T21:07:52.992962Z","steps":["trace[6976429] 'range keys from in-memory index tree' (duration: 464.975955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:52.993503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:52.526367Z","time spent":"467.124173ms","remote":"127.0.0.1:36040","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-07-30T21:07:52.99358Z","caller":"traceutil/trace.go:171","msg":"trace[1080383824] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"465.680604ms","start":"2026-07-30T21:07:52.527886Z","end":"2026-07-30T21:07:52.993567Z","steps":["trace[1080383824] 'process raft request' (duration: 460.052745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:52.99367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:52.527868Z","time spent":"465.748766ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:07:52.993668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.91356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:07:52.99328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.977283ms","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-07-30T21:07:52.993731Z","caller":"traceutil/trace.go:171","msg":"trace[322409035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1219; }","duration":"563.006843ms","start":"2026-07-30T21:07:52.430703Z","end":"2026-07-30T21:07:52.99371Z","steps":["trace[322409035] 'range keys from in-memory index tree' (duration: 562.313425ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:07:52.993751Z","caller":"traceutil/trace.go:171","msg":"trace[503806109] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1219; }","duration":"466.498987ms","start":"2026-07-30T21:07:52.527243Z","end":"2026-07-30T21:07:52.993742Z","steps":["trace[503806109] 'range keys from in-memory index tree' (duration: 465.830649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:52.993777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:52.527211Z","time spent":"466.557958ms","remote":"127.0.0.1:36040","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-07-30T21:07:52.993777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:52.430674Z","time spent":"563.094804ms","remote":"127.0.0.1:35790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T21:07:52.993323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.465802ms","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-07-30T21:07:52.993832Z","caller":"traceutil/trace.go:171","msg":"trace[1013980630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1219; }","duration":"468.039367ms","start":"2026-07-30T21:07:52.525787Z","end":"2026-07-30T21:07:52.993827Z","steps":["trace[1013980630] 'range keys from in-memory index tree' (duration: 467.315468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:52.993856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:52.525765Z","time spent":"468.083658ms","remote":"127.0.0.1:36040","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-07-30T21:07:52.992867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.820595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.181\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-30T21:07:52.993912Z","caller":"traceutil/trace.go:171","msg":"trace[713547558] range","detail":"{range_begin:/registry/masterleases/199.19.213.181; range_end:; response_count:1; response_revision:1219; }","duration":"655.916893ms","start":"2026-07-30T21:07:52.337987Z","end":"2026-07-30T21:07:52.993904Z","steps":["trace[713547558] 'range keys from in-memory index tree' (duration: 654.666521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:52.993951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:52.337973Z","time spent":"655.972265ms","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.181\" "} {"level":"warn","ts":"2026-07-30T21:07:52.994276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.102182ms","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-07-30T21:07:52.994788Z","caller":"traceutil/trace.go:171","msg":"trace[1091359184] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1219; }","duration":"467.156464ms","start":"2026-07-30T21:07:52.527144Z","end":"2026-07-30T21:07:52.994301Z","steps":["trace[1091359184] 'range keys from in-memory index tree' (duration: 466.99563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:52.994815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:52.527134Z","time spent":"467.676798ms","remote":"127.0.0.1:36040","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-07-30T21:08:09.212424Z","caller":"traceutil/trace.go:171","msg":"trace[962895848] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"317.51971ms","start":"2026-07-30T21:08:08.894879Z","end":"2026-07-30T21:08:09.212399Z","steps":["trace[962895848] 'process raft request' (duration: 317.362986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:09.212574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:08.894861Z","time spent":"317.638833ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:08:09.254458Z","caller":"traceutil/trace.go:171","msg":"trace[1823713983] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1344; }","duration":"149.204082ms","start":"2026-07-30T21:08:09.105225Z","end":"2026-07-30T21:08:09.254429Z","steps":["trace[1823713983] 'read index received' (duration: 107.026095ms)","trace[1823713983] 'applied index is now lower than readState.Index' (duration: 42.176817ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:08:09.254542Z","caller":"traceutil/trace.go:171","msg":"trace[1221116249] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"155.691543ms","start":"2026-07-30T21:08:09.098817Z","end":"2026-07-30T21:08:09.254508Z","steps":["trace[1221116249] 'process raft request' (duration: 155.458677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:09.254725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.47537ms","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-07-30T21:08:09.254765Z","caller":"traceutil/trace.go:171","msg":"trace[883278239] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1294; }","duration":"149.561242ms","start":"2026-07-30T21:08:09.105192Z","end":"2026-07-30T21:08:09.254753Z","steps":["trace[883278239] 'agreement among raft nodes before linearized reading' (duration: 149.388877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:09.255109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.813669ms","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-07-30T21:08:09.255141Z","caller":"traceutil/trace.go:171","msg":"trace[1004660216] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1294; }","duration":"143.84978ms","start":"2026-07-30T21:08:09.111283Z","end":"2026-07-30T21:08:09.255133Z","steps":["trace[1004660216] 'agreement among raft nodes before linearized reading' (duration: 143.755578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:09.255165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.988446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:08:09.255201Z","caller":"traceutil/trace.go:171","msg":"trace[1604442775] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1294; }","duration":"115.058548ms","start":"2026-07-30T21:08:09.140132Z","end":"2026-07-30T21:08:09.25519Z","steps":["trace[1604442775] 'agreement among raft nodes before linearized reading' (duration: 115.000236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:09.255197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.477717ms","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-07-30T21:08:09.255263Z","caller":"traceutil/trace.go:171","msg":"trace[896150523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1294; }","duration":"147.575659ms","start":"2026-07-30T21:08:09.107667Z","end":"2026-07-30T21:08:09.255243Z","steps":["trace[896150523] 'agreement among raft nodes before linearized reading' (duration: 147.408565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:09.255435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.777195ms","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-07-30T21:08:09.255696Z","caller":"traceutil/trace.go:171","msg":"trace[1153514869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1294; }","duration":"148.002541ms","start":"2026-07-30T21:08:09.107644Z","end":"2026-07-30T21:08:09.255646Z","steps":["trace[1153514869] 'agreement among raft nodes before linearized reading' (duration: 147.257131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:10.043577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.746017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:08:10.043691Z","caller":"traceutil/trace.go:171","msg":"trace[1202557505] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"783.947825ms","start":"2026-07-30T21:08:09.259724Z","end":"2026-07-30T21:08:10.043672Z","steps":["trace[1202557505] 'process raft request' (duration: 250.028394ms)","trace[1202557505] 'compare' (duration: 533.653684ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:08:10.043756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:09.259698Z","time spent":"784.029957ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:08:10.099695Z","caller":"traceutil/trace.go:171","msg":"trace[428697148] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"839.826835ms","start":"2026-07-30T21:08:09.259838Z","end":"2026-07-30T21:08:10.099665Z","steps":["trace[428697148] 'process raft request' (duration: 839.589209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:10.09984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:09.259777Z","time spent":"839.9936ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:08:10.099928Z","caller":"traceutil/trace.go:171","msg":"trace[584193173] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"839.943129ms","start":"2026-07-30T21:08:09.259967Z","end":"2026-07-30T21:08:10.09991Z","steps":["trace[584193173] 'process raft request' (duration: 839.62778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:10.100017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:09.259848Z","time spent":"840.126383ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:08:10.1001Z","caller":"traceutil/trace.go:171","msg":"trace[237915522] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1346; }","duration":"688.136836ms","start":"2026-07-30T21:08:09.411947Z","end":"2026-07-30T21:08:10.100084Z","steps":["trace[237915522] 'read index received' (duration: 97.816151ms)","trace[237915522] 'applied index is now lower than readState.Index' (duration: 590.316495ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:08:10.1002Z","caller":"traceutil/trace.go:171","msg":"trace[1379662900] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"839.638019ms","start":"2026-07-30T21:08:09.260546Z","end":"2026-07-30T21:08:10.100184Z","steps":["trace[1379662900] 'process raft request' (duration: 839.29919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:10.100275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:09.260537Z","time spent":"839.695291ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:08:10.100321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.26816ms","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-07-30T21:08:10.100363Z","caller":"traceutil/trace.go:171","msg":"trace[470799535] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1298; }","duration":"147.342192ms","start":"2026-07-30T21:08:09.95301Z","end":"2026-07-30T21:08:10.100352Z","steps":["trace[470799535] 'agreement among raft nodes before linearized reading' (duration: 147.196418ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:10.100411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.444974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:08:10.100482Z","caller":"traceutil/trace.go:171","msg":"trace[649150547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1298; }","duration":"688.556817ms","start":"2026-07-30T21:08:09.411908Z","end":"2026-07-30T21:08:10.100465Z","steps":["trace[649150547] 'agreement among raft nodes before linearized reading' (duration: 688.414773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:10.100517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:09.411891Z","time spent":"688.619889ms","remote":"127.0.0.1:35804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T21:08:11.609356Z","caller":"traceutil/trace.go:171","msg":"trace[328296849] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1351; }","duration":"200.507621ms","start":"2026-07-30T21:08:11.40882Z","end":"2026-07-30T21:08:11.609328Z","steps":["trace[328296849] 'read index received' (duration: 200.266355ms)","trace[328296849] 'applied index is now lower than readState.Index' (duration: 239.806µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:08:11.609541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.705137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:08:11.609585Z","caller":"traceutil/trace.go:171","msg":"trace[689434141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1300; }","duration":"200.79941ms","start":"2026-07-30T21:08:11.408774Z","end":"2026-07-30T21:08:11.609573Z","steps":["trace[689434141] 'agreement among raft nodes before linearized reading' (duration: 200.656976ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:08:11.609452Z","caller":"traceutil/trace.go:171","msg":"trace[518013532] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"348.09421ms","start":"2026-07-30T21:08:11.261326Z","end":"2026-07-30T21:08:11.60942Z","steps":["trace[518013532] 'process raft request' (duration: 347.789272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:11.609894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:11.261299Z","time spent":"348.442579ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:08:12.01251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.1892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:08:12.012583Z","caller":"traceutil/trace.go:171","msg":"trace[1666922276] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1300; }","duration":"300.285473ms","start":"2026-07-30T21:08:11.712283Z","end":"2026-07-30T21:08:12.012569Z","steps":["trace[1666922276] 'count revisions from in-memory index tree' (duration: 300.108849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:12.012614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:11.712223Z","time spent":"300.383576ms","remote":"127.0.0.1:40940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-30T21:08:12.296215Z","caller":"traceutil/trace.go:171","msg":"trace[774180414] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"186.822117ms","start":"2026-07-30T21:08:12.109373Z","end":"2026-07-30T21:08:12.296195Z","steps":["trace[774180414] 'process raft request' (duration: 186.680554ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:08:12.421266Z","caller":"traceutil/trace.go:171","msg":"trace[1890950132] linearizableReadLoop","detail":"{readStateIndex:1356; appliedIndex:1354; }","duration":"306.19222ms","start":"2026-07-30T21:08:12.115047Z","end":"2026-07-30T21:08:12.42124Z","steps":["trace[1890950132] 'read index received' (duration: 181.187598ms)","trace[1890950132] 'applied index is now lower than readState.Index' (duration: 125.003862ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:08:12.421318Z","caller":"traceutil/trace.go:171","msg":"trace[1675996109] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"311.717497ms","start":"2026-07-30T21:08:12.109563Z","end":"2026-07-30T21:08:12.421281Z","steps":["trace[1675996109] 'process raft request' (duration: 311.606674ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:08:12.421399Z","caller":"traceutil/trace.go:171","msg":"trace[1968148454] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"245.387331ms","start":"2026-07-30T21:08:12.176002Z","end":"2026-07-30T21:08:12.42139Z","steps":["trace[1968148454] 'process raft request' (duration: 245.200656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:12.421502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:12.10933Z","time spent":"312.064396ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:08:12.421761Z","caller":"traceutil/trace.go:171","msg":"trace[611092481] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"312.210489ms","start":"2026-07-30T21:08:12.109535Z","end":"2026-07-30T21:08:12.421745Z","steps":["trace[611092481] 'process raft request' (duration: 220.692284ms)","trace[611092481] 'compare' (duration: 90.827687ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:08:12.421861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:12.10927Z","time spent":"312.529447ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:08:12.421923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.866048ms","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-07-30T21:08:12.421971Z","caller":"traceutil/trace.go:171","msg":"trace[234105395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1305; }","duration":"306.951ms","start":"2026-07-30T21:08:12.115007Z","end":"2026-07-30T21:08:12.421958Z","steps":["trace[234105395] 'agreement among raft nodes before linearized reading' (duration: 306.775485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:12.422008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:12.114961Z","time spent":"307.038563ms","remote":"127.0.0.1:36040","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-07-30T21:08:13.499797Z","caller":"traceutil/trace.go:171","msg":"trace[1473889611] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1360; }","duration":"420.403745ms","start":"2026-07-30T21:08:13.079365Z","end":"2026-07-30T21:08:13.499769Z","steps":["trace[1473889611] 'read index received' (duration: 420.174489ms)","trace[1473889611] 'applied index is now lower than readState.Index' (duration: 228.256µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:08:13.499963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.583421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:08:13.500008Z","caller":"traceutil/trace.go:171","msg":"trace[1059839945] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1308; }","duration":"420.666382ms","start":"2026-07-30T21:08:13.079328Z","end":"2026-07-30T21:08:13.499995Z","steps":["trace[1059839945] 'agreement among raft nodes before linearized reading' (duration: 420.545239ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:13.500047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:13.079315Z","time spent":"420.722494ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-30T21:08:13.500311Z","caller":"traceutil/trace.go:171","msg":"trace[1510556775] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"498.935696ms","start":"2026-07-30T21:08:13.001358Z","end":"2026-07-30T21:08:13.500294Z","steps":["trace[1510556775] 'process raft request' (duration: 498.269599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:13.500395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:13.001341Z","time spent":"499.003357ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T21:08:13.88178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.836761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:08:13.882662Z","caller":"traceutil/trace.go:171","msg":"trace[2029155106] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"354.990683ms","start":"2026-07-30T21:08:13.527621Z","end":"2026-07-30T21:08:13.882611Z","steps":["trace[2029155106] 'process raft request' (duration: 65.229257ms)","trace[2029155106] 'compare' (duration: 288.238915ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:08:13.882758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:13.527608Z","time spent":"355.103806ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T21:08:13.921084Z","caller":"traceutil/trace.go:171","msg":"trace[1953273703] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"389.056516ms","start":"2026-07-30T21:08:13.531974Z","end":"2026-07-30T21:08:13.921031Z","steps":["trace[1953273703] 'process raft request' (duration: 388.982844ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:08:13.92109Z","caller":"traceutil/trace.go:171","msg":"trace[900522381] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"391.321424ms","start":"2026-07-30T21:08:13.529726Z","end":"2026-07-30T21:08:13.921047Z","steps":["trace[900522381] 'process raft request' (duration: 391.068398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:13.921251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:13.529716Z","time spent":"391.465128ms","remote":"127.0.0.1:35852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T21:08:13.921366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:13.531963Z","time spent":"389.200559ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:08:13.929282Z","caller":"traceutil/trace.go:171","msg":"trace[108566135] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"313.169105ms","start":"2026-07-30T21:08:13.616087Z","end":"2026-07-30T21:08:13.929257Z","steps":["trace[108566135] 'process raft request' (duration: 313.032811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:13.929448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:13.616061Z","time spent":"313.291769ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:08:23.481543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.610927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-30T21:08:23.481598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.355376583s","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-07-30T21:08:23.48164Z","caller":"traceutil/trace.go:171","msg":"trace[2068019141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1376; }","duration":"940.765911ms","start":"2026-07-30T21:08:22.54085Z","end":"2026-07-30T21:08:23.481616Z","steps":["trace[2068019141] 'range keys from in-memory index tree' (duration: 940.276248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:23.481704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:22.540827Z","time spent":"940.855352ms","remote":"127.0.0.1:36040","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-07-30T21:08:23.481737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.81588ms","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-07-30T21:08:23.481814Z","caller":"traceutil/trace.go:171","msg":"trace[764147057] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1376; }","duration":"935.934543ms","start":"2026-07-30T21:08:22.545856Z","end":"2026-07-30T21:08:23.48179Z","steps":["trace[764147057] 'range keys from in-memory index tree' (duration: 935.650476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:23.481858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:22.545834Z","time spent":"936.015636ms","remote":"127.0.0.1:36040","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-07-30T21:08:23.481865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.741737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:08:23.481909Z","caller":"traceutil/trace.go:171","msg":"trace[215138248] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1376; }","duration":"778.819319ms","start":"2026-07-30T21:08:22.70307Z","end":"2026-07-30T21:08:23.48189Z","steps":["trace[215138248] 'count revisions from in-memory index tree' (duration: 778.655815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:23.481942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:22.70304Z","time spent":"778.893241ms","remote":"127.0.0.1:53400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-07-30T21:08:23.48213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.015256ms","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-07-30T21:08:23.482129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.142385408s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.181\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-30T21:08:23.482163Z","caller":"traceutil/trace.go:171","msg":"trace[344527481] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1376; }","duration":"936.150139ms","start":"2026-07-30T21:08:22.546004Z","end":"2026-07-30T21:08:23.482154Z","steps":["trace[344527481] 'range keys from in-memory index tree' (duration: 935.824601ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:08:23.482179Z","caller":"traceutil/trace.go:171","msg":"trace[1266616978] range","detail":"{range_begin:/registry/masterleases/199.19.213.181; range_end:; response_count:1; response_revision:1376; }","duration":"1.14243685s","start":"2026-07-30T21:08:22.339728Z","end":"2026-07-30T21:08:23.482165Z","steps":["trace[1266616978] 'range keys from in-memory index tree' (duration: 1.142270085s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:23.482189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:22.545958Z","time spent":"936.225391ms","remote":"127.0.0.1:36040","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-07-30T21:08:23.482223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:22.339686Z","time spent":"1.142522932s","remote":"127.0.0.1:35824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.181\" "} {"level":"warn","ts":"2026-07-30T21:08:23.482234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.283132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-07-30T21:08:23.481809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.327671979s","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:533"} {"level":"info","ts":"2026-07-30T21:08:23.482268Z","caller":"traceutil/trace.go:171","msg":"trace[1632118775] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1376; }","duration":"479.340454ms","start":"2026-07-30T21:08:23.002918Z","end":"2026-07-30T21:08:23.482258Z","steps":["trace[1632118775] 'range keys from in-memory index tree' (duration: 479.151459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:23.482297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:23.0029Z","time spent":"479.391095ms","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-30T21:08:23.482302Z","caller":"traceutil/trace.go:171","msg":"trace[821650557] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1376; }","duration":"1.328250194s","start":"2026-07-30T21:08:22.154035Z","end":"2026-07-30T21:08:23.482285Z","steps":["trace[821650557] 'range keys from in-memory index tree' (duration: 1.327516975s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:23.482447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:22.154007Z","time spent":"1.328421158s","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-30T21:08:23.482541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.142644044s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T21:08:23.482586Z","caller":"traceutil/trace.go:171","msg":"trace[171526284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1376; }","duration":"1.142730377s","start":"2026-07-30T21:08:22.339843Z","end":"2026-07-30T21:08:23.482573Z","steps":["trace[171526284] 'range keys from in-memory index tree' (duration: 1.14247203s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:23.482624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:22.339792Z","time spent":"1.142821779s","remote":"127.0.0.1:36040","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-07-30T21:08:23.482663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071145556s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:08:23.481648Z","caller":"traceutil/trace.go:171","msg":"trace[1559766231] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1376; }","duration":"1.355473035s","start":"2026-07-30T21:08:22.126161Z","end":"2026-07-30T21:08:23.481634Z","steps":["trace[1559766231] 'range keys from in-memory index tree' (duration: 1.355180358s)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:08:23.482697Z","caller":"traceutil/trace.go:171","msg":"trace[730665603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1376; }","duration":"1.071219538s","start":"2026-07-30T21:08:22.411468Z","end":"2026-07-30T21:08:23.482688Z","steps":["trace[730665603] 'range keys from in-memory index tree' (duration: 1.071037343s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:23.482726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:22.411445Z","time spent":"1.071274849s","remote":"127.0.0.1:35804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T21:08:23.482732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:22.126144Z","time spent":"1.356575334s","remote":"127.0.0.1:36040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}