{"level":"warn","ts":"2026-08-11T11:06:16.988233Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-11T11:06:16.988346Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.191: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.191:2380","--initial-cluster=instance=https://199.19.213.191:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.191:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.191: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-08-11T11:06:16.988583Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-11T11:06:16.988602Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.191:2380"]} {"level":"info","ts":"2026-08-11T11:06:16.988623Z","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-08-11T11:06:16.989355Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.191:2379"]} {"level":"info","ts":"2026-08-11T11:06:16.989996Z","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.191:2380"],"listen-peer-urls":["https://199.19.213.191:2380"],"advertise-client-urls":["https://199.19.213.191:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.191:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.191: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-08-11T11:06:17.147404Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"157.048553ms"} {"level":"info","ts":"2026-08-11T11:06:17.183562Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e892d50a3a4fc0f4","cluster-id":"21eccdda20ff4e92"} {"level":"info","ts":"2026-08-11T11:06:17.183739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 switched to configuration voters=()"} {"level":"info","ts":"2026-08-11T11:06:17.183804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 became follower at term 0"} {"level":"info","ts":"2026-08-11T11:06:17.183833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e892d50a3a4fc0f4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-11T11:06:17.18386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 became follower at term 1"} {"level":"info","ts":"2026-08-11T11:06:17.18393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 switched to configuration voters=(16758691403303731444)"} {"level":"warn","ts":"2026-08-11T11:06:17.191734Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-11T11:06:17.194608Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-11T11:06:17.198163Z","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-08-11T11:06:17.201687Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e892d50a3a4fc0f4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-11T11:06:17.201988Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e892d50a3a4fc0f4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-11T11:06:17.202406Z","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-08-11T11:06:17.202549Z","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-08-11T11:06:17.202576Z","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-08-11T11:06:17.205504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 switched to configuration voters=(16758691403303731444)"} {"level":"info","ts":"2026-08-11T11:06:17.205657Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"21eccdda20ff4e92","local-member-id":"e892d50a3a4fc0f4","added-peer-id":"e892d50a3a4fc0f4","added-peer-peer-urls":["https://199.19.213.191:2380"]} {"level":"info","ts":"2026-08-11T11:06:17.208688Z","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-08-11T11:06:17.20889Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.191:2380"} {"level":"info","ts":"2026-08-11T11:06:17.208984Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.191:2380"} {"level":"info","ts":"2026-08-11T11:06:17.210349Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e892d50a3a4fc0f4","initial-advertise-peer-urls":["https://199.19.213.191:2380"],"listen-peer-urls":["https://199.19.213.191:2380"],"advertise-client-urls":["https://199.19.213.191:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.191:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-11T11:06:17.210407Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-11T11:06:17.384842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 is starting a new election at term 1"} {"level":"info","ts":"2026-08-11T11:06:17.384931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-11T11:06:17.384955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 received MsgPreVoteResp from e892d50a3a4fc0f4 at term 1"} {"level":"info","ts":"2026-08-11T11:06:17.384974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 became candidate at term 2"} {"level":"info","ts":"2026-08-11T11:06:17.385216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 received MsgVoteResp from e892d50a3a4fc0f4 at term 2"} {"level":"info","ts":"2026-08-11T11:06:17.385249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 became leader at term 2"} {"level":"info","ts":"2026-08-11T11:06:17.38532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e892d50a3a4fc0f4 elected leader e892d50a3a4fc0f4 at term 2"} {"level":"info","ts":"2026-08-11T11:06:17.387239Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e892d50a3a4fc0f4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.191:2379]}","request-path":"/0/members/e892d50a3a4fc0f4/attributes","cluster-id":"21eccdda20ff4e92","publish-timeout":"7s"} {"level":"info","ts":"2026-08-11T11:06:17.387282Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T11:06:17.387722Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T11:06:17.389084Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"21eccdda20ff4e92","local-member-id":"e892d50a3a4fc0f4","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T11:06:17.389183Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T11:06:17.389216Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T11:06:17.389513Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T11:06:17.391311Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.191:2379"} {"level":"info","ts":"2026-08-11T11:06:17.392039Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-11T11:06:17.39436Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-11T11:06:17.394425Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-11T11:06:23.753117Z","caller":"traceutil/trace.go:171","msg":"trace[13984800] transaction","detail":"{read_only:false; number_of_response:0; response_revision:270; }","duration":"120.437253ms","start":"2026-08-11T11:06:23.632658Z","end":"2026-08-11T11:06:23.753095Z","steps":["trace[13984800] 'process raft request' (duration: 105.747685ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:06:23.809223Z","caller":"traceutil/trace.go:171","msg":"trace[1315551431] linearizableReadLoop","detail":"{readStateIndex:279; appliedIndex:277; }","duration":"130.014585ms","start":"2026-08-11T11:06:23.679185Z","end":"2026-08-11T11:06:23.8092Z","steps":["trace[1315551431] 'read index received' (duration: 59.231005ms)","trace[1315551431] 'applied index is now lower than readState.Index' (duration: 70.78277ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:06:23.809336Z","caller":"traceutil/trace.go:171","msg":"trace[754032459] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"161.060842ms","start":"2026-08-11T11:06:23.648232Z","end":"2026-08-11T11:06:23.809293Z","steps":["trace[754032459] 'process raft request' (duration: 160.847004ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:06:23.809412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.153936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-08-11T11:06:23.80948Z","caller":"traceutil/trace.go:171","msg":"trace[179540630] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:271; }","duration":"130.288598ms","start":"2026-08-11T11:06:23.679178Z","end":"2026-08-11T11:06:23.809467Z","steps":["trace[179540630] 'agreement among raft nodes before linearized reading' (duration: 130.126675ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:06:23.923327Z","caller":"traceutil/trace.go:171","msg":"trace[2888983] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"104.863181ms","start":"2026-08-11T11:06:23.818427Z","end":"2026-08-11T11:06:23.92329Z","steps":["trace[2888983] 'process raft request' (duration: 103.987927ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:06:24.237675Z","caller":"traceutil/trace.go:171","msg":"trace[1757940265] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"109.082922ms","start":"2026-08-11T11:06:24.128555Z","end":"2026-08-11T11:06:24.237637Z","steps":["trace[1757940265] 'process raft request' (duration: 35.028372ms)","trace[1757940265] 'compare' (duration: 73.871785ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:06:29.158044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.749627ms","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-08-11T11:06:29.158123Z","caller":"traceutil/trace.go:171","msg":"trace[493180967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:349; }","duration":"156.863206ms","start":"2026-08-11T11:06:29.001244Z","end":"2026-08-11T11:06:29.158107Z","steps":["trace[493180967] 'range keys from in-memory index tree' (duration: 156.681882ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:06:29.158339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.061477ms","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-08-11T11:06:29.158371Z","caller":"traceutil/trace.go:171","msg":"trace[872055250] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:349; }","duration":"253.141304ms","start":"2026-08-11T11:06:28.905221Z","end":"2026-08-11T11:06:29.158362Z","steps":["trace[872055250] 'range keys from in-memory index tree' (duration: 252.971979ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:06:29.158602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.651044ms","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-08-11T11:06:29.158637Z","caller":"traceutil/trace.go:171","msg":"trace[1968259409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:349; }","duration":"160.72058ms","start":"2026-08-11T11:06:28.997906Z","end":"2026-08-11T11:06:29.158627Z","steps":["trace[1968259409] 'range keys from in-memory index tree' (duration: 160.557777ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:06:29.158931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.309002ms","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-08-11T11:06:29.158964Z","caller":"traceutil/trace.go:171","msg":"trace[2102573665] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:349; }","duration":"259.372698ms","start":"2026-08-11T11:06:28.899584Z","end":"2026-08-11T11:06:29.158956Z","steps":["trace[2102573665] 'range keys from in-memory index tree' (duration: 257.538654ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:06:29.816495Z","caller":"traceutil/trace.go:171","msg":"trace[1104906760] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"114.328541ms","start":"2026-08-11T11:06:29.702141Z","end":"2026-08-11T11:06:29.81647Z","steps":["trace[1104906760] 'process raft request' (duration: 114.176058ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:06:35.503195Z","caller":"traceutil/trace.go:171","msg":"trace[1029954195] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"128.908232ms","start":"2026-08-11T11:06:35.37426Z","end":"2026-08-11T11:06:35.503168Z","steps":["trace[1029954195] 'process raft request' (duration: 128.704835ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:06:40.841786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.677841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.191\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-11T11:06:40.841859Z","caller":"traceutil/trace.go:171","msg":"trace[2094336975] range","detail":"{range_begin:/registry/masterleases/199.19.213.191; range_end:; response_count:1; response_revision:390; }","duration":"202.796321ms","start":"2026-08-11T11:06:40.639045Z","end":"2026-08-11T11:06:40.841841Z","steps":["trace[2094336975] 'range keys from in-memory index tree' (duration: 202.582573ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:06:55.815896Z","caller":"traceutil/trace.go:171","msg":"trace[1263805447] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:502; }","duration":"496.762764ms","start":"2026-08-11T11:06:55.319102Z","end":"2026-08-11T11:06:55.815865Z","steps":["trace[1263805447] 'read index received' (duration: 496.6258ms)","trace[1263805447] 'applied index is now lower than readState.Index' (duration: 134.614µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:06:55.815967Z","caller":"traceutil/trace.go:171","msg":"trace[1180589600] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"498.192545ms","start":"2026-08-11T11:06:55.317737Z","end":"2026-08-11T11:06:55.81593Z","steps":["trace[1180589600] 'process raft request' (duration: 497.914398ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:06:55.816194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.070098ms","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-08-11T11:06:55.816285Z","caller":"traceutil/trace.go:171","msg":"trace[774410106] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:487; }","duration":"497.174675ms","start":"2026-08-11T11:06:55.319088Z","end":"2026-08-11T11:06:55.816262Z","steps":["trace[774410106] 'agreement among raft nodes before linearized reading' (duration: 496.95724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:06:55.816336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:06:55.319037Z","time spent":"497.289501ms","remote":"127.0.0.1:50906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-11T11:06:55.816872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:06:55.317717Z","time spent":"498.334149ms","remote":"127.0.0.1:50906","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-08-11T11:06:56.31654Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903913804895413343,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-11T11:06:56.589726Z","caller":"traceutil/trace.go:171","msg":"trace[1406455613] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"1.174722155s","start":"2026-08-11T11:06:55.414982Z","end":"2026-08-11T11:06:56.589704Z","steps":["trace[1406455613] 'process raft request' (duration: 1.174636545s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:06:56.58987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:06:55.414968Z","time spent":"1.174833243s","remote":"127.0.0.1:50674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:06:56.590486Z","caller":"traceutil/trace.go:171","msg":"trace[2142149055] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"1.132092378s","start":"2026-08-11T11:06:55.458374Z","end":"2026-08-11T11:06:56.590466Z","steps":["trace[2142149055] 'process raft request' (duration: 1.131894161s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:06:56.590584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:06:55.458347Z","time spent":"1.132197996s","remote":"127.0.0.1:50674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:06:56.589896Z","caller":"traceutil/trace.go:171","msg":"trace[1087189830] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"1.174106327s","start":"2026-08-11T11:06:55.415776Z","end":"2026-08-11T11:06:56.589883Z","steps":["trace[1087189830] 'process raft request' (duration: 1.173873984s)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:06:56.590681Z","caller":"traceutil/trace.go:171","msg":"trace[933861129] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"999.71945ms","start":"2026-08-11T11:06:55.590912Z","end":"2026-08-11T11:06:56.590631Z","steps":["trace[933861129] 'process raft request' (duration: 999.63028ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:06:56.590814Z","caller":"traceutil/trace.go:171","msg":"trace[1034006438] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"1.102296981s","start":"2026-08-11T11:06:55.488488Z","end":"2026-08-11T11:06:56.590785Z","steps":["trace[1034006438] 'process raft request' (duration: 1.101883529s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:06:56.590817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:06:55.590885Z","time spent":"999.847814ms","remote":"127.0.0.1:50674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T11:06:56.59089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:06:55.488469Z","time spent":"1.10238858s","remote":"127.0.0.1:50674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:06:56.589717Z","caller":"traceutil/trace.go:171","msg":"trace[1589746716] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"1.177987631s","start":"2026-08-11T11:06:55.411696Z","end":"2026-08-11T11:06:56.589684Z","steps":["trace[1589746716] 'process raft request' (duration: 1.177777155s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:06:56.591193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:06:55.411672Z","time spent":"1.179482529s","remote":"127.0.0.1:50674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:06:56.59011Z","caller":"traceutil/trace.go:171","msg":"trace[374865298] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.164132351s","start":"2026-08-11T11:06:55.425958Z","end":"2026-08-11T11:06:56.59009Z","steps":["trace[374865298] 'process raft request' (duration: 1.163869502s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:06:56.59129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:06:55.425952Z","time spent":"1.165311765s","remote":"127.0.0.1:50674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T11:06:56.590703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:06:55.415757Z","time spent":"1.174883048s","remote":"127.0.0.1:50674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:06:56.590123Z","caller":"traceutil/trace.go:171","msg":"trace[1576906834] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"1.164094696s","start":"2026-08-11T11:06:55.426002Z","end":"2026-08-11T11:06:56.590097Z","steps":["trace[1576906834] 'process raft request' (duration: 1.163906077s)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:06:56.590161Z","caller":"traceutil/trace.go:171","msg":"trace[1350565832] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"1.164287912s","start":"2026-08-11T11:06:55.425861Z","end":"2026-08-11T11:06:56.590149Z","steps":["trace[1350565832] 'process raft request' (duration: 1.163870331s)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:06:56.590196Z","caller":"traceutil/trace.go:171","msg":"trace[939345384] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"1.15724539s","start":"2026-08-11T11:06:55.432939Z","end":"2026-08-11T11:06:56.590184Z","steps":["trace[939345384] 'process raft request' (duration: 1.157035495s)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:06:56.590255Z","caller":"traceutil/trace.go:171","msg":"trace[96824944] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"1.157217554s","start":"2026-08-11T11:06:55.433025Z","end":"2026-08-11T11:06:56.590242Z","steps":["trace[96824944] 'process raft request' (duration: 1.157029035s)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:06:56.590348Z","caller":"traceutil/trace.go:171","msg":"trace[1017333918] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"1.153521149s","start":"2026-08-11T11:06:55.436815Z","end":"2026-08-11T11:06:56.590336Z","steps":["trace[1017333918] 'process raft request' (duration: 1.153320362s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:06:56.591528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:06:55.425843Z","time spent":"1.165657536s","remote":"127.0.0.1:50674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T11:06:56.591599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:06:55.425996Z","time spent":"1.165586914s","remote":"127.0.0.1:50674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T11:06:56.591542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:06:55.432822Z","time spent":"1.158683065s","remote":"127.0.0.1:50674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T11:06:56.591741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:06:55.436797Z","time spent":"1.15465407s","remote":"127.0.0.1:50674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T11:06:56.591941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:06:55.433019Z","time spent":"1.158852366s","remote":"127.0.0.1:50674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:06:56.600914Z","caller":"traceutil/trace.go:171","msg":"trace[142822242] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"780.681957ms","start":"2026-08-11T11:06:55.820216Z","end":"2026-08-11T11:06:56.600898Z","steps":["trace[142822242] 'process raft request' (duration: 779.958611ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:06:56.601011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:06:55.820189Z","time spent":"780.769248ms","remote":"127.0.0.1:50906","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-08-11T11:06:56.604491Z","caller":"traceutil/trace.go:171","msg":"trace[1959447922] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:505; }","duration":"788.475111ms","start":"2026-08-11T11:06:55.816001Z","end":"2026-08-11T11:06:56.604476Z","steps":["trace[1959447922] 'read index received' (duration: 773.652891ms)","trace[1959447922] 'applied index is now lower than readState.Index' (duration: 14.82107ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:06:56.60458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.285208788s","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-08-11T11:06:56.604609Z","caller":"traceutil/trace.go:171","msg":"trace[296198377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:500; }","duration":"1.285257752s","start":"2026-08-11T11:06:55.319342Z","end":"2026-08-11T11:06:56.6046Z","steps":["trace[296198377] 'agreement among raft nodes before linearized reading' (duration: 1.2851889s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:06:56.604645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:06:55.319334Z","time spent":"1.285303807s","remote":"127.0.0.1:50906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-11T11:06:56.605363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.285790562s","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-08-11T11:06:56.605408Z","caller":"traceutil/trace.go:171","msg":"trace[221976946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:501; }","duration":"1.285852095s","start":"2026-08-11T11:06:55.319545Z","end":"2026-08-11T11:06:56.605397Z","steps":["trace[221976946] 'agreement among raft nodes before linearized reading' (duration: 1.285728599s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:06:56.605435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:06:55.319538Z","time spent":"1.285890551s","remote":"127.0.0.1:50906","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-08-11T11:06:56.605625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.697277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:06:56.605678Z","caller":"traceutil/trace.go:171","msg":"trace[377316285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:501; }","duration":"853.771239ms","start":"2026-08-11T11:06:55.751878Z","end":"2026-08-11T11:06:56.605649Z","steps":["trace[377316285] 'agreement among raft nodes before linearized reading' (duration: 853.722324ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:06:56.605697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:06:55.751861Z","time spent":"853.832302ms","remote":"127.0.0.1:50634","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-08-11T11:07:03.530422Z","caller":"traceutil/trace.go:171","msg":"trace[1509954117] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"775.272524ms","start":"2026-08-11T11:07:02.755126Z","end":"2026-08-11T11:07:03.530398Z","steps":["trace[1509954117] 'process raft request' (duration: 775.137397ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:03.530562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:02.755099Z","time spent":"775.395283ms","remote":"127.0.0.1:50816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:07:04.2493Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903913804895413572,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-11T11:07:04.670136Z","caller":"traceutil/trace.go:171","msg":"trace[719200978] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"1.577028875s","start":"2026-08-11T11:07:03.093086Z","end":"2026-08-11T11:07:04.670115Z","steps":["trace[719200978] 'process raft request' (duration: 1.576589236s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:04.670329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:03.093055Z","time spent":"1.577172461s","remote":"127.0.0.1:50724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:07:04.681123Z","caller":"traceutil/trace.go:171","msg":"trace[2116912299] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:610; }","duration":"932.401811ms","start":"2026-08-11T11:07:03.74869Z","end":"2026-08-11T11:07:04.681092Z","steps":["trace[2116912299] 'read index received' (duration: 920.846662ms)","trace[2116912299] 'applied index is now lower than readState.Index' (duration: 11.553539ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:07:04.681254Z","caller":"traceutil/trace.go:171","msg":"trace[1831937468] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"1.05699399s","start":"2026-08-11T11:07:03.624249Z","end":"2026-08-11T11:07:04.681243Z","steps":["trace[1831937468] 'process raft request' (duration: 1.056693259s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:04.681336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.653567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:07:04.681383Z","caller":"traceutil/trace.go:171","msg":"trace[1859467126] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"932.731539ms","start":"2026-08-11T11:07:03.748635Z","end":"2026-08-11T11:07:04.681367Z","steps":["trace[1859467126] 'agreement among raft nodes before linearized reading' (duration: 932.671674ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:04.681423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:03.748614Z","time spent":"932.801812ms","remote":"127.0.0.1:50634","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-08-11T11:07:04.681339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:03.624224Z","time spent":"1.057053375s","remote":"127.0.0.1:50906","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-08-11T11:07:04.681615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.684876ms","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-08-11T11:07:04.681844Z","caller":"traceutil/trace.go:171","msg":"trace[1662393112] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"831.947962ms","start":"2026-08-11T11:07:03.849874Z","end":"2026-08-11T11:07:04.681822Z","steps":["trace[1662393112] 'agreement among raft nodes before linearized reading' (duration: 831.561098ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:04.681972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:03.849853Z","time spent":"832.099378ms","remote":"127.0.0.1:50906","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-08-11T11:07:05.487868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.803444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:07:05.488045Z","caller":"traceutil/trace.go:171","msg":"trace[968215339] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:612; }","duration":"793.409602ms","start":"2026-08-11T11:07:04.694617Z","end":"2026-08-11T11:07:05.488027Z","steps":["trace[968215339] 'read index received' (duration: 13.058677ms)","trace[968215339] 'applied index is now lower than readState.Index' (duration: 780.349965ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:07:05.488122Z","caller":"traceutil/trace.go:171","msg":"trace[1536044584] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"798.97845ms","start":"2026-08-11T11:07:04.689132Z","end":"2026-08-11T11:07:05.48811Z","steps":["trace[1536044584] 'process raft request' (duration: 798.833023ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:07:05.488251Z","caller":"traceutil/trace.go:171","msg":"trace[253453401] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"799.19662ms","start":"2026-08-11T11:07:04.689028Z","end":"2026-08-11T11:07:05.488225Z","steps":["trace[253453401] 'process raft request' (duration: 18.636054ms)","trace[253453401] 'compare' (duration: 779.685675ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:07:05.48839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:04.689019Z","time spent":"799.299331ms","remote":"127.0.0.1:50906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:07:05.488402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.779898ms","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-08-11T11:07:05.488446Z","caller":"traceutil/trace.go:171","msg":"trace[220566878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"793.852151ms","start":"2026-08-11T11:07:04.694582Z","end":"2026-08-11T11:07:05.488434Z","steps":["trace[220566878] 'agreement among raft nodes before linearized reading' (duration: 793.717004ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:05.488475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:04.694565Z","time spent":"793.903456ms","remote":"127.0.0.1:50906","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-08-11T11:07:05.488937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:04.688964Z","time spent":"799.18738ms","remote":"127.0.0.1:50906","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-08-11T11:07:05.760548Z","caller":"traceutil/trace.go:171","msg":"trace[962781587] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.036069259s","start":"2026-08-11T11:07:04.724448Z","end":"2026-08-11T11:07:05.760517Z","steps":["trace[962781587] 'process raft request' (duration: 987.686863ms)","trace[962781587] 'compare' (duration: 48.048576ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:07:05.760576Z","caller":"traceutil/trace.go:171","msg":"trace[1288778248] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"267.455692ms","start":"2026-08-11T11:07:05.493101Z","end":"2026-08-11T11:07:05.760557Z","steps":["trace[1288778248] 'process raft request' (duration: 267.403457ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:05.760685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:04.724422Z","time spent":"1.036201197s","remote":"127.0.0.1:50974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:07:05.760682Z","caller":"traceutil/trace.go:171","msg":"trace[1051133508] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"272.515251ms","start":"2026-08-11T11:07:05.488079Z","end":"2026-08-11T11:07:05.760594Z","steps":["trace[1051133508] 'read index received' (duration: 224.06695ms)","trace[1051133508] 'applied index is now lower than readState.Index' (duration: 48.445381ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:07:05.760809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01076896s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:07:05.761542Z","caller":"traceutil/trace.go:171","msg":"trace[1078108733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"1.011569466s","start":"2026-08-11T11:07:04.749948Z","end":"2026-08-11T11:07:05.761518Z","steps":["trace[1078108733] 'agreement among raft nodes before linearized reading' (duration: 1.010821625s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:05.761083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055034903s","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-08-11T11:07:05.761741Z","caller":"traceutil/trace.go:171","msg":"trace[885983665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"1.055740688s","start":"2026-08-11T11:07:04.705971Z","end":"2026-08-11T11:07:05.761712Z","steps":["trace[885983665] 'agreement among raft nodes before linearized reading' (duration: 1.054874338s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:05.761822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:04.749932Z","time spent":"1.011667367s","remote":"127.0.0.1:50634","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-08-11T11:07:05.761828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:04.705949Z","time spent":"1.055857169s","remote":"127.0.0.1:50906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-11T11:07:06.528646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.263362ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-11T11:07:06.528869Z","caller":"traceutil/trace.go:171","msg":"trace[505697279] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"756.024107ms","start":"2026-08-11T11:07:05.772827Z","end":"2026-08-11T11:07:06.528851Z","steps":["trace[505697279] 'process raft request' (duration: 755.912777ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:07:06.529003Z","caller":"traceutil/trace.go:171","msg":"trace[1946915670] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"762.309223ms","start":"2026-08-11T11:07:05.766622Z","end":"2026-08-11T11:07:06.528931Z","steps":["trace[1946915670] 'read index received' (duration: 4.664235ms)","trace[1946915670] 'applied index is now lower than readState.Index' (duration: 757.640378ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:07:06.529178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.538582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:07:06.52921Z","caller":"traceutil/trace.go:171","msg":"trace[1619224675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"762.651243ms","start":"2026-08-11T11:07:05.766551Z","end":"2026-08-11T11:07:06.529202Z","steps":["trace[1619224675] 'agreement among raft nodes before linearized reading' (duration: 762.564841ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:06.52927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:05.766533Z","time spent":"762.696388ms","remote":"127.0.0.1:50640","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-08-11T11:07:06.529199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:05.772793Z","time spent":"756.124647ms","remote":"127.0.0.1:50906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:07:06.529365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.79321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:07:06.529502Z","caller":"traceutil/trace.go:171","msg":"trace[1838189815] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:596; }","duration":"754.122082ms","start":"2026-08-11T11:07:05.775354Z","end":"2026-08-11T11:07:06.529476Z","steps":["trace[1838189815] 'agreement among raft nodes before linearized reading' (duration: 753.789641ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:06.529587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:05.775335Z","time spent":"754.238412ms","remote":"127.0.0.1:50984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-08-11T11:07:07.993522Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903913804895413589,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T11:07:08.494681Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903913804895413589,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T11:07:08.995101Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903913804895413589,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T11:07:09.247629Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.270486902s","expected-duration":"1s"} {"level":"info","ts":"2026-08-11T11:07:09.256601Z","caller":"traceutil/trace.go:171","msg":"trace[666189052] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"2.281415273s","start":"2026-08-11T11:07:06.975151Z","end":"2026-08-11T11:07:09.256567Z","steps":["trace[666189052] 'process raft request' (duration: 2.279688564s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:09.256782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.764048066s","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-08-11T11:07:09.256603Z","caller":"traceutil/trace.go:171","msg":"trace[1700606196] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"1.763811625s","start":"2026-08-11T11:07:07.492713Z","end":"2026-08-11T11:07:09.256524Z","steps":["trace[1700606196] 'read index received' (duration: 1.755063611s)","trace[1700606196] 'applied index is now lower than readState.Index' (duration: 8.746945ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:07:09.256851Z","caller":"traceutil/trace.go:171","msg":"trace[2122214759] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:597; }","duration":"1.764153478s","start":"2026-08-11T11:07:07.492684Z","end":"2026-08-11T11:07:09.256837Z","steps":["trace[2122214759] 'agreement among raft nodes before linearized reading' (duration: 1.763962213s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:09.256883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:07.492669Z","time spent":"1.764209493s","remote":"127.0.0.1:50906","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-08-11T11:07:09.257019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.498419816s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T11:07:09.257032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.764163937s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-11T11:07:09.257031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.605375ms","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-08-11T11:07:09.257074Z","caller":"traceutil/trace.go:171","msg":"trace[2110274571] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:597; }","duration":"1.764256689s","start":"2026-08-11T11:07:07.492804Z","end":"2026-08-11T11:07:09.257061Z","steps":["trace[2110274571] 'agreement among raft nodes before linearized reading' (duration: 1.764116061s)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:07:09.257121Z","caller":"traceutil/trace.go:171","msg":"trace[136333955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:597; }","duration":"723.696337ms","start":"2026-08-11T11:07:08.533371Z","end":"2026-08-11T11:07:09.257067Z","steps":["trace[136333955] 'agreement among raft nodes before linearized reading' (duration: 723.584196ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:09.25716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:08.533345Z","time spent":"723.805718ms","remote":"127.0.0.1:50906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-11T11:07:09.257046Z","caller":"traceutil/trace.go:171","msg":"trace[1511712195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"1.498474762s","start":"2026-08-11T11:07:07.758563Z","end":"2026-08-11T11:07:09.257038Z","steps":["trace[1511712195] 'agreement among raft nodes before linearized reading' (duration: 1.498426326s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:09.257248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:07.758547Z","time spent":"1.498695963s","remote":"127.0.0.1:50634","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-08-11T11:07:09.257198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.482900488s","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-08-11T11:07:09.257327Z","caller":"traceutil/trace.go:171","msg":"trace[617757228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:597; }","duration":"1.483054286s","start":"2026-08-11T11:07:07.774259Z","end":"2026-08-11T11:07:09.257313Z","steps":["trace[617757228] 'agreement among raft nodes before linearized reading' (duration: 1.482713744s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:09.25737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:07.77424Z","time spent":"1.48312149s","remote":"127.0.0.1:50906","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-08-11T11:07:09.256942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:06.975126Z","time spent":"2.281631775s","remote":"127.0.0.1:50724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:07:09.257128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:07.492779Z","time spent":"1.764339013s","remote":"127.0.0.1:50906","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-08-11T11:07:10.536169Z","caller":"traceutil/trace.go:171","msg":"trace[215229817] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"169.071624ms","start":"2026-08-11T11:07:10.367072Z","end":"2026-08-11T11:07:10.536143Z","steps":["trace[215229817] 'process raft request' (duration: 168.945424ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:10.775203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.373102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:07:10.775307Z","caller":"traceutil/trace.go:171","msg":"trace[844184749] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"226.649805ms","start":"2026-08-11T11:07:10.548642Z","end":"2026-08-11T11:07:10.775292Z","steps":["trace[844184749] 'read index received' (duration: 95.113226ms)","trace[844184749] 'applied index is now lower than readState.Index' (duration: 131.535609ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:07:10.775332Z","caller":"traceutil/trace.go:171","msg":"trace[1338780118] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"236.911898ms","start":"2026-08-11T11:07:10.538405Z","end":"2026-08-11T11:07:10.775317Z","steps":["trace[1338780118] 'process raft request' (duration: 105.341102ms)","trace[1338780118] 'compare' (duration: 131.236183ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:07:10.775375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.729228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:07:10.775403Z","caller":"traceutil/trace.go:171","msg":"trace[927820916] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:611; }","duration":"226.784054ms","start":"2026-08-11T11:07:10.54861Z","end":"2026-08-11T11:07:10.775394Z","steps":["trace[927820916] 'agreement among raft nodes before linearized reading' (duration: 226.732958ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:10.777769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.466398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.191\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-11T11:07:10.77781Z","caller":"traceutil/trace.go:171","msg":"trace[1897254562] range","detail":"{range_begin:/registry/masterleases/199.19.213.191; range_end:; response_count:1; response_revision:612; }","duration":"118.509335ms","start":"2026-08-11T11:07:10.659291Z","end":"2026-08-11T11:07:10.7778Z","steps":["trace[1897254562] 'agreement among raft nodes before linearized reading' (duration: 118.422312ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:11.197504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.528321ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:07:11.198083Z","caller":"traceutil/trace.go:171","msg":"trace[1254933593] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"257.614549ms","start":"2026-08-11T11:07:10.940402Z","end":"2026-08-11T11:07:11.198017Z","steps":["trace[1254933593] 'process raft request' (duration: 133.250823ms)","trace[1254933593] 'compare' (duration: 123.240913ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:07:14.181052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.217775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-08-11T11:07:14.181161Z","caller":"traceutil/trace.go:171","msg":"trace[86556212] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:680; }","duration":"121.369995ms","start":"2026-08-11T11:07:14.059766Z","end":"2026-08-11T11:07:14.181136Z","steps":["trace[86556212] 'range keys from in-memory index tree' (duration: 121.026179ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:25.727238Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903913804895413920,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T11:07:26.180849Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.307821689s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-11T11:07:26.509969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.757019446s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:07:26.510109Z","caller":"traceutil/trace.go:171","msg":"trace[1475812796] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:769; }","duration":"1.757180879s","start":"2026-08-11T11:07:24.752909Z","end":"2026-08-11T11:07:26.51009Z","steps":["trace[1475812796] 'range keys from in-memory index tree' (duration: 1.756930611s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:26.510152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:24.752867Z","time spent":"1.757275044s","remote":"127.0.0.1:50634","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-08-11T11:07:26.510638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.413142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:07:26.510737Z","caller":"traceutil/trace.go:171","msg":"trace[1175040553] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"1.637812524s","start":"2026-08-11T11:07:24.872907Z","end":"2026-08-11T11:07:26.510719Z","steps":["trace[1175040553] 'process raft request' (duration: 1.308163683s)","trace[1175040553] 'compare' (duration: 329.316387ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:07:26.510798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:24.872879Z","time spent":"1.63788426s","remote":"127.0.0.1:50868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:07:26.524219Z","caller":"traceutil/trace.go:171","msg":"trace[489357720] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"1.611247654s","start":"2026-08-11T11:07:24.912858Z","end":"2026-08-11T11:07:26.524105Z","steps":["trace[489357720] 'process raft request' (duration: 1.610959437s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:26.52436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:24.912832Z","time spent":"1.611458404s","remote":"127.0.0.1:50906","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-08-11T11:07:26.540529Z","caller":"traceutil/trace.go:171","msg":"trace[1781227822] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:796; }","duration":"1.313383629s","start":"2026-08-11T11:07:25.227123Z","end":"2026-08-11T11:07:26.540506Z","steps":["trace[1781227822] 'read index received' (duration: 953.950691ms)","trace[1781227822] 'applied index is now lower than readState.Index' (duration: 359.431908ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:07:26.5407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.313559993s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:07:26.54086Z","caller":"traceutil/trace.go:171","msg":"trace[819863018] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:771; }","duration":"1.313727274s","start":"2026-08-11T11:07:25.227081Z","end":"2026-08-11T11:07:26.540809Z","steps":["trace[819863018] 'agreement among raft nodes before linearized reading' (duration: 1.313545382s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:26.541028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:25.22702Z","time spent":"1.313887416s","remote":"127.0.0.1:50798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-08-11T11:07:26.541146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.196523476s","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-08-11T11:07:26.541219Z","caller":"traceutil/trace.go:171","msg":"trace[1023071479] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:771; }","duration":"1.196634361s","start":"2026-08-11T11:07:25.344567Z","end":"2026-08-11T11:07:26.541201Z","steps":["trace[1023071479] 'agreement among raft nodes before linearized reading' (duration: 1.19645659s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:26.541272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:25.344539Z","time spent":"1.196719288s","remote":"127.0.0.1:50906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-11T11:07:26.541268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147215059s","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-08-11T11:07:26.541234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148114094s","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-08-11T11:07:26.541374Z","caller":"traceutil/trace.go:171","msg":"trace[653893218] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:771; }","duration":"1.147363121s","start":"2026-08-11T11:07:25.393984Z","end":"2026-08-11T11:07:26.541347Z","steps":["trace[653893218] 'agreement among raft nodes before linearized reading' (duration: 1.147071305s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:26.541126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147624488s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-11T11:07:26.541439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:25.393966Z","time spent":"1.147461876s","remote":"127.0.0.1:50906","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-08-11T11:07:26.541513Z","caller":"traceutil/trace.go:171","msg":"trace[983860501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:771; }","duration":"1.148372101s","start":"2026-08-11T11:07:25.39303Z","end":"2026-08-11T11:07:26.541402Z","steps":["trace[983860501] 'agreement among raft nodes before linearized reading' (duration: 1.147921503s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:26.541609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:25.393014Z","time spent":"1.148577911s","remote":"127.0.0.1:50906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-11T11:07:26.541836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.200100541s","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-08-11T11:07:26.541892Z","caller":"traceutil/trace.go:171","msg":"trace[1768592817] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:771; }","duration":"1.200222505s","start":"2026-08-11T11:07:25.341657Z","end":"2026-08-11T11:07:26.541879Z","steps":["trace[1768592817] 'agreement among raft nodes before linearized reading' (duration: 1.199210554s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:26.541925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:25.34158Z","time spent":"1.20033764s","remote":"127.0.0.1:50906","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":"info","ts":"2026-08-11T11:07:26.541468Z","caller":"traceutil/trace.go:171","msg":"trace[1588887803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:771; }","duration":"1.148030508s","start":"2026-08-11T11:07:25.393421Z","end":"2026-08-11T11:07:26.541451Z","steps":["trace[1588887803] 'agreement among raft nodes before linearized reading' (duration: 1.147469245s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:07:26.541999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:07:25.393394Z","time spent":"1.148583932s","remote":"127.0.0.1:50906","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-08-11T11:08:17.046836Z","caller":"traceutil/trace.go:171","msg":"trace[210445629] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"170.182013ms","start":"2026-08-11T11:08:16.876625Z","end":"2026-08-11T11:08:17.046807Z","steps":["trace[210445629] 'process raft request' (duration: 169.613175ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:17.056242Z","caller":"traceutil/trace.go:171","msg":"trace[90968194] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"178.854031ms","start":"2026-08-11T11:08:16.877366Z","end":"2026-08-11T11:08:17.05622Z","steps":["trace[90968194] 'process raft request' (duration: 178.740409ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:27.157619Z","caller":"traceutil/trace.go:171","msg":"trace[1538751517] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1141; }","duration":"224.805548ms","start":"2026-08-11T11:08:26.932761Z","end":"2026-08-11T11:08:27.157567Z","steps":["trace[1538751517] 'read index received' (duration: 224.576273ms)","trace[1538751517] 'applied index is now lower than readState.Index' (duration: 228.425µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:08:27.157705Z","caller":"traceutil/trace.go:171","msg":"trace[772909128] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"244.091625ms","start":"2026-08-11T11:08:26.913573Z","end":"2026-08-11T11:08:27.157665Z","steps":["trace[772909128] 'process raft request' (duration: 243.823789ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:27.157857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.074955ms","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-08-11T11:08:27.157921Z","caller":"traceutil/trace.go:171","msg":"trace[942960342] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1100; }","duration":"225.191417ms","start":"2026-08-11T11:08:26.932713Z","end":"2026-08-11T11:08:27.157904Z","steps":["trace[942960342] 'agreement among raft nodes before linearized reading' (duration: 224.981502ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:27.157928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.732888ms","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-08-11T11:08:27.15799Z","caller":"traceutil/trace.go:171","msg":"trace[593985666] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1100; }","duration":"224.8439ms","start":"2026-08-11T11:08:26.933126Z","end":"2026-08-11T11:08:27.15797Z","steps":["trace[593985666] 'agreement among raft nodes before linearized reading' (duration: 224.617145ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:27.457658Z","caller":"traceutil/trace.go:171","msg":"trace[808806274] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"294.249807ms","start":"2026-08-11T11:08:27.162667Z","end":"2026-08-11T11:08:27.456916Z","steps":["trace[808806274] 'process raft request' (duration: 264.763707ms)","trace[808806274] 'compare' (duration: 29.287476ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:08:27.499633Z","caller":"traceutil/trace.go:171","msg":"trace[21818648] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"335.609365ms","start":"2026-08-11T11:08:27.163992Z","end":"2026-08-11T11:08:27.499601Z","steps":["trace[21818648] 'process raft request' (duration: 335.526373ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:27.49984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:08:27.163984Z","time spent":"335.744677ms","remote":"127.0.0.1:50906","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-08-11T11:08:27.499648Z","caller":"traceutil/trace.go:171","msg":"trace[1658423716] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"336.501364ms","start":"2026-08-11T11:08:27.163103Z","end":"2026-08-11T11:08:27.499604Z","steps":["trace[1658423716] 'process raft request' (duration: 336.243518ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:27.500167Z","caller":"traceutil/trace.go:171","msg":"trace[1101433207] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"334.075954ms","start":"2026-08-11T11:08:27.166056Z","end":"2026-08-11T11:08:27.500132Z","steps":["trace[1101433207] 'process raft request' (duration: 333.510772ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:27.500316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:08:27.163094Z","time spent":"336.909213ms","remote":"127.0.0.1:50906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:08:27.500362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:08:27.166047Z","time spent":"334.202566ms","remote":"127.0.0.1:50906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:08:27.500348Z","caller":"traceutil/trace.go:171","msg":"trace[1943700840] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"107.820534ms","start":"2026-08-11T11:08:27.3925Z","end":"2026-08-11T11:08:27.500321Z","steps":["trace[1943700840] 'process raft request' (duration: 107.332745ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:33.42566Z","caller":"traceutil/trace.go:171","msg":"trace[1031859144] linearizableReadLoop","detail":"{readStateIndex:1164; appliedIndex:1163; }","duration":"152.228366ms","start":"2026-08-11T11:08:33.273413Z","end":"2026-08-11T11:08:33.425641Z","steps":["trace[1031859144] 'read index received' (duration: 152.125294ms)","trace[1031859144] 'applied index is now lower than readState.Index' (duration: 101.632µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:08:33.425817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.366371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T11:08:33.425838Z","caller":"traceutil/trace.go:171","msg":"trace[1111323641] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1121; }","duration":"152.458132ms","start":"2026-08-11T11:08:33.273371Z","end":"2026-08-11T11:08:33.425829Z","steps":["trace[1111323641] 'agreement among raft nodes before linearized reading' (duration: 152.354759ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:33.425843Z","caller":"traceutil/trace.go:171","msg":"trace[1216134992] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"208.791616ms","start":"2026-08-11T11:08:33.21701Z","end":"2026-08-11T11:08:33.425802Z","steps":["trace[1216134992] 'process raft request' (duration: 208.492569ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:33.79839Z","caller":"traceutil/trace.go:171","msg":"trace[1633217535] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"228.628933ms","start":"2026-08-11T11:08:33.568306Z","end":"2026-08-11T11:08:33.796935Z","steps":["trace[1633217535] 'process raft request' (duration: 228.041438ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:33.834465Z","caller":"traceutil/trace.go:171","msg":"trace[1341249008] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"265.992829ms","start":"2026-08-11T11:08:33.568431Z","end":"2026-08-11T11:08:33.834424Z","steps":["trace[1341249008] 'process raft request' (duration: 265.62519ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:33.834824Z","caller":"traceutil/trace.go:171","msg":"trace[1962236385] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"265.98172ms","start":"2026-08-11T11:08:33.568791Z","end":"2026-08-11T11:08:33.834773Z","steps":["trace[1962236385] 'process raft request' (duration: 265.57328ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:33.834852Z","caller":"traceutil/trace.go:171","msg":"trace[1462551944] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"265.825896ms","start":"2026-08-11T11:08:33.568996Z","end":"2026-08-11T11:08:33.834822Z","steps":["trace[1462551944] 'process raft request' (duration: 265.652702ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:35.914357Z","caller":"traceutil/trace.go:171","msg":"trace[625933947] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"105.121206ms","start":"2026-08-11T11:08:35.809192Z","end":"2026-08-11T11:08:35.914313Z","steps":["trace[625933947] 'process raft request' (duration: 104.783327ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:36.035942Z","caller":"traceutil/trace.go:171","msg":"trace[1725289195] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"113.346207ms","start":"2026-08-11T11:08:35.922562Z","end":"2026-08-11T11:08:36.035908Z","steps":["trace[1725289195] 'process raft request' (duration: 113.290485ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:36.0362Z","caller":"traceutil/trace.go:171","msg":"trace[206442695] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"114.209938ms","start":"2026-08-11T11:08:35.921956Z","end":"2026-08-11T11:08:36.036166Z","steps":["trace[206442695] 'process raft request' (duration: 78.639655ms)","trace[206442695] 'compare' (duration: 35.122322ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:08:37.619792Z","caller":"traceutil/trace.go:171","msg":"trace[120665559] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"163.054187ms","start":"2026-08-11T11:08:37.456707Z","end":"2026-08-11T11:08:37.619761Z","steps":["trace[120665559] 'process raft request' (duration: 162.835591ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:37.985583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:08:37.672014Z","time spent":"313.559737ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-11T11:08:37.986058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.361547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:08:37.986163Z","caller":"traceutil/trace.go:171","msg":"trace[308599082] linearizableReadLoop","detail":"{readStateIndex:1178; appliedIndex:1177; }","duration":"233.688638ms","start":"2026-08-11T11:08:37.752451Z","end":"2026-08-11T11:08:37.986139Z","steps":["trace[308599082] 'read index received' (duration: 64.016752ms)","trace[308599082] 'applied index is now lower than readState.Index' (duration: 169.669776ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:08:37.986439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.982116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:08:37.986472Z","caller":"traceutil/trace.go:171","msg":"trace[332480220] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"340.08817ms","start":"2026-08-11T11:08:37.646363Z","end":"2026-08-11T11:08:37.986451Z","steps":["trace[332480220] 'process raft request' (duration: 170.227939ms)","trace[332480220] 'compare' (duration: 168.68498ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:08:37.986557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:08:37.646334Z","time spent":"340.179213ms","remote":"127.0.0.1:50906","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-08-11T11:08:37.986654Z","caller":"traceutil/trace.go:171","msg":"trace[1166403914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1134; }","duration":"234.084159ms","start":"2026-08-11T11:08:37.752403Z","end":"2026-08-11T11:08:37.986487Z","steps":["trace[1166403914] 'agreement among raft nodes before linearized reading' (duration: 233.976856ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:38.320091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.11663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:08:38.320202Z","caller":"traceutil/trace.go:171","msg":"trace[95499339] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"276.028803ms","start":"2026-08-11T11:08:38.044154Z","end":"2026-08-11T11:08:38.320183Z","steps":["trace[95499339] 'process raft request' (duration: 68.741108ms)","trace[95499339] 'compare' (duration: 205.98712ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:08:38.334497Z","caller":"traceutil/trace.go:171","msg":"trace[1654049706] linearizableReadLoop","detail":"{readStateIndex:1183; appliedIndex:1182; }","duration":"183.10388ms","start":"2026-08-11T11:08:38.151366Z","end":"2026-08-11T11:08:38.33447Z","steps":["trace[1654049706] 'read index received' (duration: 182.930885ms)","trace[1654049706] 'applied index is now lower than readState.Index' (duration: 171.875µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:08:38.33456Z","caller":"traceutil/trace.go:171","msg":"trace[1272474339] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"290.225182ms","start":"2026-08-11T11:08:38.044313Z","end":"2026-08-11T11:08:38.334538Z","steps":["trace[1272474339] 'process raft request' (duration: 290.007456ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:38.334648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.242994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:08:38.334846Z","caller":"traceutil/trace.go:171","msg":"trace[820569318] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1139; }","duration":"183.47731ms","start":"2026-08-11T11:08:38.151325Z","end":"2026-08-11T11:08:38.334802Z","steps":["trace[820569318] 'agreement among raft nodes before linearized reading' (duration: 183.247384ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:39.806956Z","caller":"traceutil/trace.go:171","msg":"trace[2060072344] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"177.749982ms","start":"2026-08-11T11:08:39.62917Z","end":"2026-08-11T11:08:39.80692Z","steps":["trace[2060072344] 'process raft request' (duration: 177.560657ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:40.436156Z","caller":"traceutil/trace.go:171","msg":"trace[1159510914] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1184; }","duration":"427.518736ms","start":"2026-08-11T11:08:40.008605Z","end":"2026-08-11T11:08:40.436124Z","steps":["trace[1159510914] 'read index received' (duration: 389.83844ms)","trace[1159510914] 'applied index is now lower than readState.Index' (duration: 37.678916ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:08:40.436188Z","caller":"traceutil/trace.go:171","msg":"trace[476287804] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"439.670548ms","start":"2026-08-11T11:08:39.996477Z","end":"2026-08-11T11:08:40.436147Z","steps":["trace[476287804] 'process raft request' (duration: 401.999292ms)","trace[476287804] 'compare' (duration: 37.434579ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:08:40.436389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:08:39.996448Z","time spent":"439.828184ms","remote":"127.0.0.1:50906","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-08-11T11:08:40.436388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.761235ms","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-08-11T11:08:40.436451Z","caller":"traceutil/trace.go:171","msg":"trace[1749396151] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1141; }","duration":"427.878508ms","start":"2026-08-11T11:08:40.008559Z","end":"2026-08-11T11:08:40.436437Z","steps":["trace[1749396151] 'agreement among raft nodes before linearized reading' (duration: 427.654592ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:40.436495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:08:40.008535Z","time spent":"427.950379ms","remote":"127.0.0.1:50906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-11T11:08:40.436503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.394142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-11T11:08:40.436502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.133897ms","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-08-11T11:08:40.436587Z","caller":"traceutil/trace.go:171","msg":"trace[883557647] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1141; }","duration":"413.24393ms","start":"2026-08-11T11:08:40.023323Z","end":"2026-08-11T11:08:40.436567Z","steps":["trace[883557647] 'agreement among raft nodes before linearized reading' (duration: 412.999363ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:40.43666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:08:40.023308Z","time spent":"413.336513ms","remote":"127.0.0.1:50906","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":"info","ts":"2026-08-11T11:08:40.436589Z","caller":"traceutil/trace.go:171","msg":"trace[79764336] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1141; }","duration":"112.508876ms","start":"2026-08-11T11:08:40.324053Z","end":"2026-08-11T11:08:40.436561Z","steps":["trace[79764336] 'agreement among raft nodes before linearized reading' (duration: 112.286279ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:40.436054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.432275ms","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-08-11T11:08:40.43729Z","caller":"traceutil/trace.go:171","msg":"trace[2069386811] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1140; }","duration":"266.694072ms","start":"2026-08-11T11:08:40.170579Z","end":"2026-08-11T11:08:40.437273Z","steps":["trace[2069386811] 'range keys from in-memory index tree' (duration: 265.410875ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:40.820449Z","caller":"traceutil/trace.go:171","msg":"trace[71085700] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"378.108935ms","start":"2026-08-11T11:08:40.442315Z","end":"2026-08-11T11:08:40.820424Z","steps":["trace[71085700] 'process raft request' (duration: 378.062674ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:40.820588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:08:40.442259Z","time spent":"378.254919ms","remote":"127.0.0.1:50906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:08:40.820668Z","caller":"traceutil/trace.go:171","msg":"trace[1136214134] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"379.787724ms","start":"2026-08-11T11:08:40.440864Z","end":"2026-08-11T11:08:40.820652Z","steps":["trace[1136214134] 'process raft request' (duration: 360.333478ms)","trace[1136214134] 'compare' (duration: 18.607381ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:08:40.820732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:08:40.440857Z","time spent":"379.846135ms","remote":"127.0.0.1:50906","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-08-11T11:08:40.820902Z","caller":"traceutil/trace.go:171","msg":"trace[1890512636] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"378.174747ms","start":"2026-08-11T11:08:40.442684Z","end":"2026-08-11T11:08:40.820859Z","steps":["trace[1890512636] 'process raft request' (duration: 377.719044ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:40.820942Z","caller":"traceutil/trace.go:171","msg":"trace[205121317] linearizableReadLoop","detail":"{readStateIndex:1189; appliedIndex:1186; }","duration":"156.546654ms","start":"2026-08-11T11:08:40.664274Z","end":"2026-08-11T11:08:40.82082Z","steps":["trace[205121317] 'read index received' (duration: 136.879342ms)","trace[205121317] 'applied index is now lower than readState.Index' (duration: 19.664792ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:08:40.821059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.7914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.191\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-11T11:08:40.821143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:08:40.442661Z","time spent":"378.361722ms","remote":"127.0.0.1:50906","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-08-11T11:08:40.821193Z","caller":"traceutil/trace.go:171","msg":"trace[2003382089] range","detail":"{range_begin:/registry/masterleases/199.19.213.191; range_end:; response_count:1; response_revision:1145; }","duration":"156.908954ms","start":"2026-08-11T11:08:40.664219Z","end":"2026-08-11T11:08:40.821127Z","steps":["trace[2003382089] 'agreement among raft nodes before linearized reading' (duration: 156.77142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:41.285925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.829721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:08:41.286048Z","caller":"traceutil/trace.go:171","msg":"trace[1188676824] linearizableReadLoop","detail":"{readStateIndex:1191; appliedIndex:1190; }","duration":"249.882916ms","start":"2026-08-11T11:08:41.036145Z","end":"2026-08-11T11:08:41.286028Z","steps":["trace[1188676824] 'read index received' (duration: 41.859929ms)","trace[1188676824] 'applied index is now lower than readState.Index' (duration: 208.021307ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:08:41.286113Z","caller":"traceutil/trace.go:171","msg":"trace[1546909711] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"415.923069ms","start":"2026-08-11T11:08:40.87017Z","end":"2026-08-11T11:08:41.286093Z","steps":["trace[1546909711] 'process raft request' (duration: 207.84413ms)","trace[1546909711] 'compare' (duration: 207.564884ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:08:41.286141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.006169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:08:41.286155Z","caller":"traceutil/trace.go:171","msg":"trace[1305834521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1146; }","duration":"250.067451ms","start":"2026-08-11T11:08:41.036083Z","end":"2026-08-11T11:08:41.28615Z","steps":["trace[1305834521] 'agreement among raft nodes before linearized reading' (duration: 249.983419ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:41.286178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:08:40.870144Z","time spent":"416.00436ms","remote":"127.0.0.1:50662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:08:42.94584Z","caller":"traceutil/trace.go:171","msg":"trace[1870611213] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"117.166943ms","start":"2026-08-11T11:08:42.828642Z","end":"2026-08-11T11:08:42.945809Z","steps":["trace[1870611213] 'process raft request' (duration: 116.995898ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:42.961115Z","caller":"traceutil/trace.go:171","msg":"trace[422003580] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"132.423106ms","start":"2026-08-11T11:08:42.82866Z","end":"2026-08-11T11:08:42.961083Z","steps":["trace[422003580] 'process raft request' (duration: 132.23752ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:42.961294Z","caller":"traceutil/trace.go:171","msg":"trace[530709766] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"132.502239ms","start":"2026-08-11T11:08:42.828753Z","end":"2026-08-11T11:08:42.961256Z","steps":["trace[530709766] 'process raft request' (duration: 132.277052ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:45.106347Z","caller":"traceutil/trace.go:171","msg":"trace[451182631] linearizableReadLoop","detail":"{readStateIndex:1202; appliedIndex:1201; }","duration":"141.155813ms","start":"2026-08-11T11:08:44.965162Z","end":"2026-08-11T11:08:45.106318Z","steps":["trace[451182631] 'read index received' (duration: 140.843102ms)","trace[451182631] 'applied index is now lower than readState.Index' (duration: 311.471µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:08:45.106484Z","caller":"traceutil/trace.go:171","msg":"trace[432199530] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"152.533712ms","start":"2026-08-11T11:08:44.953911Z","end":"2026-08-11T11:08:45.106444Z","steps":["trace[432199530] 'process raft request' (duration: 152.157769ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:45.106677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.487353ms","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-08-11T11:08:45.106758Z","caller":"traceutil/trace.go:171","msg":"trace[2114133066] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1157; }","duration":"141.633388ms","start":"2026-08-11T11:08:44.965106Z","end":"2026-08-11T11:08:45.106739Z","steps":["trace[2114133066] 'agreement among raft nodes before linearized reading' (duration: 141.35724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:45.106878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.248265ms","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-08-11T11:08:45.107069Z","caller":"traceutil/trace.go:171","msg":"trace[1630264956] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1157; }","duration":"140.496523ms","start":"2026-08-11T11:08:44.966552Z","end":"2026-08-11T11:08:45.107049Z","steps":["trace[1630264956] 'agreement among raft nodes before linearized reading' (duration: 140.08821ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:48.125969Z","caller":"traceutil/trace.go:171","msg":"trace[772793671] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"135.293873ms","start":"2026-08-11T11:08:47.990643Z","end":"2026-08-11T11:08:48.125937Z","steps":["trace[772793671] 'process raft request' (duration: 135.072836ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:48.133712Z","caller":"traceutil/trace.go:171","msg":"trace[493014938] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"106.954724ms","start":"2026-08-11T11:08:48.026728Z","end":"2026-08-11T11:08:48.133682Z","steps":["trace[493014938] 'process raft request' (duration: 106.801119ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:50.775092Z","caller":"traceutil/trace.go:171","msg":"trace[350826917] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"122.507209ms","start":"2026-08-11T11:08:50.652571Z","end":"2026-08-11T11:08:50.775079Z","steps":["trace[350826917] 'process raft request' (duration: 122.307542ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:50.775075Z","caller":"traceutil/trace.go:171","msg":"trace[254683789] linearizableReadLoop","detail":"{readStateIndex:1220; appliedIndex:1219; }","duration":"110.242965ms","start":"2026-08-11T11:08:50.6648Z","end":"2026-08-11T11:08:50.775043Z","steps":["trace[254683789] 'read index received' (duration: 110.047159ms)","trace[254683789] 'applied index is now lower than readState.Index' (duration: 194.656µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:08:50.77535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.527115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.191\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-11T11:08:50.775434Z","caller":"traceutil/trace.go:171","msg":"trace[1692454117] range","detail":"{range_begin:/registry/masterleases/199.19.213.191; range_end:; response_count:1; response_revision:1174; }","duration":"110.66806ms","start":"2026-08-11T11:08:50.664753Z","end":"2026-08-11T11:08:50.775421Z","steps":["trace[1692454117] 'agreement among raft nodes before linearized reading' (duration: 110.37222ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:51.41316Z","caller":"traceutil/trace.go:171","msg":"trace[485410696] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"137.359761ms","start":"2026-08-11T11:08:51.275769Z","end":"2026-08-11T11:08:51.413129Z","steps":["trace[485410696] 'read index received' (duration: 89.19181ms)","trace[485410696] 'applied index is now lower than readState.Index' (duration: 48.166101ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:08:51.4134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.611649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-11T11:08:51.413484Z","caller":"traceutil/trace.go:171","msg":"trace[714829168] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1178; }","duration":"137.684082ms","start":"2026-08-11T11:08:51.275734Z","end":"2026-08-11T11:08:51.413418Z","steps":["trace[714829168] 'agreement among raft nodes before linearized reading' (duration: 137.515566ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:51.413621Z","caller":"traceutil/trace.go:171","msg":"trace[694083878] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"139.183573ms","start":"2026-08-11T11:08:51.274401Z","end":"2026-08-11T11:08:51.413584Z","steps":["trace[694083878] 'process raft request' (duration: 90.514915ms)","trace[694083878] 'compare' (duration: 48.063548ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:08:52.294186Z","caller":"traceutil/trace.go:171","msg":"trace[1278327074] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"121.826117ms","start":"2026-08-11T11:08:52.172331Z","end":"2026-08-11T11:08:52.294157Z","steps":["trace[1278327074] 'process raft request' (duration: 121.657281ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:53.398939Z","caller":"traceutil/trace.go:171","msg":"trace[1608657802] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"150.142562ms","start":"2026-08-11T11:08:53.248761Z","end":"2026-08-11T11:08:53.398904Z","steps":["trace[1608657802] 'process raft request' (duration: 149.958416ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:53.799741Z","caller":"traceutil/trace.go:171","msg":"trace[307478109] linearizableReadLoop","detail":"{readStateIndex:1232; appliedIndex:1231; }","duration":"335.646301ms","start":"2026-08-11T11:08:53.464069Z","end":"2026-08-11T11:08:53.799715Z","steps":["trace[307478109] 'read index received' (duration: 243.204517ms)","trace[307478109] 'applied index is now lower than readState.Index' (duration: 92.440974ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:08:53.799976Z","caller":"traceutil/trace.go:171","msg":"trace[172466319] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"380.854492ms","start":"2026-08-11T11:08:53.419111Z","end":"2026-08-11T11:08:53.799966Z","steps":["trace[172466319] 'process raft request' (duration: 288.247882ms)","trace[172466319] 'compare' (duration: 92.106882ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:08:53.800086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:08:53.419094Z","time spent":"380.912534ms","remote":"127.0.0.1:50906","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-08-11T11:08:53.800532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.458999ms","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-08-11T11:08:53.80057Z","caller":"traceutil/trace.go:171","msg":"trace[2089457671] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1185; }","duration":"336.540281ms","start":"2026-08-11T11:08:53.464022Z","end":"2026-08-11T11:08:53.800562Z","steps":["trace[2089457671] 'agreement among raft nodes before linearized reading' (duration: 336.2187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:53.800592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:08:53.463997Z","time spent":"336.590313ms","remote":"127.0.0.1:50906","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":"info","ts":"2026-08-11T11:08:54.145476Z","caller":"traceutil/trace.go:171","msg":"trace[264788463] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"341.29109ms","start":"2026-08-11T11:08:53.804149Z","end":"2026-08-11T11:08:54.14544Z","steps":["trace[264788463] 'process raft request' (duration: 341.074762ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:54.145686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:08:53.804134Z","time spent":"341.446925ms","remote":"127.0.0.1:50906","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-08-11T11:08:54.842072Z","caller":"traceutil/trace.go:171","msg":"trace[34256297] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"457.600037ms","start":"2026-08-11T11:08:54.384432Z","end":"2026-08-11T11:08:54.842032Z","steps":["trace[34256297] 'process raft request' (duration: 457.357929ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:54.842274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:08:54.384405Z","time spent":"457.754033ms","remote":"127.0.0.1:50906","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-08-11T11:08:55.0738Z","caller":"traceutil/trace.go:171","msg":"trace[662134324] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"225.594969ms","start":"2026-08-11T11:08:54.848169Z","end":"2026-08-11T11:08:55.073764Z","steps":["trace[662134324] 'process raft request' (duration: 126.711457ms)","trace[662134324] 'compare' (duration: 98.440916ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:08:55.237151Z","caller":"traceutil/trace.go:171","msg":"trace[735917252] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"158.561935ms","start":"2026-08-11T11:08:55.078158Z","end":"2026-08-11T11:08:55.23672Z","steps":["trace[735917252] 'process raft request' (duration: 122.768641ms)","trace[735917252] 'compare' (duration: 35.629558ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:08:55.608268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.522882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-11T11:08:55.608359Z","caller":"traceutil/trace.go:171","msg":"trace[18254215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1189; }","duration":"204.681338ms","start":"2026-08-11T11:08:55.403655Z","end":"2026-08-11T11:08:55.608336Z","steps":["trace[18254215] 'range keys from in-memory index tree' (duration: 204.307895ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:55.852837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.67431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:08:55.852957Z","caller":"traceutil/trace.go:171","msg":"trace[1675624307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1190; }","duration":"100.830395ms","start":"2026-08-11T11:08:55.752096Z","end":"2026-08-11T11:08:55.852926Z","steps":["trace[1675624307] 'range keys from in-memory index tree' (duration: 100.550555ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:56.494259Z","caller":"traceutil/trace.go:171","msg":"trace[1980090958] linearizableReadLoop","detail":"{readStateIndex:1238; appliedIndex:1237; }","duration":"343.264414ms","start":"2026-08-11T11:08:56.150963Z","end":"2026-08-11T11:08:56.494227Z","steps":["trace[1980090958] 'read index received' (duration: 343.067677ms)","trace[1980090958] 'applied index is now lower than readState.Index' (duration: 194.957µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:08:56.494332Z","caller":"traceutil/trace.go:171","msg":"trace[410970764] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"635.039186ms","start":"2026-08-11T11:08:55.859248Z","end":"2026-08-11T11:08:56.494287Z","steps":["trace[410970764] 'process raft request' (duration: 634.748146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:56.49451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.523463ms","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-08-11T11:08:56.494561Z","caller":"traceutil/trace.go:171","msg":"trace[183468074] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1191; }","duration":"343.605357ms","start":"2026-08-11T11:08:56.150944Z","end":"2026-08-11T11:08:56.494549Z","steps":["trace[183468074] 'agreement among raft nodes before linearized reading' (duration: 343.398519ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:56.494606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:08:56.150876Z","time spent":"343.71635ms","remote":"127.0.0.1:50906","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-08-11T11:08:56.494827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:08:55.859217Z","time spent":"635.211862ms","remote":"127.0.0.1:50906","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-08-11T11:08:56.801978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.920672ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-11T11:08:56.802322Z","caller":"traceutil/trace.go:171","msg":"trace[1431699357] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"303.1897ms","start":"2026-08-11T11:08:56.49911Z","end":"2026-08-11T11:08:56.8023Z","steps":["trace[1431699357] 'process raft request' (duration: 303.001263ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:56.802447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:08:56.499089Z","time spent":"303.284563ms","remote":"127.0.0.1:50906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:08:57.074921Z","caller":"traceutil/trace.go:171","msg":"trace[8956079] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"130.151511ms","start":"2026-08-11T11:08:56.944722Z","end":"2026-08-11T11:08:57.074873Z","steps":["trace[8956079] 'process raft request' (duration: 129.940483ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:57.212635Z","caller":"traceutil/trace.go:171","msg":"trace[2146934976] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"132.673308ms","start":"2026-08-11T11:08:57.079932Z","end":"2026-08-11T11:08:57.212605Z","steps":["trace[2146934976] 'process raft request' (duration: 112.091894ms)","trace[2146934976] 'compare' (duration: 19.990083ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:08:57.380676Z","caller":"traceutil/trace.go:171","msg":"trace[780623697] linearizableReadLoop","detail":"{readStateIndex:1243; appliedIndex:1242; }","duration":"128.941252ms","start":"2026-08-11T11:08:57.251702Z","end":"2026-08-11T11:08:57.380644Z","steps":["trace[780623697] 'read index received' (duration: 100.783129ms)","trace[780623697] 'applied index is now lower than readState.Index' (duration: 28.156693ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:08:57.380793Z","caller":"traceutil/trace.go:171","msg":"trace[1150839577] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"136.080694ms","start":"2026-08-11T11:08:57.244654Z","end":"2026-08-11T11:08:57.380734Z","steps":["trace[1150839577] 'process raft request' (duration: 107.805576ms)","trace[1150839577] 'compare' (duration: 28.033239ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:08:57.380915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.1642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:08:57.380969Z","caller":"traceutil/trace.go:171","msg":"trace[1345357885] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1195; }","duration":"129.305506ms","start":"2026-08-11T11:08:57.251649Z","end":"2026-08-11T11:08:57.380954Z","steps":["trace[1345357885] 'agreement among raft nodes before linearized reading' (duration: 129.163771ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:57.381003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.534234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T11:08:57.381114Z","caller":"traceutil/trace.go:171","msg":"trace[1949513892] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1195; }","duration":"123.688769ms","start":"2026-08-11T11:08:57.257406Z","end":"2026-08-11T11:08:57.381095Z","steps":["trace[1949513892] 'agreement among raft nodes before linearized reading' (duration: 123.470981ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:58.163545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.510154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T11:08:58.163586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.126561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:08:58.16365Z","caller":"traceutil/trace.go:171","msg":"trace[1293049790] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1196; }","duration":"327.678981ms","start":"2026-08-11T11:08:57.835945Z","end":"2026-08-11T11:08:58.163624Z","steps":["trace[1293049790] 'count revisions from in-memory index tree' (duration: 327.341498ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:58.163681Z","caller":"traceutil/trace.go:171","msg":"trace[2028564929] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1196; }","duration":"409.277256ms","start":"2026-08-11T11:08:57.754378Z","end":"2026-08-11T11:08:58.163656Z","steps":["trace[2028564929] 'range keys from in-memory index tree' (duration: 408.958603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:58.163704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:08:57.83592Z","time spent":"327.770614ms","remote":"127.0.0.1:50652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-08-11T11:08:58.163747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:08:57.754358Z","time spent":"409.3789ms","remote":"127.0.0.1:50634","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-08-11T11:08:58.163863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.752059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329948"} {"level":"info","ts":"2026-08-11T11:08:58.16394Z","caller":"traceutil/trace.go:171","msg":"trace[1418849562] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1196; }","duration":"365.886474ms","start":"2026-08-11T11:08:57.798035Z","end":"2026-08-11T11:08:58.163921Z","steps":["trace[1418849562] 'range keys from in-memory index tree' (duration: 365.403116ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:58.163982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:08:57.798006Z","time spent":"365.964807ms","remote":"127.0.0.1:50724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329971,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-08-11T11:08:58.744024Z","caller":"traceutil/trace.go:171","msg":"trace[681974350] linearizableReadLoop","detail":"{readStateIndex:1246; appliedIndex:1245; }","duration":"171.079709ms","start":"2026-08-11T11:08:58.572922Z","end":"2026-08-11T11:08:58.744002Z","steps":["trace[681974350] 'read index received' (duration: 170.964155ms)","trace[681974350] 'applied index is now lower than readState.Index' (duration: 114.834µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:08:58.744095Z","caller":"traceutil/trace.go:171","msg":"trace[1920479640] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"239.834031ms","start":"2026-08-11T11:08:58.504242Z","end":"2026-08-11T11:08:58.744076Z","steps":["trace[1920479640] 'process raft request' (duration: 239.627533ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:08:58.744159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.216224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T11:08:58.74418Z","caller":"traceutil/trace.go:171","msg":"trace[1360120829] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1198; }","duration":"171.329318ms","start":"2026-08-11T11:08:58.572845Z","end":"2026-08-11T11:08:58.744174Z","steps":["trace[1360120829] 'agreement among raft nodes before linearized reading' (duration: 171.241545ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:08:58.936643Z","caller":"traceutil/trace.go:171","msg":"trace[1163907117] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"120.094126ms","start":"2026-08-11T11:08:58.816506Z","end":"2026-08-11T11:08:58.9366Z","steps":["trace[1163907117] 'process raft request' (duration: 116.935959ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:01.220644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.947651ms","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-08-11T11:09:01.220753Z","caller":"traceutil/trace.go:171","msg":"trace[1276025111] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1205; }","duration":"280.107376ms","start":"2026-08-11T11:09:00.940619Z","end":"2026-08-11T11:09:01.220726Z","steps":["trace[1276025111] 'range keys from in-memory index tree' (duration: 279.773553ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:13.266966Z","caller":"traceutil/trace.go:171","msg":"trace[774034906] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"159.531985ms","start":"2026-08-11T11:09:13.107402Z","end":"2026-08-11T11:09:13.266934Z","steps":["trace[774034906] 'process raft request' (duration: 159.274824ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:15.461483Z","caller":"traceutil/trace.go:171","msg":"trace[1943208773] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1314; }","duration":"155.536924ms","start":"2026-08-11T11:09:15.305925Z","end":"2026-08-11T11:09:15.461462Z","steps":["trace[1943208773] 'read index received' (duration: 155.414368ms)","trace[1943208773] 'applied index is now lower than readState.Index' (duration: 121.345µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:09:15.461538Z","caller":"traceutil/trace.go:171","msg":"trace[1049428069] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"467.159463ms","start":"2026-08-11T11:09:14.994351Z","end":"2026-08-11T11:09:15.461511Z","steps":["trace[1049428069] 'process raft request' (duration: 466.975255ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:15.461642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:14.994329Z","time spent":"467.256987ms","remote":"127.0.0.1:50906","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-08-11T11:09:15.461703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.767143ms","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-08-11T11:09:15.461871Z","caller":"traceutil/trace.go:171","msg":"trace[524228533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1263; }","duration":"155.93188ms","start":"2026-08-11T11:09:15.305887Z","end":"2026-08-11T11:09:15.461819Z","steps":["trace[524228533] 'agreement among raft nodes before linearized reading' (duration: 155.716901ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:15.805816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.306846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:09:15.806Z","caller":"traceutil/trace.go:171","msg":"trace[373446101] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"486.26171ms","start":"2026-08-11T11:09:15.319707Z","end":"2026-08-11T11:09:15.805969Z","steps":["trace[373446101] 'process raft request' (duration: 258.714834ms)","trace[373446101] 'compare' (duration: 227.17213ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:09:15.806138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:15.319675Z","time spent":"486.393845ms","remote":"127.0.0.1:50906","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-08-11T11:09:15.80653Z","caller":"traceutil/trace.go:171","msg":"trace[1615515865] linearizableReadLoop","detail":"{readStateIndex:1316; appliedIndex:1315; }","duration":"344.92628ms","start":"2026-08-11T11:09:15.461561Z","end":"2026-08-11T11:09:15.806487Z","steps":["trace[1615515865] 'read index received' (duration: 116.735687ms)","trace[1615515865] 'applied index is now lower than readState.Index' (duration: 228.187473ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:09:15.806573Z","caller":"traceutil/trace.go:171","msg":"trace[1175511500] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"340.631685ms","start":"2026-08-11T11:09:15.465865Z","end":"2026-08-11T11:09:15.806497Z","steps":["trace[1175511500] 'process raft request' (duration: 340.066361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:15.807041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:15.465831Z","time spent":"341.129097ms","remote":"127.0.0.1:50906","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-08-11T11:09:15.816568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.371571ms","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-08-11T11:09:15.816654Z","caller":"traceutil/trace.go:171","msg":"trace[367562325] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1265; }","duration":"491.483266ms","start":"2026-08-11T11:09:15.325159Z","end":"2026-08-11T11:09:15.816643Z","steps":["trace[367562325] 'agreement among raft nodes before linearized reading' (duration: 481.485483ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:15.816719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:15.325147Z","time spent":"491.56365ms","remote":"127.0.0.1:50906","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-08-11T11:09:15.816758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.978447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-11T11:09:15.816585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.587824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-400f5bbf-ee15-4f33-8cff-18573aff2cba\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-08-11T11:09:15.817183Z","caller":"traceutil/trace.go:171","msg":"trace[422008201] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-400f5bbf-ee15-4f33-8cff-18573aff2cba; range_end:; response_count:1; response_revision:1265; }","duration":"372.21272ms","start":"2026-08-11T11:09:15.444936Z","end":"2026-08-11T11:09:15.817149Z","steps":["trace[422008201] 'agreement among raft nodes before linearized reading' (duration: 361.592291ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:15.81684Z","caller":"traceutil/trace.go:171","msg":"trace[1504233604] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1265; }","duration":"210.086132ms","start":"2026-08-11T11:09:15.606732Z","end":"2026-08-11T11:09:15.816819Z","steps":["trace[1504233604] 'agreement among raft nodes before linearized reading' (duration: 209.677435ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:15.817243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:15.444913Z","time spent":"372.314725ms","remote":"127.0.0.1:50816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3602,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-400f5bbf-ee15-4f33-8cff-18573aff2cba\" "} {"level":"warn","ts":"2026-08-11T11:09:18.092597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.627663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:09:18.092692Z","caller":"traceutil/trace.go:171","msg":"trace[1409438001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1272; }","duration":"340.991519ms","start":"2026-08-11T11:09:17.751686Z","end":"2026-08-11T11:09:18.092677Z","steps":["trace[1409438001] 'range keys from in-memory index tree' (duration: 340.502847ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:18.092689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.977371ms","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-08-11T11:09:18.092747Z","caller":"traceutil/trace.go:171","msg":"trace[1228508847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1272; }","duration":"282.113357ms","start":"2026-08-11T11:09:17.81062Z","end":"2026-08-11T11:09:18.092733Z","steps":["trace[1228508847] 'range keys from in-memory index tree' (duration: 281.759012ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:18.092723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:17.751654Z","time spent":"341.061832ms","remote":"127.0.0.1:50640","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-08-11T11:09:18.092861Z","caller":"traceutil/trace.go:171","msg":"trace[1459833113] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1325; }","duration":"266.142308ms","start":"2026-08-11T11:09:17.826691Z","end":"2026-08-11T11:09:18.092834Z","steps":["trace[1459833113] 'read index received' (duration: 252.488544ms)","trace[1459833113] 'applied index is now lower than readState.Index' (duration: 13.651953ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:09:18.09306Z","caller":"traceutil/trace.go:171","msg":"trace[2013668935] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"281.744802ms","start":"2026-08-11T11:09:17.811304Z","end":"2026-08-11T11:09:18.093049Z","steps":["trace[2013668935] 'process raft request' (duration: 267.966843ms)","trace[2013668935] 'compare' (duration: 13.229564ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:09:18.093348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.703633ms","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-08-11T11:09:18.093389Z","caller":"traceutil/trace.go:171","msg":"trace[485591042] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1273; }","duration":"258.778745ms","start":"2026-08-11T11:09:17.834601Z","end":"2026-08-11T11:09:18.09338Z","steps":["trace[485591042] 'agreement among raft nodes before linearized reading' (duration: 258.667571ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:18.093224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.522494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-400f5bbf-ee15-4f33-8cff-18573aff2cba\" ","response":"range_response_count:1 size:3579"} {"level":"warn","ts":"2026-08-11T11:09:18.09345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.998ms","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-08-11T11:09:18.09345Z","caller":"traceutil/trace.go:171","msg":"trace[1890985973] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-400f5bbf-ee15-4f33-8cff-18573aff2cba; range_end:; response_count:1; response_revision:1273; }","duration":"266.803688ms","start":"2026-08-11T11:09:17.826634Z","end":"2026-08-11T11:09:18.093438Z","steps":["trace[1890985973] 'agreement among raft nodes before linearized reading' (duration: 266.437982ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:18.093481Z","caller":"traceutil/trace.go:171","msg":"trace[620265265] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1273; }","duration":"198.061073ms","start":"2026-08-11T11:09:17.89541Z","end":"2026-08-11T11:09:18.093471Z","steps":["trace[620265265] 'agreement among raft nodes before linearized reading' (duration: 198.001911ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:18.37266Z","caller":"traceutil/trace.go:171","msg":"trace[959160905] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"107.330588ms","start":"2026-08-11T11:09:18.265298Z","end":"2026-08-11T11:09:18.372629Z","steps":["trace[959160905] 'process raft request' (duration: 107.117988ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:18.581179Z","caller":"traceutil/trace.go:171","msg":"trace[179407920] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"244.860771ms","start":"2026-08-11T11:09:18.336289Z","end":"2026-08-11T11:09:18.581149Z","steps":["trace[179407920] 'process raft request' (duration: 160.57432ms)","trace[179407920] 'compare' (duration: 84.129764ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:09:19.704874Z","caller":"traceutil/trace.go:171","msg":"trace[275187669] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"214.921613ms","start":"2026-08-11T11:09:19.489933Z","end":"2026-08-11T11:09:19.704854Z","steps":["trace[275187669] 'process raft request' (duration: 214.797037ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:20.294184Z","caller":"traceutil/trace.go:171","msg":"trace[532988236] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"107.931718ms","start":"2026-08-11T11:09:20.186233Z","end":"2026-08-11T11:09:20.294165Z","steps":["trace[532988236] 'process raft request' (duration: 104.470874ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:20.294197Z","caller":"traceutil/trace.go:171","msg":"trace[846697744] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"107.719518ms","start":"2026-08-11T11:09:20.186452Z","end":"2026-08-11T11:09:20.294171Z","steps":["trace[846697744] 'process raft request' (duration: 107.593573ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:20.294367Z","caller":"traceutil/trace.go:171","msg":"trace[1050311004] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"106.674682ms","start":"2026-08-11T11:09:20.187662Z","end":"2026-08-11T11:09:20.294336Z","steps":["trace[1050311004] 'process raft request' (duration: 106.469423ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:21.238792Z","caller":"traceutil/trace.go:171","msg":"trace[35929587] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"124.355279ms","start":"2026-08-11T11:09:21.114412Z","end":"2026-08-11T11:09:21.238767Z","steps":["trace[35929587] 'process raft request' (duration: 124.215422ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:21.631271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.748905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:09:21.631403Z","caller":"traceutil/trace.go:171","msg":"trace[1839614862] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"307.165941ms","start":"2026-08-11T11:09:21.324213Z","end":"2026-08-11T11:09:21.631379Z","steps":["trace[1839614862] 'process raft request' (duration: 77.233658ms)","trace[1839614862] 'compare' (duration: 229.574657ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:09:21.63179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:21.324189Z","time spent":"307.515667ms","remote":"127.0.0.1:50788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1136,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T11:09:21.900387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.440674ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:09:21.900597Z","caller":"traceutil/trace.go:171","msg":"trace[843111523] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"264.51248ms","start":"2026-08-11T11:09:21.63605Z","end":"2026-08-11T11:09:21.900562Z","steps":["trace[843111523] 'process raft request' (duration: 119.848406ms)","trace[843111523] 'compare' (duration: 144.327319ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:09:21.900931Z","caller":"traceutil/trace.go:171","msg":"trace[51158956] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"169.148727ms","start":"2026-08-11T11:09:21.731762Z","end":"2026-08-11T11:09:21.900911Z","steps":["trace[51158956] 'process raft request' (duration: 168.809012ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:21.901191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.969285ms","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-08-11T11:09:21.901237Z","caller":"traceutil/trace.go:171","msg":"trace[2041077751] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1294; }","duration":"192.066888ms","start":"2026-08-11T11:09:21.709161Z","end":"2026-08-11T11:09:21.901228Z","steps":["trace[2041077751] 'agreement among raft nodes before linearized reading' (duration: 191.88574ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:21.901022Z","caller":"traceutil/trace.go:171","msg":"trace[1609969575] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1345; }","duration":"191.805316ms","start":"2026-08-11T11:09:21.709204Z","end":"2026-08-11T11:09:21.90101Z","steps":["trace[1609969575] 'read index received' (duration: 19.081179ms)","trace[1609969575] 'applied index is now lower than readState.Index' (duration: 172.722987ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:09:21.901488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.877094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:09:21.901544Z","caller":"traceutil/trace.go:171","msg":"trace[1828909655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1294; }","duration":"150.963088ms","start":"2026-08-11T11:09:21.750564Z","end":"2026-08-11T11:09:21.901527Z","steps":["trace[1828909655] 'agreement among raft nodes before linearized reading' (duration: 150.878134ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:22.524523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:22.107Z","time spent":"417.513967ms","remote":"127.0.0.1:58654","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-11T11:09:22.525199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.648862ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:09:22.525582Z","caller":"traceutil/trace.go:171","msg":"trace[1918937024] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"619.565605ms","start":"2026-08-11T11:09:21.905992Z","end":"2026-08-11T11:09:22.525557Z","steps":["trace[1918937024] 'process raft request' (duration: 366.992231ms)","trace[1918937024] 'compare' (duration: 251.374459ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:09:22.525718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:21.905981Z","time spent":"619.68175ms","remote":"127.0.0.1:50798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1450,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:09:22.526315Z","caller":"traceutil/trace.go:171","msg":"trace[124793072] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"229.902267ms","start":"2026-08-11T11:09:22.296385Z","end":"2026-08-11T11:09:22.526288Z","steps":["trace[124793072] 'process raft request' (duration: 229.786501ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:22.526498Z","caller":"traceutil/trace.go:171","msg":"trace[1042177985] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"423.453286ms","start":"2026-08-11T11:09:22.102954Z","end":"2026-08-11T11:09:22.526408Z","steps":["trace[1042177985] 'process raft request' (duration: 422.358337ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:22.527225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:22.102929Z","time spent":"424.247001ms","remote":"127.0.0.1:50906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:09:22.526735Z","caller":"traceutil/trace.go:171","msg":"trace[1269247711] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1349; }","duration":"329.531121ms","start":"2026-08-11T11:09:22.197186Z","end":"2026-08-11T11:09:22.526717Z","steps":["trace[1269247711] 'read index received' (duration: 75.811755ms)","trace[1269247711] 'applied index is now lower than readState.Index' (duration: 253.717806ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:09:22.52692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.725909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-11T11:09:22.528676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.416091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-11T11:09:22.528843Z","caller":"traceutil/trace.go:171","msg":"trace[1716236225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1298; }","duration":"231.473298ms","start":"2026-08-11T11:09:22.297312Z","end":"2026-08-11T11:09:22.528786Z","steps":["trace[1716236225] 'agreement among raft nodes before linearized reading' (duration: 230.315126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:22.529033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.962414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-11T11:09:22.528874Z","caller":"traceutil/trace.go:171","msg":"trace[1664591235] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1298; }","duration":"330.412361ms","start":"2026-08-11T11:09:22.197154Z","end":"2026-08-11T11:09:22.527567Z","steps":["trace[1664591235] 'agreement among raft nodes before linearized reading' (duration: 329.614444ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:22.529127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:22.19714Z","time spent":"331.973341ms","remote":"127.0.0.1:50906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-11T11:09:22.529155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.670297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-11T11:09:22.529277Z","caller":"traceutil/trace.go:171","msg":"trace[766056358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1298; }","duration":"231.808573ms","start":"2026-08-11T11:09:22.297448Z","end":"2026-08-11T11:09:22.529256Z","steps":["trace[766056358] 'agreement among raft nodes before linearized reading' (duration: 231.464727ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:22.529789Z","caller":"traceutil/trace.go:171","msg":"trace[1759243451] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1298; }","duration":"231.025807ms","start":"2026-08-11T11:09:22.298041Z","end":"2026-08-11T11:09:22.529066Z","steps":["trace[1759243451] 'agreement among raft nodes before linearized reading' (duration: 230.813248ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:22.885631Z","caller":"traceutil/trace.go:171","msg":"trace[1137029561] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"355.422434ms","start":"2026-08-11T11:09:22.530171Z","end":"2026-08-11T11:09:22.885593Z","steps":["trace[1137029561] 'process raft request' (duration: 289.5651ms)","trace[1137029561] 'compare' (duration: 65.526659ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:09:22.885785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:22.530152Z","time spent":"355.56286ms","remote":"127.0.0.1:50716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":656,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:09:22.885954Z","caller":"traceutil/trace.go:171","msg":"trace[1205473896] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"352.049981ms","start":"2026-08-11T11:09:22.533869Z","end":"2026-08-11T11:09:22.885919Z","steps":["trace[1205473896] 'process raft request' (duration: 351.753317ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:22.886027Z","caller":"traceutil/trace.go:171","msg":"trace[143035013] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"350.291571ms","start":"2026-08-11T11:09:22.535724Z","end":"2026-08-11T11:09:22.886016Z","steps":["trace[143035013] 'process raft request' (duration: 350.075241ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:22.886028Z","caller":"traceutil/trace.go:171","msg":"trace[457600787] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"350.371775ms","start":"2026-08-11T11:09:22.535637Z","end":"2026-08-11T11:09:22.886009Z","steps":["trace[457600787] 'process raft request' (duration: 350.110023ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:22.886109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:22.533857Z","time spent":"352.171976ms","remote":"127.0.0.1:50906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:09:22.886142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:22.535718Z","time spent":"350.351765ms","remote":"127.0.0.1:50906","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-08-11T11:09:22.885957Z","caller":"traceutil/trace.go:171","msg":"trace[943668360] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"350.162695ms","start":"2026-08-11T11:09:22.535767Z","end":"2026-08-11T11:09:22.88593Z","steps":["trace[943668360] 'process raft request' (duration: 350.097832ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:22.886162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:22.535627Z","time spent":"350.44596ms","remote":"127.0.0.1:50906","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-08-11T11:09:22.886117Z","caller":"traceutil/trace.go:171","msg":"trace[377308514] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1352; }","duration":"353.33295ms","start":"2026-08-11T11:09:22.532735Z","end":"2026-08-11T11:09:22.886068Z","steps":["trace[377308514] 'read index received' (duration: 287.203143ms)","trace[377308514] 'applied index is now lower than readState.Index' (duration: 66.125477ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:09:22.886316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:22.535743Z","time spent":"350.495012ms","remote":"127.0.0.1:50906","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-08-11T11:09:22.886358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.616893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:1 size:199"} {"level":"info","ts":"2026-08-11T11:09:22.886398Z","caller":"traceutil/trace.go:171","msg":"trace[2144836219] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:1; response_revision:1303; }","duration":"353.655445ms","start":"2026-08-11T11:09:22.532731Z","end":"2026-08-11T11:09:22.886386Z","steps":["trace[2144836219] 'agreement among raft nodes before linearized reading' (duration: 353.577501ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:22.886429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:22.532709Z","time spent":"353.712787ms","remote":"127.0.0.1:50834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":222,"request content":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" "} {"level":"warn","ts":"2026-08-11T11:09:22.886457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.33862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T11:09:22.886489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.226565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-08-11T11:09:22.886519Z","caller":"traceutil/trace.go:171","msg":"trace[580248065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1303; }","duration":"127.420534ms","start":"2026-08-11T11:09:22.759077Z","end":"2026-08-11T11:09:22.886497Z","steps":["trace[580248065] 'agreement among raft nodes before linearized reading' (duration: 127.306768ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:22.886529Z","caller":"traceutil/trace.go:171","msg":"trace[1912014971] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1303; }","duration":"291.305699ms","start":"2026-08-11T11:09:22.595212Z","end":"2026-08-11T11:09:22.886518Z","steps":["trace[1912014971] 'agreement among raft nodes before linearized reading' (duration: 291.177153ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:22.886595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.682626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1536"} {"level":"info","ts":"2026-08-11T11:09:22.886663Z","caller":"traceutil/trace.go:171","msg":"trace[1003280628] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1303; }","duration":"353.805271ms","start":"2026-08-11T11:09:22.532839Z","end":"2026-08-11T11:09:22.886644Z","steps":["trace[1003280628] 'agreement among raft nodes before linearized reading' (duration: 353.503478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:22.886731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:22.532832Z","time spent":"353.883754ms","remote":"127.0.0.1:50816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":1559,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-08-11T11:09:23.167495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.293231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-08-11T11:09:23.167618Z","caller":"traceutil/trace.go:171","msg":"trace[1836725846] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:1304; }","duration":"168.467089ms","start":"2026-08-11T11:09:22.999124Z","end":"2026-08-11T11:09:23.167591Z","steps":["trace[1836725846] 'range keys from in-memory index tree' (duration: 168.121784ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:23.168095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.978113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:09:23.16818Z","caller":"traceutil/trace.go:171","msg":"trace[682341660] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"157.616335ms","start":"2026-08-11T11:09:23.010548Z","end":"2026-08-11T11:09:23.168164Z","steps":["trace[682341660] 'process raft request' (duration: 47.507166ms)","trace[682341660] 'compare' (duration: 109.632758ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:09:23.881057Z","caller":"traceutil/trace.go:171","msg":"trace[491957521] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1360; }","duration":"129.338627ms","start":"2026-08-11T11:09:23.751695Z","end":"2026-08-11T11:09:23.881034Z","steps":["trace[491957521] 'read index received' (duration: 58.938318ms)","trace[491957521] 'applied index is now lower than readState.Index' (duration: 70.399389ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:09:23.881212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.511595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:09:23.88125Z","caller":"traceutil/trace.go:171","msg":"trace[458311023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1307; }","duration":"129.595368ms","start":"2026-08-11T11:09:23.751643Z","end":"2026-08-11T11:09:23.881239Z","steps":["trace[458311023] 'agreement among raft nodes before linearized reading' (duration: 129.509264ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:23.881263Z","caller":"traceutil/trace.go:171","msg":"trace[945407010] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"243.973523ms","start":"2026-08-11T11:09:23.637137Z","end":"2026-08-11T11:09:23.88111Z","steps":["trace[945407010] 'process raft request' (duration: 173.554322ms)","trace[945407010] 'compare' (duration: 70.060394ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:09:24.735639Z","caller":"traceutil/trace.go:171","msg":"trace[569556168] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"198.094125ms","start":"2026-08-11T11:09:24.537525Z","end":"2026-08-11T11:09:24.73562Z","steps":["trace[569556168] 'process raft request' (duration: 198.000641ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:24.917544Z","caller":"traceutil/trace.go:171","msg":"trace[799012354] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1363; }","duration":"165.582574ms","start":"2026-08-11T11:09:24.751945Z","end":"2026-08-11T11:09:24.917527Z","steps":["trace[799012354] 'read index received' (duration: 73.455148ms)","trace[799012354] 'applied index is now lower than readState.Index' (duration: 92.126826ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:09:24.917599Z","caller":"traceutil/trace.go:171","msg":"trace[2137004337] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"166.527468ms","start":"2026-08-11T11:09:24.751024Z","end":"2026-08-11T11:09:24.917551Z","steps":["trace[2137004337] 'process raft request' (duration: 74.428104ms)","trace[2137004337] 'compare' (duration: 92.002969ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:09:24.917665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.707709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:09:24.917683Z","caller":"traceutil/trace.go:171","msg":"trace[1727338510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1310; }","duration":"165.757672ms","start":"2026-08-11T11:09:24.751921Z","end":"2026-08-11T11:09:24.917678Z","steps":["trace[1727338510] 'agreement among raft nodes before linearized reading' (duration: 165.699149ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:26.305201Z","caller":"traceutil/trace.go:171","msg":"trace[359042108] linearizableReadLoop","detail":"{readStateIndex:1370; appliedIndex:1369; }","duration":"206.071812ms","start":"2026-08-11T11:09:26.099101Z","end":"2026-08-11T11:09:26.305173Z","steps":["trace[359042108] 'read index received' (duration: 205.744087ms)","trace[359042108] 'applied index is now lower than readState.Index' (duration: 326.155µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:09:26.305291Z","caller":"traceutil/trace.go:171","msg":"trace[1017186317] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"337.69749ms","start":"2026-08-11T11:09:25.967555Z","end":"2026-08-11T11:09:26.305253Z","steps":["trace[1017186317] 'process raft request' (duration: 337.423907ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:26.305385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.260371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:09:26.305485Z","caller":"traceutil/trace.go:171","msg":"trace[243276960] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1315; }","duration":"206.419708ms","start":"2026-08-11T11:09:26.099042Z","end":"2026-08-11T11:09:26.305462Z","steps":["trace[243276960] 'agreement among raft nodes before linearized reading' (duration: 206.23322ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:26.30548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:25.967534Z","time spent":"337.836666ms","remote":"127.0.0.1:50906","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-08-11T11:09:26.699017Z","caller":"traceutil/trace.go:171","msg":"trace[1809476529] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1370; }","duration":"236.603589ms","start":"2026-08-11T11:09:26.462387Z","end":"2026-08-11T11:09:26.69899Z","steps":["trace[1809476529] 'read index received' (duration: 236.388998ms)","trace[1809476529] 'applied index is now lower than readState.Index' (duration: 213.501µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:09:26.699083Z","caller":"traceutil/trace.go:171","msg":"trace[1750981638] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"314.225101ms","start":"2026-08-11T11:09:26.384827Z","end":"2026-08-11T11:09:26.699052Z","steps":["trace[1750981638] 'process raft request' (duration: 314.00303ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:26.699195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.626482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T11:09:26.699249Z","caller":"traceutil/trace.go:171","msg":"trace[1838211798] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1316; }","duration":"127.760478ms","start":"2026-08-11T11:09:26.571475Z","end":"2026-08-11T11:09:26.699235Z","steps":["trace[1838211798] 'agreement among raft nodes before linearized reading' (duration: 127.661144ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:26.699261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:26.3848Z","time spent":"314.350046ms","remote":"127.0.0.1:50808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5719,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:09:26.699288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.875461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T11:09:26.699365Z","caller":"traceutil/trace.go:171","msg":"trace[831313588] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1316; }","duration":"237.030868ms","start":"2026-08-11T11:09:26.462316Z","end":"2026-08-11T11:09:26.699347Z","steps":["trace[831313588] 'agreement among raft nodes before linearized reading' (duration: 236.817538ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:27.36403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.71567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-11T11:09:27.364208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.45418ms","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-08-11T11:09:27.364255Z","caller":"traceutil/trace.go:171","msg":"trace[1322763096] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1317; }","duration":"429.545635ms","start":"2026-08-11T11:09:26.934696Z","end":"2026-08-11T11:09:27.364242Z","steps":["trace[1322763096] 'range keys from in-memory index tree' (duration: 429.261511ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:27.364273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.382309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-11T11:09:27.364302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:26.934676Z","time spent":"429.616658ms","remote":"127.0.0.1:50906","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-08-11T11:09:27.364352Z","caller":"traceutil/trace.go:171","msg":"trace[1416388887] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1317; }","duration":"448.510925ms","start":"2026-08-11T11:09:26.915824Z","end":"2026-08-11T11:09:27.364335Z","steps":["trace[1416388887] 'count revisions from in-memory index tree' (duration: 448.302515ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:27.364441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:26.915792Z","time spent":"448.607899ms","remote":"127.0.0.1:50974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-08-11T11:09:27.36477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.92115ms","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-08-11T11:09:27.364818Z","caller":"traceutil/trace.go:171","msg":"trace[1311352728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1317; }","duration":"428.017414ms","start":"2026-08-11T11:09:26.936792Z","end":"2026-08-11T11:09:27.36481Z","steps":["trace[1311352728] 'range keys from in-memory index tree' (duration: 427.354274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:27.364842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:26.936772Z","time spent":"428.063056ms","remote":"127.0.0.1:50906","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-08-11T11:09:27.364099Z","caller":"traceutil/trace.go:171","msg":"trace[1759059270] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1317; }","duration":"169.838475ms","start":"2026-08-11T11:09:27.194246Z","end":"2026-08-11T11:09:27.364085Z","steps":["trace[1759059270] 'count revisions from in-memory index tree' (duration: 169.577893ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:27.365022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.572093ms","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-08-11T11:09:27.365123Z","caller":"traceutil/trace.go:171","msg":"trace[960391943] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1317; }","duration":"427.746391ms","start":"2026-08-11T11:09:26.937358Z","end":"2026-08-11T11:09:27.365104Z","steps":["trace[960391943] 'range keys from in-memory index tree' (duration: 426.86144ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:27.365198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:26.937346Z","time spent":"427.839476ms","remote":"127.0.0.1:50906","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-08-11T11:09:27.365253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.812694ms","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-08-11T11:09:27.365704Z","caller":"traceutil/trace.go:171","msg":"trace[977748241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1317; }","duration":"428.314168ms","start":"2026-08-11T11:09:26.937363Z","end":"2026-08-11T11:09:27.365677Z","steps":["trace[977748241] 'range keys from in-memory index tree' (duration: 426.41813ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:27.365801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:26.937354Z","time spent":"428.430593ms","remote":"127.0.0.1:50906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-11T11:09:27.634108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.705687ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:09:27.634299Z","caller":"traceutil/trace.go:171","msg":"trace[1386325863] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"265.749199ms","start":"2026-08-11T11:09:27.36851Z","end":"2026-08-11T11:09:27.63426Z","steps":["trace[1386325863] 'process raft request' (duration: 95.831671ms)","trace[1386325863] 'compare' (duration: 169.608183ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:09:27.634366Z","caller":"traceutil/trace.go:171","msg":"trace[1044682542] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"265.289218ms","start":"2026-08-11T11:09:27.369056Z","end":"2026-08-11T11:09:27.634345Z","steps":["trace[1044682542] 'process raft request' (duration: 265.128ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:27.634476Z","caller":"traceutil/trace.go:171","msg":"trace[182213533] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"264.816856ms","start":"2026-08-11T11:09:27.36965Z","end":"2026-08-11T11:09:27.634467Z","steps":["trace[182213533] 'process raft request' (duration: 264.598486ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:27.634632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.433916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:09:27.634667Z","caller":"traceutil/trace.go:171","msg":"trace[446346577] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1321; }","duration":"106.505169ms","start":"2026-08-11T11:09:27.528153Z","end":"2026-08-11T11:09:27.634659Z","steps":["trace[446346577] 'agreement among raft nodes before linearized reading' (duration: 106.444846ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:27.634408Z","caller":"traceutil/trace.go:171","msg":"trace[1188505914] linearizableReadLoop","detail":"{readStateIndex:1376; appliedIndex:1373; }","duration":"106.205045ms","start":"2026-08-11T11:09:27.528192Z","end":"2026-08-11T11:09:27.634398Z","steps":["trace[1188505914] 'read index received' (duration: 37.772µs)","trace[1188505914] 'applied index is now lower than readState.Index' (duration: 106.161423ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:09:28.173402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.826977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:09:28.173472Z","caller":"traceutil/trace.go:171","msg":"trace[622622487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1321; }","duration":"421.944892ms","start":"2026-08-11T11:09:27.751513Z","end":"2026-08-11T11:09:28.173458Z","steps":["trace[622622487] 'range keys from in-memory index tree' (duration: 421.731412ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:28.173501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:27.751486Z","time spent":"422.008565ms","remote":"127.0.0.1:50634","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-08-11T11:09:28.852538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.225761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:09:28.852672Z","caller":"traceutil/trace.go:171","msg":"trace[1602493619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1323; }","duration":"100.403291ms","start":"2026-08-11T11:09:28.752246Z","end":"2026-08-11T11:09:28.85265Z","steps":["trace[1602493619] 'range keys from in-memory index tree' (duration: 100.102646ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:29.601827Z","caller":"traceutil/trace.go:171","msg":"trace[1286078541] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"212.574138ms","start":"2026-08-11T11:09:29.389207Z","end":"2026-08-11T11:09:29.601782Z","steps":["trace[1286078541] 'process raft request' (duration: 212.419431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:30.650511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012637469s","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-08-11T11:09:30.650631Z","caller":"traceutil/trace.go:171","msg":"trace[288108344] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1325; }","duration":"1.012810337s","start":"2026-08-11T11:09:29.637801Z","end":"2026-08-11T11:09:30.650612Z","steps":["trace[288108344] 'range keys from in-memory index tree' (duration: 1.012424209s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:30.650687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:29.637781Z","time spent":"1.012892501s","remote":"127.0.0.1:50906","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-08-11T11:09:30.650845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012672621s","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-08-11T11:09:30.650955Z","caller":"traceutil/trace.go:171","msg":"trace[309290268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1325; }","duration":"1.012824779s","start":"2026-08-11T11:09:29.638113Z","end":"2026-08-11T11:09:30.650938Z","steps":["trace[309290268] 'range keys from in-memory index tree' (duration: 1.012527095s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:30.651014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:29.638096Z","time spent":"1.012906102s","remote":"127.0.0.1:50906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-11T11:09:30.651238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012324134s","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-08-11T11:09:30.6514Z","caller":"traceutil/trace.go:171","msg":"trace[310450601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1325; }","duration":"1.012390948s","start":"2026-08-11T11:09:29.638867Z","end":"2026-08-11T11:09:30.651258Z","steps":["trace[310450601] 'range keys from in-memory index tree' (duration: 1.012180438s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:30.651435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.395102ms","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-08-11T11:09:30.651459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:29.638848Z","time spent":"1.012595227s","remote":"127.0.0.1:50906","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-08-11T11:09:30.6515Z","caller":"traceutil/trace.go:171","msg":"trace[1624612923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1325; }","duration":"324.484187ms","start":"2026-08-11T11:09:30.326992Z","end":"2026-08-11T11:09:30.651476Z","steps":["trace[1624612923] 'range keys from in-memory index tree' (duration: 324.065707ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:30.651567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:30.326977Z","time spent":"324.576032ms","remote":"127.0.0.1:50906","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-08-11T11:09:30.651635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.920793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T11:09:30.651586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.962347ms","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-08-11T11:09:30.651695Z","caller":"traceutil/trace.go:171","msg":"trace[726560398] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1325; }","duration":"900.024468ms","start":"2026-08-11T11:09:29.751651Z","end":"2026-08-11T11:09:30.651676Z","steps":["trace[726560398] 'range keys from in-memory index tree' (duration: 899.820258ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:30.651707Z","caller":"traceutil/trace.go:171","msg":"trace[853623746] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1325; }","duration":"481.092694ms","start":"2026-08-11T11:09:30.170597Z","end":"2026-08-11T11:09:30.651689Z","steps":["trace[853623746] 'range keys from in-memory index tree' (duration: 480.944416ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:30.651765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:29.751627Z","time spent":"900.107142ms","remote":"127.0.0.1:50640","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-08-11T11:09:30.651008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.198647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:09:30.651827Z","caller":"traceutil/trace.go:171","msg":"trace[431150636] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1325; }","duration":"374.052579ms","start":"2026-08-11T11:09:30.277755Z","end":"2026-08-11T11:09:30.651807Z","steps":["trace[431150636] 'count revisions from in-memory index tree' (duration: 373.117244ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:30.651865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:30.277736Z","time spent":"374.116042ms","remote":"127.0.0.1:51048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-08-11T11:09:31.168905Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903913804895415263,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-11T11:09:31.559151Z","caller":"traceutil/trace.go:171","msg":"trace[810702625] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"901.221554ms","start":"2026-08-11T11:09:30.657899Z","end":"2026-08-11T11:09:31.55912Z","steps":["trace[810702625] 'process raft request' (duration: 901.073427ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:31.559442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:30.65788Z","time spent":"901.350249ms","remote":"127.0.0.1:50906","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-08-11T11:09:31.609229Z","caller":"traceutil/trace.go:171","msg":"trace[1772836991] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"950.530894ms","start":"2026-08-11T11:09:30.658678Z","end":"2026-08-11T11:09:31.609209Z","steps":["trace[1772836991] 'process raft request' (duration: 950.481252ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:31.609255Z","caller":"traceutil/trace.go:171","msg":"trace[1666161511] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"951.30175ms","start":"2026-08-11T11:09:30.657923Z","end":"2026-08-11T11:09:31.609225Z","steps":["trace[1666161511] 'process raft request' (duration: 951.08969ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:31.609295Z","caller":"traceutil/trace.go:171","msg":"trace[1343367737] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"950.669681ms","start":"2026-08-11T11:09:30.65858Z","end":"2026-08-11T11:09:31.60925Z","steps":["trace[1343367737] 'process raft request' (duration: 950.547075ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:31.609361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:30.658673Z","time spent":"950.616938ms","remote":"127.0.0.1:50906","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-08-11T11:09:31.6094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:30.657905Z","time spent":"951.419166ms","remote":"127.0.0.1:50906","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-08-11T11:09:31.609432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:30.658573Z","time spent":"950.789006ms","remote":"127.0.0.1:50906","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-08-11T11:09:31.609433Z","caller":"traceutil/trace.go:171","msg":"trace[648987504] linearizableReadLoop","detail":"{readStateIndex:1385; appliedIndex:1380; }","duration":"940.863476ms","start":"2026-08-11T11:09:30.668526Z","end":"2026-08-11T11:09:31.609389Z","steps":["trace[648987504] 'read index received' (duration: 890.476414ms)","trace[648987504] 'applied index is now lower than readState.Index' (duration: 50.385452ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:09:31.609471Z","caller":"traceutil/trace.go:171","msg":"trace[2044181192] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"751.012163ms","start":"2026-08-11T11:09:30.858451Z","end":"2026-08-11T11:09:31.609463Z","steps":["trace[2044181192] 'process raft request' (duration: 750.73006ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:31.609551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:30.858428Z","time spent":"751.097187ms","remote":"127.0.0.1:50906","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-08-11T11:09:31.610092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.659831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:09:31.610242Z","caller":"traceutil/trace.go:171","msg":"trace[1067965182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1330; }","duration":"858.221608ms","start":"2026-08-11T11:09:30.751963Z","end":"2026-08-11T11:09:31.610185Z","steps":["trace[1067965182] 'agreement among raft nodes before linearized reading' (duration: 857.587917ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:31.61029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:30.751946Z","time spent":"858.330213ms","remote":"127.0.0.1:50634","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-08-11T11:09:31.61199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.431998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.191\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-11T11:09:31.612066Z","caller":"traceutil/trace.go:171","msg":"trace[1452350264] range","detail":"{range_begin:/registry/masterleases/199.19.213.191; range_end:; response_count:1; response_revision:1330; }","duration":"943.582596ms","start":"2026-08-11T11:09:30.668465Z","end":"2026-08-11T11:09:31.612048Z","steps":["trace[1452350264] 'agreement among raft nodes before linearized reading' (duration: 941.039054ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:31.612108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:30.668454Z","time spent":"943.644649ms","remote":"127.0.0.1:50662","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.191\" "} {"level":"warn","ts":"2026-08-11T11:09:32.308064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.697499ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-08-11T11:09:32.308386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:31.613134Z","time spent":"695.247065ms","remote":"127.0.0.1:50662","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-11T11:09:32.308395Z","caller":"traceutil/trace.go:171","msg":"trace[121873851] linearizableReadLoop","detail":"{readStateIndex:1387; appliedIndex:1385; }","duration":"411.561632ms","start":"2026-08-11T11:09:31.896812Z","end":"2026-08-11T11:09:32.308373Z","steps":["trace[121873851] 'read index received' (duration: 42.312µs)","trace[121873851] 'applied index is now lower than readState.Index' (duration: 411.51814ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:09:32.308783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.004836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-11T11:09:32.308832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.021864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T11:09:32.308847Z","caller":"traceutil/trace.go:171","msg":"trace[745670163] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1331; }","duration":"294.117041ms","start":"2026-08-11T11:09:32.014715Z","end":"2026-08-11T11:09:32.308832Z","steps":["trace[745670163] 'agreement among raft nodes before linearized reading' (duration: 293.958734ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:32.308887Z","caller":"traceutil/trace.go:171","msg":"trace[1324021333] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1331; }","duration":"412.123079ms","start":"2026-08-11T11:09:31.896748Z","end":"2026-08-11T11:09:32.308871Z","steps":["trace[1324021333] 'agreement among raft nodes before linearized reading' (duration: 411.681738ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:32.308897Z","caller":"traceutil/trace.go:171","msg":"trace[2102788767] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"694.52391ms","start":"2026-08-11T11:09:31.614356Z","end":"2026-08-11T11:09:32.30888Z","steps":["trace[2102788767] 'process raft request' (duration: 693.88698ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:32.308936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:31.896725Z","time spent":"412.195762ms","remote":"127.0.0.1:51006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":54,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-08-11T11:09:32.309114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:31.614339Z","time spent":"694.597264ms","remote":"127.0.0.1:50906","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-08-11T11:09:32.586192Z","caller":"traceutil/trace.go:171","msg":"trace[558397251] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"276.180002ms","start":"2026-08-11T11:09:32.309989Z","end":"2026-08-11T11:09:32.586169Z","steps":["trace[558397251] 'process raft request' (duration: 276.039275ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:33.406373Z","caller":"traceutil/trace.go:171","msg":"trace[1679518397] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"150.547559ms","start":"2026-08-11T11:09:33.255804Z","end":"2026-08-11T11:09:33.406352Z","steps":["trace[1679518397] 'process raft request' (duration: 150.384391ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:34.67386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.549945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:09:34.673919Z","caller":"traceutil/trace.go:171","msg":"trace[1421267115] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1339; }","duration":"168.654569ms","start":"2026-08-11T11:09:34.505253Z","end":"2026-08-11T11:09:34.673907Z","steps":["trace[1421267115] 'count revisions from in-memory index tree' (duration: 168.437039ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:35.898512Z","caller":"traceutil/trace.go:171","msg":"trace[2019971118] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"275.434969ms","start":"2026-08-11T11:09:35.623051Z","end":"2026-08-11T11:09:35.898486Z","steps":["trace[2019971118] 'process raft request' (duration: 275.276871ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:35.901968Z","caller":"traceutil/trace.go:171","msg":"trace[2142919579] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"235.389843ms","start":"2026-08-11T11:09:35.666561Z","end":"2026-08-11T11:09:35.901951Z","steps":["trace[2142919579] 'process raft request' (duration: 235.141141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:35.955246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.859867ms","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-08-11T11:09:35.955352Z","caller":"traceutil/trace.go:171","msg":"trace[71044108] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1341; }","duration":"250.021085ms","start":"2026-08-11T11:09:35.705312Z","end":"2026-08-11T11:09:35.955333Z","steps":["trace[71044108] 'agreement among raft nodes before linearized reading' (duration: 249.751382ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:35.955243Z","caller":"traceutil/trace.go:171","msg":"trace[1943507032] linearizableReadLoop","detail":"{readStateIndex:1397; appliedIndex:1395; }","duration":"249.650677ms","start":"2026-08-11T11:09:35.705396Z","end":"2026-08-11T11:09:35.955047Z","steps":["trace[1943507032] 'read index received' (duration: 192.934719ms)","trace[1943507032] 'applied index is now lower than readState.Index' (duration: 56.714518ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:09:35.955636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.785515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T11:09:35.955677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.91198ms","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-08-11T11:09:35.955719Z","caller":"traceutil/trace.go:171","msg":"trace[889947629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1341; }","duration":"204.933292ms","start":"2026-08-11T11:09:35.750766Z","end":"2026-08-11T11:09:35.955699Z","steps":["trace[889947629] 'agreement among raft nodes before linearized reading' (duration: 204.787695ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:35.955732Z","caller":"traceutil/trace.go:171","msg":"trace[332103819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1341; }","duration":"249.008895ms","start":"2026-08-11T11:09:35.70671Z","end":"2026-08-11T11:09:35.955719Z","steps":["trace[332103819] 'agreement among raft nodes before linearized reading' (duration: 248.832866ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:35.955886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.952753ms","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-08-11T11:09:35.955913Z","caller":"traceutil/trace.go:171","msg":"trace[491783774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1341; }","duration":"249.000355ms","start":"2026-08-11T11:09:35.706907Z","end":"2026-08-11T11:09:35.955907Z","steps":["trace[491783774] 'agreement among raft nodes before linearized reading' (duration: 248.925411ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:36.284595Z","caller":"traceutil/trace.go:171","msg":"trace[1398972609] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"323.808715ms","start":"2026-08-11T11:09:35.960753Z","end":"2026-08-11T11:09:36.284561Z","steps":["trace[1398972609] 'process raft request' (duration: 323.740462ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:36.284726Z","caller":"traceutil/trace.go:171","msg":"trace[185665120] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"324.186024ms","start":"2026-08-11T11:09:35.960503Z","end":"2026-08-11T11:09:36.284689Z","steps":["trace[185665120] 'process raft request' (duration: 316.593583ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:36.284797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:35.960737Z","time spent":"323.956692ms","remote":"127.0.0.1:50906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:09:36.284881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:35.960479Z","time spent":"324.3226ms","remote":"127.0.0.1:50906","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-08-11T11:09:36.826909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.561708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:09:36.82715Z","caller":"traceutil/trace.go:171","msg":"trace[658096888] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"445.832125ms","start":"2026-08-11T11:09:36.381294Z","end":"2026-08-11T11:09:36.827126Z","steps":["trace[658096888] 'process raft request' (duration: 161.005133ms)","trace[658096888] 'compare' (duration: 284.18304ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:09:36.827187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:09:36.381278Z","time spent":"445.893378ms","remote":"127.0.0.1:50906","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-08-11T11:09:38.20063Z","caller":"traceutil/trace.go:171","msg":"trace[1164171988] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"124.984789ms","start":"2026-08-11T11:09:38.07562Z","end":"2026-08-11T11:09:38.200605Z","steps":["trace[1164171988] 'process raft request' (duration: 124.79532ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:38.538798Z","caller":"traceutil/trace.go:171","msg":"trace[1629040204] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"182.481263ms","start":"2026-08-11T11:09:38.356291Z","end":"2026-08-11T11:09:38.538772Z","steps":["trace[1629040204] 'process raft request' (duration: 182.337106ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:38.628907Z","caller":"traceutil/trace.go:171","msg":"trace[1282008980] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"271.871449ms","start":"2026-08-11T11:09:38.357002Z","end":"2026-08-11T11:09:38.628874Z","steps":["trace[1282008980] 'process raft request' (duration: 203.636346ms)","trace[1282008980] 'compare' (duration: 67.834733ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:09:38.628883Z","caller":"traceutil/trace.go:171","msg":"trace[247170270] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1405; }","duration":"134.883837ms","start":"2026-08-11T11:09:38.493916Z","end":"2026-08-11T11:09:38.6288Z","steps":["trace[247170270] 'read index received' (duration: 44.779306ms)","trace[247170270] 'applied index is now lower than readState.Index' (duration: 90.102701ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:09:38.629865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.858865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"info","ts":"2026-08-11T11:09:38.62996Z","caller":"traceutil/trace.go:171","msg":"trace[587931146] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1350; }","duration":"136.081326ms","start":"2026-08-11T11:09:38.493865Z","end":"2026-08-11T11:09:38.629946Z","steps":["trace[587931146] 'agreement among raft nodes before linearized reading' (duration: 135.119439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:40.750026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.752168ms","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-08-11T11:09:40.750134Z","caller":"traceutil/trace.go:171","msg":"trace[269101230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1359; }","duration":"206.945338ms","start":"2026-08-11T11:09:40.54317Z","end":"2026-08-11T11:09:40.750116Z","steps":["trace[269101230] 'range keys from in-memory index tree' (duration: 206.503947ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:09:40.750309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.857299ms","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-08-11T11:09:40.750392Z","caller":"traceutil/trace.go:171","msg":"trace[1687138409] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1359; }","duration":"116.980325ms","start":"2026-08-11T11:09:40.633389Z","end":"2026-08-11T11:09:40.750369Z","steps":["trace[1687138409] 'range keys from in-memory index tree' (duration: 116.604067ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:09:40.952656Z","caller":"traceutil/trace.go:171","msg":"trace[1181321927] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"100.392102ms","start":"2026-08-11T11:09:40.852239Z","end":"2026-08-11T11:09:40.952631Z","steps":["trace[1181321927] 'process raft request' (duration: 12.250399ms)","trace[1181321927] 'compare' (duration: 88.010065ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:09:45.628629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.437569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:09:45.628969Z","caller":"traceutil/trace.go:171","msg":"trace[1135191002] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1378; }","duration":"200.8614ms","start":"2026-08-11T11:09:45.428086Z","end":"2026-08-11T11:09:45.628948Z","steps":["trace[1135191002] 'count revisions from in-memory index tree' (duration: 200.372895ms)"],"step_count":1}