{"level":"warn","ts":"2026-07-17T03:44:49.375547Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T03:44:49.37565Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.204.45.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-17T03:44:49.375731Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T03:44:49.375738Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-07-17T03:44:49.375753Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-17T03:44:49.376353Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-07-17T03:44:49.376436Z","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.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-17T03:44:49.378806Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.174209ms"} {"level":"info","ts":"2026-07-17T03:44:49.384203Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-07-17T03:44:49.384309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-07-17T03:44:49.384329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-07-17T03:44:49.38434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-17T03:44:49.384352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-07-17T03:44:49.384385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-07-17T03:44:49.389536Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-17T03:44:49.390967Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-17T03:44:49.392618Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-17T03:44:49.395707Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-17T03:44:49.396714Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-17T03:44:49.396797Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T03:44:49.396837Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T03:44:49.396849Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T03:44:49.399923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-07-17T03:44:49.400041Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-07-17T03:44:49.406135Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-17T03:44:49.406326Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-17T03:44:49.406359Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-17T03:44:49.406469Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-07-17T03:44:49.406485Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-07-17T03:44:49.485413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-07-17T03:44:49.485481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-07-17T03:44:49.485632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-07-17T03:44:49.485666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-07-17T03:44:49.485746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-07-17T03:44:49.485831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-07-17T03:44:49.4859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-07-17T03:44:49.487055Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T03:44:49.488262Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T03:44:49.488267Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-07-17T03:44:49.488298Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T03:44:49.488628Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-17T03:44:49.488683Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-17T03:44:49.488806Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T03:44:49.488949Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T03:44:49.488994Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T03:44:49.490524Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-07-17T03:44:49.492025Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-17T03:44:52.712153Z","caller":"traceutil/trace.go:171","msg":"trace[548422611] transaction","detail":"{read_only:false; response_revision:161; number_of_response:1; }","duration":"221.270423ms","start":"2026-07-17T03:44:52.490856Z","end":"2026-07-17T03:44:52.712127Z","steps":["trace[548422611] 'process raft request' (duration: 221.221531ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:44:52.712214Z","caller":"traceutil/trace.go:171","msg":"trace[1851870889] transaction","detail":"{read_only:false; response_revision:160; number_of_response:1; }","duration":"312.472336ms","start":"2026-07-17T03:44:52.399706Z","end":"2026-07-17T03:44:52.712179Z","steps":["trace[1851870889] 'process raft request' (duration: 302.307534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:44:52.713444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:44:52.399691Z","time spent":"312.598028ms","remote":"127.0.0.1:40930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":760,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T03:44:56.264744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.760154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:44:56.264862Z","caller":"traceutil/trace.go:171","msg":"trace[1720409044] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:0; response_revision:283; }","duration":"243.936528ms","start":"2026-07-17T03:44:56.020901Z","end":"2026-07-17T03:44:56.264837Z","steps":["trace[1720409044] 'range keys from in-memory index tree' (duration: 243.629781ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:45:02.37113Z","caller":"traceutil/trace.go:171","msg":"trace[497630293] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"664.927401ms","start":"2026-07-17T03:45:01.706177Z","end":"2026-07-17T03:45:02.371105Z","steps":["trace[497630293] 'process raft request' (duration: 664.780538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:02.371593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:01.70616Z","time spent":"665.092786ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:45:03.352942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.372065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:45:03.353019Z","caller":"traceutil/trace.go:171","msg":"trace[519948998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:362; }","duration":"145.469168ms","start":"2026-07-17T03:45:03.207531Z","end":"2026-07-17T03:45:03.353Z","steps":["trace[519948998] 'range keys from in-memory index tree' (duration: 145.324394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:03.353018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.031189ms","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-07-17T03:45:03.353106Z","caller":"traceutil/trace.go:171","msg":"trace[1078116198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:362; }","duration":"138.159153ms","start":"2026-07-17T03:45:03.214926Z","end":"2026-07-17T03:45:03.353085Z","steps":["trace[1078116198] 'range keys from in-memory index tree' (duration: 137.941307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:03.353115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.860393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-17T03:45:03.35319Z","caller":"traceutil/trace.go:171","msg":"trace[2054376259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:362; }","duration":"340.982056ms","start":"2026-07-17T03:45:03.012189Z","end":"2026-07-17T03:45:03.353171Z","steps":["trace[2054376259] 'range keys from in-memory index tree' (duration: 340.770891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:03.353234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:03.01217Z","time spent":"341.056107ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T03:45:03.353204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.849809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-17T03:45:03.353368Z","caller":"traceutil/trace.go:171","msg":"trace[517797088] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:362; }","duration":"409.086373ms","start":"2026-07-17T03:45:02.944249Z","end":"2026-07-17T03:45:03.353335Z","steps":["trace[517797088] 'range keys from in-memory index tree' (duration: 408.704655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:03.353428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.767477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-17T03:45:03.353436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:02.944229Z","time spent":"409.195076ms","remote":"127.0.0.1:40632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.53\" "} {"level":"info","ts":"2026-07-17T03:45:03.353483Z","caller":"traceutil/trace.go:171","msg":"trace[464052344] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:362; }","duration":"149.850608ms","start":"2026-07-17T03:45:03.203619Z","end":"2026-07-17T03:45:03.353469Z","steps":["trace[464052344] 'range keys from in-memory index tree' (duration: 149.164772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:03.856136Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900641097247568,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T03:45:03.920738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:03.355251Z","time spent":"565.483311ms","remote":"127.0.0.1:40632","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-17T03:45:05.245707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.225643085s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T03:45:05.245971Z","caller":"traceutil/trace.go:171","msg":"trace[1423161510] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"1.888800956s","start":"2026-07-17T03:45:03.357148Z","end":"2026-07-17T03:45:05.245949Z","steps":["trace[1423161510] 'process raft request' (duration: 662.594418ms)","trace[1423161510] 'compare' (duration: 1.225508132s)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:45:05.245982Z","caller":"traceutil/trace.go:171","msg":"trace[31945824] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:374; }","duration":"1.890434524s","start":"2026-07-17T03:45:03.35553Z","end":"2026-07-17T03:45:05.245965Z","steps":["trace[31945824] 'read index received' (duration: 565.119853ms)","trace[31945824] 'applied index is now lower than readState.Index' (duration: 1.325313481s)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:45:05.246075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:03.357129Z","time spent":"1.888887619s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:45:05.246148Z","caller":"traceutil/trace.go:171","msg":"trace[578354727] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"1.88762524s","start":"2026-07-17T03:45:03.358517Z","end":"2026-07-17T03:45:05.246142Z","steps":["trace[578354727] 'process raft request' (duration: 1.887411836s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:05.246175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:03.358491Z","time spent":"1.887668001s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:45:05.246218Z","caller":"traceutil/trace.go:171","msg":"trace[1363308982] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"1.887798054s","start":"2026-07-17T03:45:03.358415Z","end":"2026-07-17T03:45:05.246213Z","steps":["trace[1363308982] 'process raft request' (duration: 1.887427376s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:05.24624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:03.358396Z","time spent":"1.887831254s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:45:05.246311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.890771972s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"info","ts":"2026-07-17T03:45:05.246362Z","caller":"traceutil/trace.go:171","msg":"trace[1471482360] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:365; }","duration":"1.890841873s","start":"2026-07-17T03:45:03.35551Z","end":"2026-07-17T03:45:05.246352Z","steps":["trace[1471482360] 'agreement among raft nodes before linearized reading' (duration: 1.890760081s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:05.246397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:03.355502Z","time spent":"1.890887994s","remote":"127.0.0.1:40778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5350,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-07-17T03:45:05.579031Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.559171749s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T03:45:05.583445Z","caller":"traceutil/trace.go:171","msg":"trace[1639356554] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"1.661455467s","start":"2026-07-17T03:45:03.921963Z","end":"2026-07-17T03:45:05.583419Z","steps":["trace[1639356554] 'process raft request' (duration: 1.657297151s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:05.583594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:03.921945Z","time spent":"1.661573389s","remote":"127.0.0.1:40632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:45:05.585607Z","caller":"traceutil/trace.go:171","msg":"trace[1129702743] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:378; }","duration":"339.568442ms","start":"2026-07-17T03:45:05.246022Z","end":"2026-07-17T03:45:05.585591Z","steps":["trace[1129702743] 'read index received' (duration: 333.260139ms)","trace[1129702743] 'applied index is now lower than readState.Index' (duration: 6.307183ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:45:05.585679Z","caller":"traceutil/trace.go:171","msg":"trace[553322761] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"331.644012ms","start":"2026-07-17T03:45:05.254025Z","end":"2026-07-17T03:45:05.585669Z","steps":["trace[553322761] 'process raft request' (duration: 331.533259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:05.585757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:05.254011Z","time spent":"331.685394ms","remote":"127.0.0.1:40778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5311,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:45:05.585914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.087788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:45:05.585951Z","caller":"traceutil/trace.go:171","msg":"trace[483240399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:368; }","duration":"367.15212ms","start":"2026-07-17T03:45:05.218787Z","end":"2026-07-17T03:45:05.585939Z","steps":["trace[483240399] 'agreement among raft nodes before linearized reading' (duration: 367.104849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:05.585976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:05.218774Z","time spent":"367.196311ms","remote":"127.0.0.1:40608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T03:45:05.585895Z","caller":"traceutil/trace.go:171","msg":"trace[178091118] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"503.829739ms","start":"2026-07-17T03:45:05.082044Z","end":"2026-07-17T03:45:05.585874Z","steps":["trace[178091118] 'process raft request' (duration: 503.420228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:05.586219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:05.082019Z","time spent":"504.106345ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:45:05.586223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.210640373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T03:45:05.586265Z","caller":"traceutil/trace.go:171","msg":"trace[1047099347] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:368; }","duration":"1.210729706s","start":"2026-07-17T03:45:04.375524Z","end":"2026-07-17T03:45:05.586254Z","steps":["trace[1047099347] 'agreement among raft nodes before linearized reading' (duration: 1.210657274s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:05.586298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:04.375486Z","time spent":"1.210804168s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T03:45:08.754142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.643749ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-07-17T03:45:13.750568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.27762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T03:45:13.750752Z","caller":"traceutil/trace.go:171","msg":"trace[148804330] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:390; }","duration":"118.538455ms","start":"2026-07-17T03:45:13.632187Z","end":"2026-07-17T03:45:13.750725Z","steps":["trace[148804330] 'range keys from in-memory index tree' (duration: 118.097816ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:45:33.218678Z","caller":"traceutil/trace.go:171","msg":"trace[451621797] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"127.342594ms","start":"2026-07-17T03:45:33.091308Z","end":"2026-07-17T03:45:33.21865Z","steps":["trace[451621797] 'process raft request' (duration: 126.941174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:36.076522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.763778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T03:45:36.076635Z","caller":"traceutil/trace.go:171","msg":"trace[1028963283] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:593; }","duration":"156.954972ms","start":"2026-07-17T03:45:35.91966Z","end":"2026-07-17T03:45:36.076615Z","steps":["trace[1028963283] 'range keys from in-memory index tree' (duration: 156.502442ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:45:36.220284Z","caller":"traceutil/trace.go:171","msg":"trace[1472181001] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"102.105672ms","start":"2026-07-17T03:45:36.118162Z","end":"2026-07-17T03:45:36.220268Z","steps":["trace[1472181001] 'process raft request' (duration: 101.928258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:37.208222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.808639ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:45:37.208756Z","caller":"traceutil/trace.go:171","msg":"trace[1514152935] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:597; }","duration":"597.336091ms","start":"2026-07-17T03:45:36.611384Z","end":"2026-07-17T03:45:37.20872Z","steps":["trace[1514152935] 'range keys from in-memory index tree' (duration: 596.790069ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:45:37.211641Z","caller":"traceutil/trace.go:171","msg":"trace[1954152270] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"514.765924ms","start":"2026-07-17T03:45:36.696825Z","end":"2026-07-17T03:45:37.211591Z","steps":["trace[1954152270] 'process raft request' (duration: 509.129338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:37.212062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:36.696801Z","time spent":"515.188052ms","remote":"127.0.0.1:40686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:45:43.381097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.632602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T03:45:43.381203Z","caller":"traceutil/trace.go:171","msg":"trace[160071700] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"419.671537ms","start":"2026-07-17T03:45:42.961513Z","end":"2026-07-17T03:45:43.381184Z","steps":["trace[160071700] 'process raft request' (duration: 139.868352ms)","trace[160071700] 'compare' (duration: 279.364526ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:45:43.381266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:42.961495Z","time spent":"419.739799ms","remote":"127.0.0.1:40778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:45:43.40805Z","caller":"traceutil/trace.go:171","msg":"trace[2058265125] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"425.073917ms","start":"2026-07-17T03:45:42.982958Z","end":"2026-07-17T03:45:43.408032Z","steps":["trace[2058265125] 'process raft request' (duration: 424.869203ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:45:43.40803Z","caller":"traceutil/trace.go:171","msg":"trace[50812455] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"199.650132ms","start":"2026-07-17T03:45:43.208355Z","end":"2026-07-17T03:45:43.408006Z","steps":["trace[50812455] 'read index received' (duration: 199.473968ms)","trace[50812455] 'applied index is now lower than readState.Index' (duration: 174.824µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:45:43.40818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.806605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T03:45:43.408192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:42.982938Z","time spent":"425.16866ms","remote":"127.0.0.1:40632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:45:43.408237Z","caller":"traceutil/trace.go:171","msg":"trace[1954070082] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"199.926148ms","start":"2026-07-17T03:45:43.208299Z","end":"2026-07-17T03:45:43.408225Z","steps":["trace[1954070082] 'agreement among raft nodes before linearized reading' (duration: 199.793665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:43.944996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.8463ms","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-07-17T03:45:43.945019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.046551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T03:45:43.94506Z","caller":"traceutil/trace.go:171","msg":"trace[312146168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"392.937221ms","start":"2026-07-17T03:45:43.552109Z","end":"2026-07-17T03:45:43.945046Z","steps":["trace[312146168] 'range keys from in-memory index tree' (duration: 392.701455ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:45:43.945075Z","caller":"traceutil/trace.go:171","msg":"trace[1063075305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:622; }","duration":"392.114283ms","start":"2026-07-17T03:45:43.552949Z","end":"2026-07-17T03:45:43.945063Z","steps":["trace[1063075305] 'range keys from in-memory index tree' (duration: 391.920979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:43.945091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:43.552096Z","time spent":"392.983043ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T03:45:43.9451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:43.552894Z","time spent":"392.201964ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T03:45:43.945185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.501708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-17T03:45:43.945197Z","caller":"traceutil/trace.go:171","msg":"trace[1321661266] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:622; }","duration":"431.553789ms","start":"2026-07-17T03:45:43.513639Z","end":"2026-07-17T03:45:43.945193Z","steps":["trace[1321661266] 'range keys from in-memory index tree' (duration: 431.408207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:43.94525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:43.513619Z","time spent":"431.626082ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T03:45:46.496181Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900641097248082,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T03:45:46.922396Z","caller":"traceutil/trace.go:171","msg":"trace[846069330] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"933.362903ms","start":"2026-07-17T03:45:45.989003Z","end":"2026-07-17T03:45:46.922366Z","steps":["trace[846069330] 'process raft request' (duration: 932.985775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:46.922636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:45.988982Z","time spent":"933.510807ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:45:47.999835Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.077961087s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-17T03:45:48.255449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.326942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T03:45:48.255545Z","caller":"traceutil/trace.go:171","msg":"trace[1092446289] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"2.259803659s","start":"2026-07-17T03:45:45.995726Z","end":"2026-07-17T03:45:48.25553Z","steps":["trace[1092446289] 'read index received' (duration: 926.665874ms)","trace[1092446289] 'applied index is now lower than readState.Index' (duration: 1.333136135s)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:45:48.255631Z","caller":"traceutil/trace.go:171","msg":"trace[97720824] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"1.799548761s","start":"2026-07-17T03:45:46.456072Z","end":"2026-07-17T03:45:48.25562Z","steps":["trace[97720824] 'process raft request' (duration: 1.543934093s)","trace[97720824] 'compare' (duration: 255.206469ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:45:48.255691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:46.456046Z","time spent":"1.799615862s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:45:48.256045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048321237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:45:48.256172Z","caller":"traceutil/trace.go:171","msg":"trace[918211383] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"1.048492421s","start":"2026-07-17T03:45:47.20765Z","end":"2026-07-17T03:45:48.256142Z","steps":["trace[918211383] 'agreement among raft nodes before linearized reading' (duration: 1.048279917s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:48.25616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.260423352s","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-07-17T03:45:48.256238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:47.20763Z","time spent":"1.048592873s","remote":"127.0.0.1:40608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T03:45:48.256237Z","caller":"traceutil/trace.go:171","msg":"trace[2045905115] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"2.260536045s","start":"2026-07-17T03:45:45.995682Z","end":"2026-07-17T03:45:48.256218Z","steps":["trace[2045905115] 'agreement among raft nodes before linearized reading' (duration: 2.260346021s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:48.256101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.260180778s","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-07-17T03:45:48.256318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:45.995666Z","time spent":"2.260641679s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-17T03:45:48.256354Z","caller":"traceutil/trace.go:171","msg":"trace[1313687374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:628; }","duration":"2.260450454s","start":"2026-07-17T03:45:45.995866Z","end":"2026-07-17T03:45:48.256317Z","steps":["trace[1313687374] 'agreement among raft nodes before linearized reading' (duration: 2.259944352s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:48.256444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.092452756s","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-07-17T03:45:48.25646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:45.995862Z","time spent":"2.260565387s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-17T03:45:48.256496Z","caller":"traceutil/trace.go:171","msg":"trace[1589298686] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"2.092729743s","start":"2026-07-17T03:45:46.163748Z","end":"2026-07-17T03:45:48.256477Z","steps":["trace[1589298686] 'agreement among raft nodes before linearized reading' (duration: 2.092385504s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:48.256535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:46.163728Z","time spent":"2.092797325s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T03:45:50.382733Z","caller":"traceutil/trace.go:171","msg":"trace[657076260] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:686; }","duration":"175.002473ms","start":"2026-07-17T03:45:50.20771Z","end":"2026-07-17T03:45:50.382713Z","steps":["trace[657076260] 'read index received' (duration: 174.882221ms)","trace[657076260] 'applied index is now lower than readState.Index' (duration: 119.102µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:45:50.382836Z","caller":"traceutil/trace.go:171","msg":"trace[652316709] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"193.214098ms","start":"2026-07-17T03:45:50.189612Z","end":"2026-07-17T03:45:50.382826Z","steps":["trace[652316709] 'process raft request' (duration: 192.989553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:50.383341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.612577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:45:50.383425Z","caller":"traceutil/trace.go:171","msg":"trace[240390309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"175.74002ms","start":"2026-07-17T03:45:50.207673Z","end":"2026-07-17T03:45:50.383413Z","steps":["trace[240390309] 'agreement among raft nodes before linearized reading' (duration: 175.603557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:53.501359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.020262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T03:45:53.501542Z","caller":"traceutil/trace.go:171","msg":"trace[1518549432] linearizableReadLoop","detail":"{readStateIndex:729; appliedIndex:728; }","duration":"294.889899ms","start":"2026-07-17T03:45:53.206634Z","end":"2026-07-17T03:45:53.501524Z","steps":["trace[1518549432] 'read index received' (duration: 37.721µs)","trace[1518549432] 'applied index is now lower than readState.Index' (duration: 294.850208ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:45:53.501622Z","caller":"traceutil/trace.go:171","msg":"trace[1280350168] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"390.841435ms","start":"2026-07-17T03:45:53.110724Z","end":"2026-07-17T03:45:53.501566Z","steps":["trace[1280350168] 'process raft request' (duration: 82.548347ms)","trace[1280350168] 'compare' (duration: 307.894349ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:45:53.501749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:53.110705Z","time spent":"390.985599ms","remote":"127.0.0.1:40682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T03:45:53.501812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.140435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:45:53.50188Z","caller":"traceutil/trace.go:171","msg":"trace[882713546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:701; }","duration":"295.273918ms","start":"2026-07-17T03:45:53.206591Z","end":"2026-07-17T03:45:53.501865Z","steps":["trace[882713546] 'agreement among raft nodes before linearized reading' (duration: 295.044613ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:45:53.518473Z","caller":"traceutil/trace.go:171","msg":"trace[664524549] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"298.873668ms","start":"2026-07-17T03:45:53.21957Z","end":"2026-07-17T03:45:53.518444Z","steps":["trace[664524549] 'process raft request' (duration: 298.673874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:53.518563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.659737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-07-17T03:45:53.518776Z","caller":"traceutil/trace.go:171","msg":"trace[903476251] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:702; }","duration":"130.913252ms","start":"2026-07-17T03:45:53.387844Z","end":"2026-07-17T03:45:53.518757Z","steps":["trace[903476251] 'agreement among raft nodes before linearized reading' (duration: 130.587785ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:45:53.833727Z","caller":"traceutil/trace.go:171","msg":"trace[329439654] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"106.446719ms","start":"2026-07-17T03:45:53.727254Z","end":"2026-07-17T03:45:53.8337Z","steps":["trace[329439654] 'process raft request' (duration: 63.622736ms)","trace[329439654] 'compare' (duration: 42.660649ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:45:53.844149Z","caller":"traceutil/trace.go:171","msg":"trace[60581556] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"115.341046ms","start":"2026-07-17T03:45:53.728741Z","end":"2026-07-17T03:45:53.844082Z","steps":["trace[60581556] 'process raft request' (duration: 115.136722ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:45:54.021516Z","caller":"traceutil/trace.go:171","msg":"trace[928892474] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"111.143354ms","start":"2026-07-17T03:45:53.910334Z","end":"2026-07-17T03:45:54.021478Z","steps":["trace[928892474] 'process raft request' (duration: 65.081148ms)","trace[928892474] 'compare' (duration: 45.785229ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:46:01.186163Z","caller":"traceutil/trace.go:171","msg":"trace[1049345483] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"138.4058ms","start":"2026-07-17T03:46:01.047733Z","end":"2026-07-17T03:46:01.186139Z","steps":["trace[1049345483] 'read index received' (duration: 138.272687ms)","trace[1049345483] 'applied index is now lower than readState.Index' (duration: 132.223µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:46:01.186343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.597654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-vr9kb\" ","response":"range_response_count:1 size:5046"} {"level":"info","ts":"2026-07-17T03:46:01.186392Z","caller":"traceutil/trace.go:171","msg":"trace[408143325] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-vr9kb; range_end:; response_count:1; response_revision:765; }","duration":"138.677096ms","start":"2026-07-17T03:46:01.047694Z","end":"2026-07-17T03:46:01.186371Z","steps":["trace[408143325] 'agreement among raft nodes before linearized reading' (duration: 138.543513ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:46:01.186425Z","caller":"traceutil/trace.go:171","msg":"trace[1963066867] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"191.747357ms","start":"2026-07-17T03:46:00.994653Z","end":"2026-07-17T03:46:01.1864Z","steps":["trace[1963066867] 'process raft request' (duration: 191.352148ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:46:01.298096Z","caller":"traceutil/trace.go:171","msg":"trace[1944015517] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"102.467229ms","start":"2026-07-17T03:46:01.195608Z","end":"2026-07-17T03:46:01.298075Z","steps":["trace[1944015517] 'process raft request' (duration: 102.303826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:04.559557Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900641097248476,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T03:46:04.885564Z","caller":"traceutil/trace.go:171","msg":"trace[637026245] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"826.638722ms","start":"2026-07-17T03:46:04.058872Z","end":"2026-07-17T03:46:04.885511Z","steps":["trace[637026245] 'read index received' (duration: 826.484648ms)","trace[637026245] 'applied index is now lower than readState.Index' (duration: 152.514µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:46:04.885698Z","caller":"traceutil/trace.go:171","msg":"trace[1396938169] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"985.037358ms","start":"2026-07-17T03:46:03.900556Z","end":"2026-07-17T03:46:04.885594Z","steps":["trace[1396938169] 'process raft request' (duration: 984.801672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:04.88582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.923418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-l48d6\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-07-17T03:46:04.885863Z","caller":"traceutil/trace.go:171","msg":"trace[298922653] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-l48d6; range_end:; response_count:1; response_revision:786; }","duration":"827.01615ms","start":"2026-07-17T03:46:04.058836Z","end":"2026-07-17T03:46:04.885852Z","steps":["trace[298922653] 'agreement among raft nodes before linearized reading' (duration: 826.850917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:04.885913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:04.058821Z","time spent":"827.079511ms","remote":"127.0.0.1:40778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-l48d6\" "} {"level":"warn","ts":"2026-07-17T03:46:04.885984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:03.900537Z","time spent":"985.257372ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:46:04.88602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.731678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T03:46:04.886124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.888767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-17T03:46:04.886184Z","caller":"traceutil/trace.go:171","msg":"trace[170190109] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:786; }","duration":"439.996149ms","start":"2026-07-17T03:46:04.446175Z","end":"2026-07-17T03:46:04.886171Z","steps":["trace[170190109] 'agreement among raft nodes before linearized reading' (duration: 439.843546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:04.886223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:04.446157Z","time spent":"440.058701ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-17T03:46:04.886127Z","caller":"traceutil/trace.go:171","msg":"trace[692649776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:786; }","duration":"677.873582ms","start":"2026-07-17T03:46:04.20823Z","end":"2026-07-17T03:46:04.886104Z","steps":["trace[692649776] 'agreement among raft nodes before linearized reading' (duration: 677.708768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:04.886271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.729193ms","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-07-17T03:46:04.886283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:04.208212Z","time spent":"678.061096ms","remote":"127.0.0.1:40608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T03:46:04.886312Z","caller":"traceutil/trace.go:171","msg":"trace[995250973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:786; }","duration":"443.809355ms","start":"2026-07-17T03:46:04.442493Z","end":"2026-07-17T03:46:04.886302Z","steps":["trace[995250973] 'agreement among raft nodes before linearized reading' (duration: 443.665582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:04.886301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.317932ms","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-07-17T03:46:04.886369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:04.442473Z","time spent":"443.887686ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T03:46:04.886387Z","caller":"traceutil/trace.go:171","msg":"trace[366002056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:786; }","duration":"438.437225ms","start":"2026-07-17T03:46:04.447926Z","end":"2026-07-17T03:46:04.886364Z","steps":["trace[366002056] 'agreement among raft nodes before linearized reading' (duration: 438.1784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:04.886431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:04.447916Z","time spent":"438.506527ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-17T03:46:05.122314Z","caller":"traceutil/trace.go:171","msg":"trace[736793664] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"230.013438ms","start":"2026-07-17T03:46:04.892273Z","end":"2026-07-17T03:46:05.122287Z","steps":["trace[736793664] 'process raft request' (duration: 227.643405ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:46:05.123869Z","caller":"traceutil/trace.go:171","msg":"trace[224208790] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"231.572773ms","start":"2026-07-17T03:46:04.892274Z","end":"2026-07-17T03:46:05.123847Z","steps":["trace[224208790] 'process raft request' (duration: 231.370448ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:46:05.124151Z","caller":"traceutil/trace.go:171","msg":"trace[1628509915] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"231.303997ms","start":"2026-07-17T03:46:04.892827Z","end":"2026-07-17T03:46:05.124131Z","steps":["trace[1628509915] 'process raft request' (duration: 230.933959ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:46:05.124196Z","caller":"traceutil/trace.go:171","msg":"trace[1552384415] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"229.951026ms","start":"2026-07-17T03:46:04.894225Z","end":"2026-07-17T03:46:05.124176Z","steps":["trace[1552384415] 'process raft request' (duration: 229.823953ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:47:05.578501Z","caller":"traceutil/trace.go:171","msg":"trace[2022381838] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"314.761605ms","start":"2026-07-17T03:47:05.263704Z","end":"2026-07-17T03:47:05.578466Z","steps":["trace[2022381838] 'process raft request' (duration: 314.53401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:05.578698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:05.263679Z","time spent":"314.925048ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:47:05.84946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.789885ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T03:47:05.849658Z","caller":"traceutil/trace.go:171","msg":"trace[1174200674] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1132; }","duration":"258.312827ms","start":"2026-07-17T03:47:05.591323Z","end":"2026-07-17T03:47:05.849636Z","steps":["trace[1174200674] 'read index received' (duration: 119.270546ms)","trace[1174200674] 'applied index is now lower than readState.Index' (duration: 139.041071ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:47:05.849671Z","caller":"traceutil/trace.go:171","msg":"trace[2028863089] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"266.971871ms","start":"2026-07-17T03:47:05.582665Z","end":"2026-07-17T03:47:05.849637Z","steps":["trace[2028863089] 'process raft request' (duration: 127.91137ms)","trace[2028863089] 'compare' (duration: 138.654032ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:47:05.84996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.615464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T03:47:05.849993Z","caller":"traceutil/trace.go:171","msg":"trace[1370587837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1089; }","duration":"258.698116ms","start":"2026-07-17T03:47:05.591284Z","end":"2026-07-17T03:47:05.849982Z","steps":["trace[1370587837] 'agreement among raft nodes before linearized reading' (duration: 258.546803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:05.850084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.032204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T03:47:05.850119Z","caller":"traceutil/trace.go:171","msg":"trace[1772615578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1089; }","duration":"239.091716ms","start":"2026-07-17T03:47:05.611017Z","end":"2026-07-17T03:47:05.850109Z","steps":["trace[1772615578] 'agreement among raft nodes before linearized reading' (duration: 238.952233ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:47:05.850145Z","caller":"traceutil/trace.go:171","msg":"trace[79845800] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"267.273268ms","start":"2026-07-17T03:47:05.582861Z","end":"2026-07-17T03:47:05.850135Z","steps":["trace[79845800] 'process raft request' (duration: 266.720926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:06.235851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.925493ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T03:47:06.236065Z","caller":"traceutil/trace.go:171","msg":"trace[1756704348] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"381.217656ms","start":"2026-07-17T03:47:05.854828Z","end":"2026-07-17T03:47:06.236045Z","steps":["trace[1756704348] 'process raft request' (duration: 381.131925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:06.236137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:05.854813Z","time spent":"381.285488ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:47:06.236411Z","caller":"traceutil/trace.go:171","msg":"trace[37809990] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"382.146687ms","start":"2026-07-17T03:47:05.854245Z","end":"2026-07-17T03:47:06.236392Z","steps":["trace[37809990] 'process raft request' (duration: 109.58894ms)","trace[37809990] 'compare' (duration: 271.715918ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:47:06.236492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:05.854222Z","time spent":"382.230049ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:47:06.334792Z","caller":"traceutil/trace.go:171","msg":"trace[534861112] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1136; }","duration":"129.312282ms","start":"2026-07-17T03:47:06.205456Z","end":"2026-07-17T03:47:06.334769Z","steps":["trace[534861112] 'read index received' (duration: 129.157689ms)","trace[534861112] 'applied index is now lower than readState.Index' (duration: 153.533µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:47:06.334841Z","caller":"traceutil/trace.go:171","msg":"trace[1522753379] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"357.299899ms","start":"2026-07-17T03:47:05.977499Z","end":"2026-07-17T03:47:06.334799Z","steps":["trace[1522753379] 'process raft request' (duration: 357.129905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:06.334913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.440126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:47:06.334944Z","caller":"traceutil/trace.go:171","msg":"trace[1427982663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1092; }","duration":"129.527197ms","start":"2026-07-17T03:47:06.205408Z","end":"2026-07-17T03:47:06.334935Z","steps":["trace[1427982663] 'agreement among raft nodes before linearized reading' (duration: 129.452005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:06.334964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:05.977468Z","time spent":"357.421213ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:47:06.45081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.10844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:47:06.450904Z","caller":"traceutil/trace.go:171","msg":"trace[612676449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1092; }","duration":"114.268914ms","start":"2026-07-17T03:47:06.336617Z","end":"2026-07-17T03:47:06.450886Z","steps":["trace[612676449] 'range keys from in-memory index tree' (duration: 113.970237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:17.407373Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.031533703s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-17T03:47:17.461221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.948607ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:47:17.46133Z","caller":"traceutil/trace.go:171","msg":"trace[1053410012] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"1.148717563s","start":"2026-07-17T03:47:16.312575Z","end":"2026-07-17T03:47:17.461293Z","steps":["trace[1053410012] 'process raft request' (duration: 1.094962086s)","trace[1053410012] 'compare' (duration: 53.00094ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:47:17.461361Z","caller":"traceutil/trace.go:171","msg":"trace[954348701] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1123; }","duration":"850.102181ms","start":"2026-07-17T03:47:16.611238Z","end":"2026-07-17T03:47:17.46134Z","steps":["trace[954348701] 'range keys from in-memory index tree' (duration: 849.932527ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:47:17.461724Z","caller":"traceutil/trace.go:171","msg":"trace[1529359358] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"1.094871165s","start":"2026-07-17T03:47:16.36682Z","end":"2026-07-17T03:47:17.461691Z","steps":["trace[1529359358] 'process raft request' (duration: 1.094423365s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:17.461845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:16.366803Z","time spent":"1.094965876s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:47:17.461859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.5284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:47:17.461917Z","caller":"traceutil/trace.go:171","msg":"trace[1546903589] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1125; }","duration":"253.627692ms","start":"2026-07-17T03:47:17.208271Z","end":"2026-07-17T03:47:17.461899Z","steps":["trace[1546903589] 'agreement among raft nodes before linearized reading' (duration: 253.53048ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:47:17.461409Z","caller":"traceutil/trace.go:171","msg":"trace[1928422843] linearizableReadLoop","detail":"{readStateIndex:1172; appliedIndex:1170; }","duration":"253.06027ms","start":"2026-07-17T03:47:17.208326Z","end":"2026-07-17T03:47:17.461386Z","steps":["trace[1928422843] 'read index received' (duration: 199.223702ms)","trace[1928422843] 'applied index is now lower than readState.Index' (duration: 53.834988ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:47:17.462214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:16.312566Z","time spent":"1.148825145s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:47:18.048363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.121002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-17T03:47:18.048441Z","caller":"traceutil/trace.go:171","msg":"trace[1630400610] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1125; }","duration":"409.277866ms","start":"2026-07-17T03:47:17.639145Z","end":"2026-07-17T03:47:18.048423Z","steps":["trace[1630400610] 'range keys from in-memory index tree' (duration: 408.954279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:18.048483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:17.639127Z","time spent":"409.347688ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T03:47:18.048478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.79524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-17T03:47:18.048533Z","caller":"traceutil/trace.go:171","msg":"trace[2114075216] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1125; }","duration":"124.894652ms","start":"2026-07-17T03:47:17.923623Z","end":"2026-07-17T03:47:18.048518Z","steps":["trace[2114075216] 'range keys from in-memory index tree' (duration: 124.594016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:18.048766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.354329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T03:47:18.049066Z","caller":"traceutil/trace.go:171","msg":"trace[451626197] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1125; }","duration":"119.675607ms","start":"2026-07-17T03:47:17.929342Z","end":"2026-07-17T03:47:18.049018Z","steps":["trace[451626197] 'range keys from in-memory index tree' (duration: 119.066161ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:47:18.58896Z","caller":"traceutil/trace.go:171","msg":"trace[439145252] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"536.505131ms","start":"2026-07-17T03:47:18.052429Z","end":"2026-07-17T03:47:18.588934Z","steps":["trace[439145252] 'process raft request' (duration: 536.308787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:18.589179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:18.0524Z","time spent":"536.635094ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:47:18.670396Z","caller":"traceutil/trace.go:171","msg":"trace[1334532373] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"617.604391ms","start":"2026-07-17T03:47:18.052773Z","end":"2026-07-17T03:47:18.670378Z","steps":["trace[1334532373] 'process raft request' (duration: 614.375069ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:47:18.670479Z","caller":"traceutil/trace.go:171","msg":"trace[1936570009] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"617.52677ms","start":"2026-07-17T03:47:18.052927Z","end":"2026-07-17T03:47:18.670453Z","steps":["trace[1936570009] 'process raft request' (duration: 617.382507ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:18.67052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:18.052754Z","time spent":"617.690004ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:47:18.670639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:18.052914Z","time spent":"617.645403ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:47:18.672623Z","caller":"traceutil/trace.go:171","msg":"trace[1783122751] linearizableReadLoop","detail":"{readStateIndex:1176; appliedIndex:1172; }","duration":"291.115255ms","start":"2026-07-17T03:47:18.381497Z","end":"2026-07-17T03:47:18.672612Z","steps":["trace[1783122751] 'read index received' (duration: 207.220131ms)","trace[1783122751] 'applied index is now lower than readState.Index' (duration: 83.894154ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:47:18.672755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.261808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T03:47:18.672811Z","caller":"traceutil/trace.go:171","msg":"trace[153946360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1128; }","duration":"291.36108ms","start":"2026-07-17T03:47:18.381439Z","end":"2026-07-17T03:47:18.6728Z","steps":["trace[153946360] 'agreement among raft nodes before linearized reading' (duration: 291.218167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:32.017388Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900641097249359,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T03:47:32.5177Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900641097249359,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T03:47:32.629955Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.122994971s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T03:47:32.630424Z","caller":"traceutil/trace.go:171","msg":"trace[1708186481] linearizableReadLoop","detail":"{readStateIndex:1220; appliedIndex:1219; }","duration":"1.113057775s","start":"2026-07-17T03:47:31.517341Z","end":"2026-07-17T03:47:32.630398Z","steps":["trace[1708186481] 'read index received' (duration: 1.112737698s)","trace[1708186481] 'applied index is now lower than readState.Index' (duration: 318.367µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:47:32.630528Z","caller":"traceutil/trace.go:171","msg":"trace[1385360155] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"1.123619444s","start":"2026-07-17T03:47:31.506881Z","end":"2026-07-17T03:47:32.630501Z","steps":["trace[1385360155] 'process raft request' (duration: 1.123362458s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:32.63067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113311861s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-17T03:47:32.630672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.821635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:47:32.630704Z","caller":"traceutil/trace.go:171","msg":"trace[1003573539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1170; }","duration":"1.113404623s","start":"2026-07-17T03:47:31.517289Z","end":"2026-07-17T03:47:32.630694Z","steps":["trace[1003573539] 'agreement among raft nodes before linearized reading' (duration: 1.113246239s)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:47:32.630733Z","caller":"traceutil/trace.go:171","msg":"trace[1118155109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1170; }","duration":"419.897007ms","start":"2026-07-17T03:47:32.210821Z","end":"2026-07-17T03:47:32.630718Z","steps":["trace[1118155109] 'agreement among raft nodes before linearized reading' (duration: 419.779264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:32.630745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:31.517267Z","time spent":"1.113468855s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T03:47:32.630764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:32.210776Z","time spent":"419.983139ms","remote":"127.0.0.1:40608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T03:47:32.630749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:31.506857Z","time spent":"1.123746548s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:47:33.054786Z","caller":"traceutil/trace.go:171","msg":"trace[1158996663] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"408.120208ms","start":"2026-07-17T03:47:32.646641Z","end":"2026-07-17T03:47:33.054761Z","steps":["trace[1158996663] 'process raft request' (duration: 407.979665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:33.054971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:32.646631Z","time spent":"408.243981ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:47:33.054945Z","caller":"traceutil/trace.go:171","msg":"trace[901240144] linearizableReadLoop","detail":"{readStateIndex:1221; appliedIndex:1221; }","duration":"377.800629ms","start":"2026-07-17T03:47:32.677121Z","end":"2026-07-17T03:47:33.054921Z","steps":["trace[901240144] 'read index received' (duration: 377.792789ms)","trace[901240144] 'applied index is now lower than readState.Index' (duration: 5.74µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:47:33.055306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.870093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-17T03:47:33.05533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.293378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T03:47:33.055381Z","caller":"traceutil/trace.go:171","msg":"trace[502631696] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1171; }","duration":"319.37359ms","start":"2026-07-17T03:47:32.735995Z","end":"2026-07-17T03:47:33.055369Z","steps":["trace[502631696] 'agreement among raft nodes before linearized reading' (duration: 319.231687ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:47:33.055381Z","caller":"traceutil/trace.go:171","msg":"trace[1963218486] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1171; }","duration":"320.992636ms","start":"2026-07-17T03:47:32.73437Z","end":"2026-07-17T03:47:33.055363Z","steps":["trace[1963218486] 'agreement among raft nodes before linearized reading' (duration: 320.74808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:33.055424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:32.735987Z","time spent":"319.429441ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T03:47:33.055435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:32.734352Z","time spent":"321.074037ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T03:47:33.055353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.432692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T03:47:33.055649Z","caller":"traceutil/trace.go:171","msg":"trace[1399920060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1171; }","duration":"305.75108ms","start":"2026-07-17T03:47:32.749876Z","end":"2026-07-17T03:47:33.055627Z","steps":["trace[1399920060] 'agreement among raft nodes before linearized reading' (duration: 305.333309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:33.05572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.603029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-17T03:47:33.055755Z","caller":"traceutil/trace.go:171","msg":"trace[2091099903] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1171; }","duration":"378.669379ms","start":"2026-07-17T03:47:32.677076Z","end":"2026-07-17T03:47:33.055745Z","steps":["trace[2091099903] 'agreement among raft nodes before linearized reading' (duration: 377.997373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:33.0558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:32.677059Z","time spent":"378.730781ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T03:47:33.055858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:32.749865Z","time spent":"305.872913ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-17T03:47:34.15775Z","caller":"traceutil/trace.go:171","msg":"trace[1809502034] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"117.365451ms","start":"2026-07-17T03:47:34.04036Z","end":"2026-07-17T03:47:34.157725Z","steps":["trace[1809502034] 'process raft request' (duration: 117.202947ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:47:34.768633Z","caller":"traceutil/trace.go:171","msg":"trace[1412970302] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"128.379753ms","start":"2026-07-17T03:47:34.640225Z","end":"2026-07-17T03:47:34.768605Z","steps":["trace[1412970302] 'process raft request' (duration: 128.164208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:35.655889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.599748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T03:47:35.655968Z","caller":"traceutil/trace.go:171","msg":"trace[803813480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1178; }","duration":"534.700381ms","start":"2026-07-17T03:47:35.121249Z","end":"2026-07-17T03:47:35.65595Z","steps":["trace[803813480] 'range keys from in-memory index tree' (duration: 534.451944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:35.656058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:35.121245Z","time spent":"534.801923ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T03:47:35.656251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.819949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T03:47:35.656392Z","caller":"traceutil/trace.go:171","msg":"trace[160975938] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1178; }","duration":"450.023223ms","start":"2026-07-17T03:47:35.206333Z","end":"2026-07-17T03:47:35.656356Z","steps":["trace[160975938] 'count revisions from in-memory index tree' (duration: 449.724466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:35.656458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:35.20631Z","time spent":"450.132766ms","remote":"127.0.0.1:44832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-07-17T03:47:35.656664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.391186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T03:47:35.65675Z","caller":"traceutil/trace.go:171","msg":"trace[2078848705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1178; }","duration":"535.511588ms","start":"2026-07-17T03:47:35.121217Z","end":"2026-07-17T03:47:35.656728Z","steps":["trace[2078848705] 'range keys from in-memory index tree' (duration: 535.188492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:35.656799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:35.121204Z","time spent":"535.58698ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T03:47:35.657247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.55727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-17T03:47:35.656598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.24709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-17T03:47:35.657319Z","caller":"traceutil/trace.go:171","msg":"trace[459288494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1178; }","duration":"597.651993ms","start":"2026-07-17T03:47:35.059652Z","end":"2026-07-17T03:47:35.657304Z","steps":["trace[459288494] 'range keys from in-memory index tree' (duration: 596.337553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:35.656636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.369015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-17T03:47:35.657383Z","caller":"traceutil/trace.go:171","msg":"trace[1821751838] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1178; }","duration":"535.031577ms","start":"2026-07-17T03:47:35.122337Z","end":"2026-07-17T03:47:35.657368Z","steps":["trace[1821751838] 'range keys from in-memory index tree' (duration: 534.106657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:35.657496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:35.122304Z","time spent":"535.171651ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-17T03:47:35.657396Z","caller":"traceutil/trace.go:171","msg":"trace[1222651014] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1178; }","duration":"536.179343ms","start":"2026-07-17T03:47:35.121195Z","end":"2026-07-17T03:47:35.657375Z","steps":["trace[1222651014] 'range keys from in-memory index tree' (duration: 534.924356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:35.658033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:35.121174Z","time spent":"536.744456ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T03:47:35.657782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:35.059636Z","time spent":"598.132953ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T03:47:36.874968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.18733ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:47:36.875084Z","caller":"traceutil/trace.go:171","msg":"trace[1402009021] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1179; }","duration":"263.320603ms","start":"2026-07-17T03:47:36.611746Z","end":"2026-07-17T03:47:36.875067Z","steps":["trace[1402009021] 'range keys from in-memory index tree' (duration: 263.17191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:36.875391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.876504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T03:47:36.875772Z","caller":"traceutil/trace.go:171","msg":"trace[2110430940] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"100.437186ms","start":"2026-07-17T03:47:36.775315Z","end":"2026-07-17T03:47:36.875752Z","steps":["trace[2110430940] 'process raft request' (duration: 100.394605ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:47:36.875789Z","caller":"traceutil/trace.go:171","msg":"trace[167209271] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"1.213721635s","start":"2026-07-17T03:47:35.662036Z","end":"2026-07-17T03:47:36.875757Z","steps":["trace[167209271] 'process raft request' (duration: 678.396361ms)","trace[167209271] 'compare' (duration: 534.705601ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:47:36.876008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:35.662019Z","time spent":"1.213840738s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:47:36.87617Z","caller":"traceutil/trace.go:171","msg":"trace[177723529] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"1.214046662s","start":"2026-07-17T03:47:35.662111Z","end":"2026-07-17T03:47:36.876158Z","steps":["trace[177723529] 'process raft request' (duration: 1.213528451s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:36.876305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:35.662108Z","time spent":"1.214095793s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:47:36.876317Z","caller":"traceutil/trace.go:171","msg":"trace[1181260151] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"1.213982212s","start":"2026-07-17T03:47:35.662319Z","end":"2026-07-17T03:47:36.876301Z","steps":["trace[1181260151] 'process raft request' (duration: 1.213354018s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:36.876435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:35.662314Z","time spent":"1.214038343s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:47:36.876458Z","caller":"traceutil/trace.go:171","msg":"trace[1154517985] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"1.21436235s","start":"2026-07-17T03:47:35.662084Z","end":"2026-07-17T03:47:36.876446Z","steps":["trace[1154517985] 'process raft request' (duration: 1.2134708s)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:47:36.876471Z","caller":"traceutil/trace.go:171","msg":"trace[1098605628] linearizableReadLoop","detail":"{readStateIndex:1234; appliedIndex:1230; }","duration":"742.290205ms","start":"2026-07-17T03:47:36.134154Z","end":"2026-07-17T03:47:36.876444Z","steps":["trace[1098605628] 'read index received' (duration: 206.300426ms)","trace[1098605628] 'applied index is now lower than readState.Index' (duration: 535.986109ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:47:36.87664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.054651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:47:36.876735Z","caller":"traceutil/trace.go:171","msg":"trace[787926646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1184; }","duration":"663.186195ms","start":"2026-07-17T03:47:36.213537Z","end":"2026-07-17T03:47:36.876723Z","steps":["trace[787926646] 'agreement among raft nodes before linearized reading' (duration: 663.053171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:36.876741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:35.66208Z","time spent":"1.214433671s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:47:36.876798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:36.213523Z","time spent":"663.269077ms","remote":"127.0.0.1:40608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T03:47:36.877016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.862699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:47:36.877066Z","caller":"traceutil/trace.go:171","msg":"trace[1181098429] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1184; }","duration":"742.956432ms","start":"2026-07-17T03:47:36.134097Z","end":"2026-07-17T03:47:36.877054Z","steps":["trace[1181098429] 'agreement among raft nodes before linearized reading' (duration: 742.87205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:36.877106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:36.134072Z","time spent":"743.024672ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-07-17T03:47:39.554541Z","caller":"traceutil/trace.go:171","msg":"trace[1218489642] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"671.493544ms","start":"2026-07-17T03:47:38.88302Z","end":"2026-07-17T03:47:39.554514Z","steps":["trace[1218489642] 'process raft request' (duration: 671.34773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:39.554695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:38.883007Z","time spent":"671.609076ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:47:40.065076Z","caller":"traceutil/trace.go:171","msg":"trace[2078601730] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"1.17966284s","start":"2026-07-17T03:47:38.885386Z","end":"2026-07-17T03:47:40.065048Z","steps":["trace[2078601730] 'process raft request' (duration: 1.179449166s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:40.065237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:38.885373Z","time spent":"1.179784853s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:47:40.065334Z","caller":"traceutil/trace.go:171","msg":"trace[1664584937] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"1.179844414s","start":"2026-07-17T03:47:38.885465Z","end":"2026-07-17T03:47:40.06531Z","steps":["trace[1664584937] 'process raft request' (duration: 1.179543468s)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:47:40.065603Z","caller":"traceutil/trace.go:171","msg":"trace[950065896] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"1.179980518s","start":"2026-07-17T03:47:38.885609Z","end":"2026-07-17T03:47:40.06559Z","steps":["trace[950065896] 'process raft request' (duration: 1.17963368s)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:47:40.065612Z","caller":"traceutil/trace.go:171","msg":"trace[1090537967] linearizableReadLoop","detail":"{readStateIndex:1242; appliedIndex:1238; }","duration":"857.676322ms","start":"2026-07-17T03:47:39.20791Z","end":"2026-07-17T03:47:40.065586Z","steps":["trace[1090537967] 'read index received' (duration: 346.564098ms)","trace[1090537967] 'applied index is now lower than readState.Index' (duration: 511.110783ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:47:40.065666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:38.885583Z","time spent":"1.180049009s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:47:40.065719Z","caller":"traceutil/trace.go:171","msg":"trace[1655250687] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"1.179230181s","start":"2026-07-17T03:47:38.886452Z","end":"2026-07-17T03:47:40.065683Z","steps":["trace[1655250687] 'process raft request' (duration: 1.179047417s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:40.065766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.837945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:47:40.065803Z","caller":"traceutil/trace.go:171","msg":"trace[1796111713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1190; }","duration":"857.919587ms","start":"2026-07-17T03:47:39.207872Z","end":"2026-07-17T03:47:40.065792Z","steps":["trace[1796111713] 'agreement among raft nodes before linearized reading' (duration: 857.845255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:40.065834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:39.207849Z","time spent":"857.977748ms","remote":"127.0.0.1:40608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T03:47:40.065872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:38.886442Z","time spent":"1.179343863s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:47:40.065882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.08374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T03:47:40.065932Z","caller":"traceutil/trace.go:171","msg":"trace[514411682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1190; }","duration":"343.178372ms","start":"2026-07-17T03:47:39.72274Z","end":"2026-07-17T03:47:40.065919Z","steps":["trace[514411682] 'agreement among raft nodes before linearized reading' (duration: 343.016008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:40.066004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.78007ms","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":"warn","ts":"2026-07-17T03:47:40.06603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:39.722717Z","time spent":"343.301934ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-17T03:47:40.066056Z","caller":"traceutil/trace.go:171","msg":"trace[2069630704] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1190; }","duration":"247.873971ms","start":"2026-07-17T03:47:39.818167Z","end":"2026-07-17T03:47:40.066041Z","steps":["trace[2069630704] 'agreement among raft nodes before linearized reading' (duration: 247.754468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:40.066465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:38.885443Z","time spent":"1.179953176s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:47:40.471786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.368374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:47:40.471872Z","caller":"traceutil/trace.go:171","msg":"trace[1924434633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1191; }","duration":"263.568669ms","start":"2026-07-17T03:47:40.208283Z","end":"2026-07-17T03:47:40.471852Z","steps":["trace[1924434633] 'range keys from in-memory index tree' (duration: 263.067128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:56.651339Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900641097249653,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T03:47:57.151689Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900641097249653,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T03:47:57.198164Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.047600965s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T03:47:57.198501Z","caller":"traceutil/trace.go:171","msg":"trace[1458135750] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1348; }","duration":"1.047523343s","start":"2026-07-17T03:47:56.150954Z","end":"2026-07-17T03:47:57.198477Z","steps":["trace[1458135750] 'read index received' (duration: 1.047328169s)","trace[1458135750] 'applied index is now lower than readState.Index' (duration: 191.724µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:47:57.198561Z","caller":"traceutil/trace.go:171","msg":"trace[1096747189] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"1.048025705s","start":"2026-07-17T03:47:56.15049Z","end":"2026-07-17T03:47:57.198515Z","steps":["trace[1096747189] 'process raft request' (duration: 1.047834541s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:57.198689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047707307s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-17T03:47:57.198725Z","caller":"traceutil/trace.go:171","msg":"trace[458659681] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1292; }","duration":"1.047776508s","start":"2026-07-17T03:47:56.150938Z","end":"2026-07-17T03:47:57.198715Z","steps":["trace[458659681] 'agreement among raft nodes before linearized reading' (duration: 1.047633305s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:57.198722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:56.150478Z","time spent":"1.048158798s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:47:57.198728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030225599s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-17T03:47:57.198736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046735036s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-17T03:47:57.198761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:56.150933Z","time spent":"1.04781935s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-17T03:47:57.19878Z","caller":"traceutil/trace.go:171","msg":"trace[1391972319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1292; }","duration":"1.030298242s","start":"2026-07-17T03:47:56.168468Z","end":"2026-07-17T03:47:57.198766Z","steps":["trace[1391972319] 'agreement among raft nodes before linearized reading' (duration: 1.030147988s)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:47:57.198793Z","caller":"traceutil/trace.go:171","msg":"trace[1117008871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1292; }","duration":"1.046803827s","start":"2026-07-17T03:47:56.151973Z","end":"2026-07-17T03:47:57.198776Z","steps":["trace[1117008871] 'agreement among raft nodes before linearized reading' (duration: 1.046656954s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:57.198809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:56.168462Z","time spent":"1.030341672s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T03:47:57.198831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:56.151968Z","time spent":"1.046855298s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T03:47:57.198878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.001321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-17T03:47:57.198926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047298338s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-17T03:47:57.198948Z","caller":"traceutil/trace.go:171","msg":"trace[1695637874] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1292; }","duration":"1.047332079s","start":"2026-07-17T03:47:56.15161Z","end":"2026-07-17T03:47:57.198942Z","steps":["trace[1695637874] 'agreement among raft nodes before linearized reading' (duration: 1.046982931s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:57.19896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:56.151605Z","time spent":"1.04735295s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-17T03:47:57.198964Z","caller":"traceutil/trace.go:171","msg":"trace[2114457362] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1292; }","duration":"417.136075ms","start":"2026-07-17T03:47:56.781806Z","end":"2026-07-17T03:47:57.198942Z","steps":["trace[2114457362] 'agreement among raft nodes before linearized reading' (duration: 416.872949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:57.199011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:56.781792Z","time spent":"417.207996ms","remote":"127.0.0.1:40682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":103,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-07-17T03:47:57.671669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.805066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T03:47:57.671804Z","caller":"traceutil/trace.go:171","msg":"trace[583916613] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"469.22051ms","start":"2026-07-17T03:47:57.202558Z","end":"2026-07-17T03:47:57.671778Z","steps":["trace[583916613] 'process raft request' (duration: 154.208489ms)","trace[583916613] 'compare' (duration: 314.498199ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:47:57.671883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:57.202536Z","time spent":"469.310142ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:47:58.293966Z","caller":"traceutil/trace.go:171","msg":"trace[1852141441] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"1.091227498s","start":"2026-07-17T03:47:57.202717Z","end":"2026-07-17T03:47:58.293944Z","steps":["trace[1852141441] 'process raft request' (duration: 1.090702076s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:58.294081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:57.202688Z","time spent":"1.091333001s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:47:58.29409Z","caller":"traceutil/trace.go:171","msg":"trace[712571526] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1349; }","duration":"1.086693984s","start":"2026-07-17T03:47:57.207375Z","end":"2026-07-17T03:47:58.294069Z","steps":["trace[712571526] 'read index received' (duration: 149.515412ms)","trace[712571526] 'applied index is now lower than readState.Index' (duration: 937.177582ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:47:58.294104Z","caller":"traceutil/trace.go:171","msg":"trace[2081078902] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"610.344682ms","start":"2026-07-17T03:47:57.683743Z","end":"2026-07-17T03:47:58.294088Z","steps":["trace[2081078902] 'process raft request' (duration: 610.120997ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:47:58.294216Z","caller":"traceutil/trace.go:171","msg":"trace[501475537] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"1.091418812s","start":"2026-07-17T03:47:57.202787Z","end":"2026-07-17T03:47:58.294206Z","steps":["trace[501475537] 'process raft request' (duration: 1.090967822s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:58.294219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:57.683725Z","time spent":"610.432464ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:47:58.294293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:57.202765Z","time spent":"1.091470663s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:47:58.294323Z","caller":"traceutil/trace.go:171","msg":"trace[20210940] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"1.091159766s","start":"2026-07-17T03:47:57.202892Z","end":"2026-07-17T03:47:58.294052Z","steps":["trace[20210940] 'process raft request' (duration: 1.090942101s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:58.294363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:57.202884Z","time spent":"1.091456823s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:47:58.298469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.472119ms","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-07-17T03:47:58.29851Z","caller":"traceutil/trace.go:171","msg":"trace[1003627530] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1297; }","duration":"340.549321ms","start":"2026-07-17T03:47:57.957953Z","end":"2026-07-17T03:47:58.298502Z","steps":["trace[1003627530] 'agreement among raft nodes before linearized reading' (duration: 340.48514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:58.298541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:57.957928Z","time spent":"340.607552ms","remote":"127.0.0.1:40946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-07-17T03:47:58.298678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091303709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:47:58.298702Z","caller":"traceutil/trace.go:171","msg":"trace[1106219856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1297; }","duration":"1.091356199s","start":"2026-07-17T03:47:57.20734Z","end":"2026-07-17T03:47:58.298696Z","steps":["trace[1106219856] 'agreement among raft nodes before linearized reading' (duration: 1.086822687s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:58.29872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:57.207326Z","time spent":"1.091389541s","remote":"127.0.0.1:40608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T03:48:10.237259Z","caller":"traceutil/trace.go:171","msg":"trace[1590016640] linearizableReadLoop","detail":"{readStateIndex:1392; appliedIndex:1391; }","duration":"331.497406ms","start":"2026-07-17T03:48:09.905742Z","end":"2026-07-17T03:48:10.237239Z","steps":["trace[1590016640] 'read index received' (duration: 331.365863ms)","trace[1590016640] 'applied index is now lower than readState.Index' (duration: 130.143µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:48:10.237327Z","caller":"traceutil/trace.go:171","msg":"trace[382230876] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"488.08025ms","start":"2026-07-17T03:48:09.749203Z","end":"2026-07-17T03:48:10.237284Z","steps":["trace[382230876] 'process raft request' (duration: 487.917146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:10.237458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.644469ms","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":"info","ts":"2026-07-17T03:48:10.238257Z","caller":"traceutil/trace.go:171","msg":"trace[282843751] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1332; }","duration":"332.501539ms","start":"2026-07-17T03:48:09.905736Z","end":"2026-07-17T03:48:10.238237Z","steps":["trace[282843751] 'agreement among raft nodes before linearized reading' (duration: 331.623238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:10.237494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:09.749182Z","time spent":"488.222342ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:48:10.238386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:09.905676Z","time spent":"332.663122ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-07-17T03:48:10.237576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.972192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T03:48:10.238518Z","caller":"traceutil/trace.go:171","msg":"trace[24574205] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1332; }","duration":"128.956546ms","start":"2026-07-17T03:48:10.109544Z","end":"2026-07-17T03:48:10.2385Z","steps":["trace[24574205] 'agreement among raft nodes before linearized reading' (duration: 127.958783ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:48:10.739833Z","caller":"traceutil/trace.go:171","msg":"trace[1704824016] linearizableReadLoop","detail":"{readStateIndex:1393; appliedIndex:1392; }","duration":"358.854419ms","start":"2026-07-17T03:48:10.380956Z","end":"2026-07-17T03:48:10.73981Z","steps":["trace[1704824016] 'read index received' (duration: 358.452619ms)","trace[1704824016] 'applied index is now lower than readState.Index' (duration: 400.7µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:48:10.739962Z","caller":"traceutil/trace.go:171","msg":"trace[1021206119] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"376.156782ms","start":"2026-07-17T03:48:10.363793Z","end":"2026-07-17T03:48:10.73995Z","steps":["trace[1021206119] 'process raft request' (duration: 375.64168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:10.740074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:10.363766Z","time spent":"376.231014ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:48:10.740093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.494589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:48:10.740166Z","caller":"traceutil/trace.go:171","msg":"trace[1398295548] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1333; }","duration":"133.581531ms","start":"2026-07-17T03:48:10.606567Z","end":"2026-07-17T03:48:10.740149Z","steps":["trace[1398295548] 'agreement among raft nodes before linearized reading' (duration: 133.455908ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:10.740229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.689693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-17T03:48:10.740268Z","caller":"traceutil/trace.go:171","msg":"trace[959043337] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1333; }","duration":"354.783026ms","start":"2026-07-17T03:48:10.385473Z","end":"2026-07-17T03:48:10.740256Z","steps":["trace[959043337] 'agreement among raft nodes before linearized reading' (duration: 354.673124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:10.740299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:10.385439Z","time spent":"354.852777ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T03:48:10.740508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.559415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T03:48:10.740538Z","caller":"traceutil/trace.go:171","msg":"trace[362787199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1333; }","duration":"359.634636ms","start":"2026-07-17T03:48:10.380894Z","end":"2026-07-17T03:48:10.740529Z","steps":["trace[362787199] 'agreement among raft nodes before linearized reading' (duration: 359.510764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:10.740575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:10.380873Z","time spent":"359.694167ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T03:48:10.740115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.630563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-17T03:48:10.740639Z","caller":"traceutil/trace.go:171","msg":"trace[1416511658] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1333; }","duration":"355.179444ms","start":"2026-07-17T03:48:10.385447Z","end":"2026-07-17T03:48:10.740627Z","steps":["trace[1416511658] 'agreement among raft nodes before linearized reading' (duration: 354.569841ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:10.740683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:10.385432Z","time spent":"355.235985ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-17T03:48:14.77292Z","caller":"traceutil/trace.go:171","msg":"trace[1718938923] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"259.453435ms","start":"2026-07-17T03:48:14.513428Z","end":"2026-07-17T03:48:14.772881Z","steps":["trace[1718938923] 'process raft request' (duration: 259.317472ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:48:14.783566Z","caller":"traceutil/trace.go:171","msg":"trace[249014954] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"109.039672ms","start":"2026-07-17T03:48:14.674518Z","end":"2026-07-17T03:48:14.783558Z","steps":["trace[249014954] 'process raft request' (duration: 108.890959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:15.916518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:15.366875Z","time spent":"549.630121ms","remote":"127.0.0.1:35926","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-17T03:48:15.917158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.68109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T03:48:15.917236Z","caller":"traceutil/trace.go:171","msg":"trace[1536325344] linearizableReadLoop","detail":"{readStateIndex:1432; appliedIndex:1431; }","duration":"637.626414ms","start":"2026-07-17T03:48:15.279596Z","end":"2026-07-17T03:48:15.917222Z","steps":["trace[1536325344] 'read index received' (duration: 64.012µs)","trace[1536325344] 'applied index is now lower than readState.Index' (duration: 637.561362ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:48:15.917333Z","caller":"traceutil/trace.go:171","msg":"trace[783715677] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"1.073597146s","start":"2026-07-17T03:48:14.843725Z","end":"2026-07-17T03:48:15.917322Z","steps":["trace[783715677] 'process raft request' (duration: 419.687512ms)","trace[783715677] 'compare' (duration: 653.515275ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:48:15.917393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:14.843717Z","time spent":"1.073645337s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:48:15.917428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.821476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T03:48:15.917688Z","caller":"traceutil/trace.go:171","msg":"trace[735968078] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1371; }","duration":"163.117463ms","start":"2026-07-17T03:48:15.754555Z","end":"2026-07-17T03:48:15.917672Z","steps":["trace[735968078] 'agreement among raft nodes before linearized reading' (duration: 162.813076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:15.917936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.33605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T03:48:15.917979Z","caller":"traceutil/trace.go:171","msg":"trace[1127348783] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1371; }","duration":"638.406841ms","start":"2026-07-17T03:48:15.279562Z","end":"2026-07-17T03:48:15.917969Z","steps":["trace[1127348783] 'agreement among raft nodes before linearized reading' (duration: 638.282009ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:15.918015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:15.279545Z","time spent":"638.463602ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T03:48:16.439076Z","caller":"traceutil/trace.go:171","msg":"trace[1269776556] linearizableReadLoop","detail":"{readStateIndex:1433; appliedIndex:1432; }","duration":"231.392997ms","start":"2026-07-17T03:48:16.207661Z","end":"2026-07-17T03:48:16.439054Z","steps":["trace[1269776556] 'read index received' (duration: 199.577443ms)","trace[1269776556] 'applied index is now lower than readState.Index' (duration: 31.814314ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:48:16.43927Z","caller":"traceutil/trace.go:171","msg":"trace[651747715] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"517.784755ms","start":"2026-07-17T03:48:15.921461Z","end":"2026-07-17T03:48:16.439246Z","steps":["trace[651747715] 'process raft request' (duration: 485.781367ms)","trace[651747715] 'compare' (duration: 31.718041ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:48:16.43939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:15.921445Z","time spent":"517.872157ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:48:16.439456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.332232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T03:48:16.439532Z","caller":"traceutil/trace.go:171","msg":"trace[2002324402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1372; }","duration":"103.452825ms","start":"2026-07-17T03:48:16.336062Z","end":"2026-07-17T03:48:16.439515Z","steps":["trace[2002324402] 'agreement among raft nodes before linearized reading' (duration: 103.266691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:16.43967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.014931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:48:16.43971Z","caller":"traceutil/trace.go:171","msg":"trace[450561704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1372; }","duration":"232.089072ms","start":"2026-07-17T03:48:16.207611Z","end":"2026-07-17T03:48:16.4397Z","steps":["trace[450561704] 'agreement among raft nodes before linearized reading' (duration: 232.026261ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:48:16.902058Z","caller":"traceutil/trace.go:171","msg":"trace[2008942300] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"114.152438ms","start":"2026-07-17T03:48:16.787883Z","end":"2026-07-17T03:48:16.902035Z","steps":["trace[2008942300] 'process raft request' (duration: 114.011294ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:48:17.045154Z","caller":"traceutil/trace.go:171","msg":"trace[1191133198] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"138.204675ms","start":"2026-07-17T03:48:16.906925Z","end":"2026-07-17T03:48:17.04513Z","steps":["trace[1191133198] 'process raft request' (duration: 138.157784ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:48:17.045278Z","caller":"traceutil/trace.go:171","msg":"trace[2065341154] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"138.342898ms","start":"2026-07-17T03:48:16.90687Z","end":"2026-07-17T03:48:17.045213Z","steps":["trace[2065341154] 'process raft request' (duration: 58.025771ms)","trace[2065341154] 'compare' (duration: 80.062772ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:48:18.487894Z","caller":"traceutil/trace.go:171","msg":"trace[1894182604] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"561.914569ms","start":"2026-07-17T03:48:17.925947Z","end":"2026-07-17T03:48:18.487862Z","steps":["trace[1894182604] 'process raft request' (duration: 561.718404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:18.488201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:17.925921Z","time spent":"562.066993ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:48:19.357581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.441695ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-17T03:48:19.357835Z","caller":"traceutil/trace.go:171","msg":"trace[579754607] linearizableReadLoop","detail":"{readStateIndex:1439; appliedIndex:1437; }","duration":"1.151758995s","start":"2026-07-17T03:48:18.206049Z","end":"2026-07-17T03:48:19.357808Z","steps":["trace[579754607] 'read index received' (duration: 281.681061ms)","trace[579754607] 'applied index is now lower than readState.Index' (duration: 870.076224ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:48:19.357962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.151904808s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:48:19.358Z","caller":"traceutil/trace.go:171","msg":"trace[1720835847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1377; }","duration":"1.15199152s","start":"2026-07-17T03:48:18.205998Z","end":"2026-07-17T03:48:19.35799Z","steps":["trace[1720835847] 'agreement among raft nodes before linearized reading' (duration: 1.151920568s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:19.358033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:18.205975Z","time spent":"1.152050922s","remote":"127.0.0.1:40608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T03:48:19.358655Z","caller":"traceutil/trace.go:171","msg":"trace[1030319291] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"451.157198ms","start":"2026-07-17T03:48:18.907478Z","end":"2026-07-17T03:48:19.358636Z","steps":["trace[1030319291] 'process raft request' (duration: 450.267468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:19.358687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.920111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-17T03:48:19.358733Z","caller":"traceutil/trace.go:171","msg":"trace[1845549424] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1378; }","duration":"309.013983ms","start":"2026-07-17T03:48:19.049706Z","end":"2026-07-17T03:48:19.35872Z","steps":["trace[1845549424] 'agreement among raft nodes before linearized reading' (duration: 308.86894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:19.358766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:19.049676Z","time spent":"309.081854ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T03:48:19.358767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:18.907438Z","time spent":"451.264501ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:48:19.358818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.372932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T03:48:19.35891Z","caller":"traceutil/trace.go:171","msg":"trace[250640427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1378; }","duration":"874.523485ms","start":"2026-07-17T03:48:18.484362Z","end":"2026-07-17T03:48:19.358886Z","steps":["trace[250640427] 'agreement among raft nodes before linearized reading' (duration: 874.29155ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:19.358954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:18.484334Z","time spent":"874.611336ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T03:48:19.359205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.346373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T03:48:19.359241Z","caller":"traceutil/trace.go:171","msg":"trace[1380093861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1378; }","duration":"310.439695ms","start":"2026-07-17T03:48:19.048791Z","end":"2026-07-17T03:48:19.359231Z","steps":["trace[1380093861] 'agreement among raft nodes before linearized reading' (duration: 309.813581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:19.359271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:19.048777Z","time spent":"310.486376ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T03:48:19.35941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.979997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T03:48:19.359559Z","caller":"traceutil/trace.go:171","msg":"trace[208130947] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1378; }","duration":"916.05994ms","start":"2026-07-17T03:48:18.443381Z","end":"2026-07-17T03:48:19.359441Z","steps":["trace[208130947] 'agreement among raft nodes before linearized reading' (duration: 915.13896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:19.359604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:18.443363Z","time spent":"916.231346ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T03:48:19.671119Z","caller":"traceutil/trace.go:171","msg":"trace[228743905] linearizableReadLoop","detail":"{readStateIndex:1444; appliedIndex:1440; }","duration":"302.126397ms","start":"2026-07-17T03:48:19.368967Z","end":"2026-07-17T03:48:19.671093Z","steps":["trace[228743905] 'read index received' (duration: 83.073321ms)","trace[228743905] 'applied index is now lower than readState.Index' (duration: 219.052046ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:48:19.671197Z","caller":"traceutil/trace.go:171","msg":"trace[263822796] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"306.535078ms","start":"2026-07-17T03:48:19.364601Z","end":"2026-07-17T03:48:19.671136Z","steps":["trace[263822796] 'process raft request' (duration: 306.419645ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:48:19.671281Z","caller":"traceutil/trace.go:171","msg":"trace[580834767] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"305.924194ms","start":"2026-07-17T03:48:19.365296Z","end":"2026-07-17T03:48:19.67122Z","steps":["trace[580834767] 'process raft request' (duration: 305.76193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:19.67146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:19.364555Z","time spent":"306.812244ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:48:19.671262Z","caller":"traceutil/trace.go:171","msg":"trace[2062870769] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"307.692514ms","start":"2026-07-17T03:48:19.363535Z","end":"2026-07-17T03:48:19.671228Z","steps":["trace[2062870769] 'process raft request' (duration: 307.309945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:19.671571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:19.365277Z","time spent":"306.141398ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:48:19.671638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:19.363513Z","time spent":"308.054281ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:48:19.67129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.301571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:48:19.671771Z","caller":"traceutil/trace.go:171","msg":"trace[1345302176] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1382; }","duration":"302.827843ms","start":"2026-07-17T03:48:19.368927Z","end":"2026-07-17T03:48:19.671755Z","steps":["trace[1345302176] 'agreement among raft nodes before linearized reading' (duration: 302.312001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:19.671829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:19.368917Z","time spent":"302.895854ms","remote":"127.0.0.1:40608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T03:48:19.695975Z","caller":"traceutil/trace.go:171","msg":"trace[1815669161] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"152.818479ms","start":"2026-07-17T03:48:19.543126Z","end":"2026-07-17T03:48:19.695945Z","steps":["trace[1815669161] 'process raft request' (duration: 152.716576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:19.696121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.545371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:48:19.696215Z","caller":"traceutil/trace.go:171","msg":"trace[358057234] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1383; }","duration":"115.612623ms","start":"2026-07-17T03:48:19.580549Z","end":"2026-07-17T03:48:19.696161Z","steps":["trace[358057234] 'agreement among raft nodes before linearized reading' (duration: 115.405268ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:48:20.619591Z","caller":"traceutil/trace.go:171","msg":"trace[60129643] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"122.344255ms","start":"2026-07-17T03:48:20.497221Z","end":"2026-07-17T03:48:20.619566Z","steps":["trace[60129643] 'process raft request' (duration: 122.165971ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:48:21.238292Z","caller":"traceutil/trace.go:171","msg":"trace[2055393711] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"103.636519ms","start":"2026-07-17T03:48:21.134634Z","end":"2026-07-17T03:48:21.23827Z","steps":["trace[2055393711] 'process raft request' (duration: 103.440865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:21.541023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.349553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T03:48:21.541112Z","caller":"traceutil/trace.go:171","msg":"trace[1306177136] linearizableReadLoop","detail":"{readStateIndex:1448; appliedIndex:1446; }","duration":"329.993932ms","start":"2026-07-17T03:48:21.211104Z","end":"2026-07-17T03:48:21.541098Z","steps":["trace[1306177136] 'read index received' (duration: 27.050286ms)","trace[1306177136] 'applied index is now lower than readState.Index' (duration: 302.942176ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:48:21.541182Z","caller":"traceutil/trace.go:171","msg":"trace[1349631176] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"362.080711ms","start":"2026-07-17T03:48:21.179064Z","end":"2026-07-17T03:48:21.541145Z","steps":["trace[1349631176] 'process raft request' (duration: 214.542153ms)","trace[1349631176] 'compare' (duration: 147.234971ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:48:21.541308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:21.179016Z","time spent":"362.229474ms","remote":"127.0.0.1:40682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T03:48:21.541349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.219296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:48:21.541487Z","caller":"traceutil/trace.go:171","msg":"trace[424223928] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1386; }","duration":"330.3681ms","start":"2026-07-17T03:48:21.211083Z","end":"2026-07-17T03:48:21.541451Z","steps":["trace[424223928] 'agreement among raft nodes before linearized reading' (duration: 330.083813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:21.541563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:21.21102Z","time spent":"330.526554ms","remote":"127.0.0.1:40608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T03:48:21.867358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.661241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T03:48:21.867479Z","caller":"traceutil/trace.go:171","msg":"trace[731866320] linearizableReadLoop","detail":"{readStateIndex:1449; appliedIndex:1448; }","duration":"326.328517ms","start":"2026-07-17T03:48:21.541131Z","end":"2026-07-17T03:48:21.867459Z","steps":["trace[731866320] 'read index received' (duration: 167.487612ms)","trace[731866320] 'applied index is now lower than readState.Index' (duration: 158.839325ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:48:21.867736Z","caller":"traceutil/trace.go:171","msg":"trace[2067897615] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"502.725783ms","start":"2026-07-17T03:48:21.364996Z","end":"2026-07-17T03:48:21.867722Z","steps":["trace[2067897615] 'process raft request' (duration: 343.591701ms)","trace[2067897615] 'compare' (duration: 158.510668ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:48:21.867808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:21.364978Z","time spent":"502.792734ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:48:21.867902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.832204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:48:21.867968Z","caller":"traceutil/trace.go:171","msg":"trace[1808446791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1387; }","duration":"324.984876ms","start":"2026-07-17T03:48:21.542965Z","end":"2026-07-17T03:48:21.86795Z","steps":["trace[1808446791] 'agreement among raft nodes before linearized reading' (duration: 324.829363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:21.868006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:21.542948Z","time spent":"325.050588ms","remote":"127.0.0.1:40596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T03:48:21.868104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.265663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T03:48:21.868206Z","caller":"traceutil/trace.go:171","msg":"trace[1432820833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1387; }","duration":"191.412017ms","start":"2026-07-17T03:48:21.67677Z","end":"2026-07-17T03:48:21.868182Z","steps":["trace[1432820833] 'agreement among raft nodes before linearized reading' (duration: 191.034628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:21.8683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.155974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-17T03:48:21.868332Z","caller":"traceutil/trace.go:171","msg":"trace[702394603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1387; }","duration":"192.203695ms","start":"2026-07-17T03:48:21.676119Z","end":"2026-07-17T03:48:21.868322Z","steps":["trace[702394603] 'agreement among raft nodes before linearized reading' (duration: 192.066542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:21.868374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.981177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T03:48:21.868412Z","caller":"traceutil/trace.go:171","msg":"trace[705583814] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1387; }","duration":"412.053678ms","start":"2026-07-17T03:48:21.456347Z","end":"2026-07-17T03:48:21.868401Z","steps":["trace[705583814] 'agreement among raft nodes before linearized reading' (duration: 411.909855ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:21.868442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:21.456331Z","time spent":"412.104869ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T03:48:21.868457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.83817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-17T03:48:21.868482Z","caller":"traceutil/trace.go:171","msg":"trace[1011325705] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1387; }","duration":"192.88473ms","start":"2026-07-17T03:48:21.675589Z","end":"2026-07-17T03:48:21.868474Z","steps":["trace[1011325705] 'agreement among raft nodes before linearized reading' (duration: 192.813908ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:21.868582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.414327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:48:21.868622Z","caller":"traceutil/trace.go:171","msg":"trace[629124907] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1387; }","duration":"557.494859ms","start":"2026-07-17T03:48:21.311113Z","end":"2026-07-17T03:48:21.868608Z","steps":["trace[629124907] 'agreement among raft nodes before linearized reading' (duration: 557.429698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:21.868652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:21.311089Z","time spent":"557.55521ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-17T03:48:27.231629Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900641097249934,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T03:48:27.73246Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900641097249934,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T03:48:28.233Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900641097249934,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T03:48:28.320368Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.671448083s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T03:48:28.320848Z","caller":"traceutil/trace.go:171","msg":"trace[309192159] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"1.672129109s","start":"2026-07-17T03:48:26.64869Z","end":"2026-07-17T03:48:28.320819Z","steps":["trace[309192159] 'process raft request' (duration: 1.671945705s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:28.320997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:26.648666Z","time spent":"1.672255132s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:48:28.323084Z","caller":"traceutil/trace.go:171","msg":"trace[306193812] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"436.293071ms","start":"2026-07-17T03:48:27.886766Z","end":"2026-07-17T03:48:28.323059Z","steps":["trace[306193812] 'process raft request' (duration: 436.129647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:28.323861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:27.886734Z","time spent":"436.403642ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:48:28.325791Z","caller":"traceutil/trace.go:171","msg":"trace[768890146] linearizableReadLoop","detail":"{readStateIndex:1490; appliedIndex:1487; }","duration":"1.594437791s","start":"2026-07-17T03:48:26.731294Z","end":"2026-07-17T03:48:28.325732Z","steps":["trace[768890146] 'read index received' (duration: 1.589389776s)","trace[768890146] 'applied index is now lower than readState.Index' (duration: 5.046264ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:48:28.325944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.517193532s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:48:28.325984Z","caller":"traceutil/trace.go:171","msg":"trace[1909379638] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1426; }","duration":"1.517241963s","start":"2026-07-17T03:48:26.80873Z","end":"2026-07-17T03:48:28.325972Z","steps":["trace[1909379638] 'agreement among raft nodes before linearized reading' (duration: 1.517167591s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:28.326038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:26.808668Z","time spent":"1.517359216s","remote":"127.0.0.1:44994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-17T03:48:28.32597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.594580004s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-17T03:48:28.326062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.245532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T03:48:28.32614Z","caller":"traceutil/trace.go:171","msg":"trace[622053071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1426; }","duration":"343.350984ms","start":"2026-07-17T03:48:27.982769Z","end":"2026-07-17T03:48:28.32612Z","steps":["trace[622053071] 'agreement among raft nodes before linearized reading' (duration: 343.1544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:28.326142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.881555ms","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-07-17T03:48:28.326188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:27.982758Z","time spent":"343.421366ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-17T03:48:28.326209Z","caller":"traceutil/trace.go:171","msg":"trace[1556175788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1426; }","duration":"339.985148ms","start":"2026-07-17T03:48:27.986207Z","end":"2026-07-17T03:48:28.326192Z","steps":["trace[1556175788] 'agreement among raft nodes before linearized reading' (duration: 339.795574ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:48:28.326128Z","caller":"traceutil/trace.go:171","msg":"trace[146248213] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1426; }","duration":"1.594812139s","start":"2026-07-17T03:48:26.731258Z","end":"2026-07-17T03:48:28.32607Z","steps":["trace[146248213] 'agreement among raft nodes before linearized reading' (duration: 1.594558283s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:28.326258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.118423966s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:48:28.326309Z","caller":"traceutil/trace.go:171","msg":"trace[1883743178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1426; }","duration":"1.118514468s","start":"2026-07-17T03:48:27.207781Z","end":"2026-07-17T03:48:28.326296Z","steps":["trace[1883743178] 'agreement among raft nodes before linearized reading' (duration: 1.118433376s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:28.326343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:27.207762Z","time spent":"1.118573649s","remote":"127.0.0.1:40608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T03:48:28.326389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.29052ms","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-07-17T03:48:28.326412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:26.731242Z","time spent":"1.595055094s","remote":"127.0.0.1:40940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-17T03:48:28.3262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.122165ms","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":"warn","ts":"2026-07-17T03:48:28.326435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.550797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T03:48:28.326489Z","caller":"traceutil/trace.go:171","msg":"trace[894544951] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1426; }","duration":"164.465392ms","start":"2026-07-17T03:48:28.162009Z","end":"2026-07-17T03:48:28.326474Z","steps":["trace[894544951] 'agreement among raft nodes before linearized reading' (duration: 163.950791ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:48:28.326503Z","caller":"traceutil/trace.go:171","msg":"trace[350113970] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1426; }","duration":"346.653489ms","start":"2026-07-17T03:48:27.979834Z","end":"2026-07-17T03:48:28.326487Z","steps":["trace[350113970] 'agreement among raft nodes before linearized reading' (duration: 346.435945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:28.326494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.360493935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-17T03:48:28.326587Z","caller":"traceutil/trace.go:171","msg":"trace[194595369] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1426; }","duration":"1.360689119s","start":"2026-07-17T03:48:26.965886Z","end":"2026-07-17T03:48:28.326575Z","steps":["trace[194595369] 'agreement among raft nodes before linearized reading' (duration: 1.360384913s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:28.326669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:26.965868Z","time spent":"1.360789823s","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-17T03:48:28.326543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:27.979819Z","time spent":"346.716131ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T03:48:28.326449Z","caller":"traceutil/trace.go:171","msg":"trace[1972061337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1426; }","duration":"411.388252ms","start":"2026-07-17T03:48:27.91505Z","end":"2026-07-17T03:48:28.326438Z","steps":["trace[1972061337] 'agreement among raft nodes before linearized reading' (duration: 411.245409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:48:28.326815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:27.915032Z","time spent":"411.771343ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T03:48:28.326243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:48:27.986189Z","time spent":"340.046669ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-17T03:50:53.771387Z","caller":"traceutil/trace.go:171","msg":"trace[7870968] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"107.485868ms","start":"2026-07-17T03:50:53.66388Z","end":"2026-07-17T03:50:53.771366Z","steps":["trace[7870968] 'process raft request' (duration: 107.334065ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:51:25.488997Z","caller":"traceutil/trace.go:171","msg":"trace[5599689] transaction","detail":"{read_only:false; response_revision:2256; number_of_response:1; }","duration":"138.412803ms","start":"2026-07-17T03:51:25.350547Z","end":"2026-07-17T03:51:25.48896Z","steps":["trace[5599689] 'process raft request' (duration: 100.395701ms)","trace[5599689] 'compare' (duration: 37.663653ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:51:25.489306Z","caller":"traceutil/trace.go:171","msg":"trace[627443251] transaction","detail":"{read_only:false; response_revision:2257; number_of_response:1; }","duration":"134.578601ms","start":"2026-07-17T03:51:25.354678Z","end":"2026-07-17T03:51:25.489256Z","steps":["trace[627443251] 'process raft request' (duration: 134.134581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:52:38.373215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.35976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:52:38.37332Z","caller":"traceutil/trace.go:171","msg":"trace[1494720661] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2613; }","duration":"161.574955ms","start":"2026-07-17T03:52:38.211724Z","end":"2026-07-17T03:52:38.373299Z","steps":["trace[1494720661] 'range keys from in-memory index tree' (duration: 161.301029ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:52:38.708446Z","caller":"traceutil/trace.go:171","msg":"trace[1973116608] linearizableReadLoop","detail":"{readStateIndex:2728; appliedIndex:2727; }","duration":"194.697853ms","start":"2026-07-17T03:52:38.513718Z","end":"2026-07-17T03:52:38.708416Z","steps":["trace[1973116608] 'read index received' (duration: 99.953574ms)","trace[1973116608] 'applied index is now lower than readState.Index' (duration: 94.742349ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:52:38.708603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.865556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T03:52:38.708673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.190353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:52:38.708755Z","caller":"traceutil/trace.go:171","msg":"trace[2142329705] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2613; }","duration":"133.359188ms","start":"2026-07-17T03:52:38.575382Z","end":"2026-07-17T03:52:38.708741Z","steps":["trace[2142329705] 'agreement among raft nodes before linearized reading' (duration: 133.186393ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:52:38.708701Z","caller":"traceutil/trace.go:171","msg":"trace[807359177] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2613; }","duration":"194.988749ms","start":"2026-07-17T03:52:38.513665Z","end":"2026-07-17T03:52:38.708653Z","steps":["trace[807359177] 'agreement among raft nodes before linearized reading' (duration: 194.855216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:52:45.931063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.604367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T03:52:45.931162Z","caller":"traceutil/trace.go:171","msg":"trace[842969211] linearizableReadLoop","detail":"{readStateIndex:2766; appliedIndex:2765; }","duration":"375.608853ms","start":"2026-07-17T03:52:45.555538Z","end":"2026-07-17T03:52:45.931146Z","steps":["trace[842969211] 'read index received' (duration: 60.833302ms)","trace[842969211] 'applied index is now lower than readState.Index' (duration: 314.77393ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:52:45.931266Z","caller":"traceutil/trace.go:171","msg":"trace[732479726] transaction","detail":"{read_only:false; response_revision:2650; number_of_response:1; }","duration":"416.999718ms","start":"2026-07-17T03:52:45.514254Z","end":"2026-07-17T03:52:45.931254Z","steps":["trace[732479726] 'process raft request' (duration: 102.128026ms)","trace[732479726] 'compare' (duration: 313.904128ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:52:45.93132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:52:45.514235Z","time spent":"417.053479ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:52:45.931362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.793707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:52:45.931448Z","caller":"traceutil/trace.go:171","msg":"trace[714006127] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2650; }","duration":"375.94328ms","start":"2026-07-17T03:52:45.555484Z","end":"2026-07-17T03:52:45.931427Z","steps":["trace[714006127] 'agreement among raft nodes before linearized reading' (duration: 375.777746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:52:45.931492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:52:45.555464Z","time spent":"376.020603ms","remote":"127.0.0.1:40750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"info","ts":"2026-07-17T03:52:45.952992Z","caller":"traceutil/trace.go:171","msg":"trace[2096267870] transaction","detail":"{read_only:false; response_revision:2651; number_of_response:1; }","duration":"130.012977ms","start":"2026-07-17T03:52:45.82294Z","end":"2026-07-17T03:52:45.952953Z","steps":["trace[2096267870] 'process raft request' (duration: 129.869984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:52:46.447531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.066471ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T03:52:46.447783Z","caller":"traceutil/trace.go:171","msg":"trace[81034758] linearizableReadLoop","detail":"{readStateIndex:2769; appliedIndex:2767; }","duration":"423.944955ms","start":"2026-07-17T03:52:46.023818Z","end":"2026-07-17T03:52:46.447763Z","steps":["trace[81034758] 'read index received' (duration: 102.507985ms)","trace[81034758] 'applied index is now lower than readState.Index' (duration: 321.43545ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:52:46.447793Z","caller":"traceutil/trace.go:171","msg":"trace[249680194] transaction","detail":"{read_only:false; response_revision:2652; number_of_response:1; }","duration":"490.541997ms","start":"2026-07-17T03:52:45.95721Z","end":"2026-07-17T03:52:46.447752Z","steps":["trace[249680194] 'process raft request' (duration: 169.127237ms)","trace[249680194] 'compare' (duration: 320.919238ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:52:46.447896Z","caller":"traceutil/trace.go:171","msg":"trace[1456067141] transaction","detail":"{read_only:false; response_revision:2653; number_of_response:1; }","duration":"490.158418ms","start":"2026-07-17T03:52:45.957723Z","end":"2026-07-17T03:52:46.447881Z","steps":["trace[1456067141] 'process raft request' (duration: 489.965743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:52:46.447945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:52:45.957179Z","time spent":"490.685259ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:52:46.447971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:52:45.957695Z","time spent":"490.234149ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:52:46.448065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.097904ms","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-07-17T03:52:46.448137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.814687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:52:46.448166Z","caller":"traceutil/trace.go:171","msg":"trace[1417084245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2653; }","duration":"418.253618ms","start":"2026-07-17T03:52:46.029885Z","end":"2026-07-17T03:52:46.448139Z","steps":["trace[1417084245] 'agreement among raft nodes before linearized reading' (duration: 418.010812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:52:46.448224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:52:46.029863Z","time spent":"418.351651ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T03:52:46.44833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.519208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-17T03:52:46.448358Z","caller":"traceutil/trace.go:171","msg":"trace[2069528012] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2653; }","duration":"424.58684ms","start":"2026-07-17T03:52:46.023763Z","end":"2026-07-17T03:52:46.44835Z","steps":["trace[2069528012] 'agreement among raft nodes before linearized reading' (duration: 424.439116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:52:46.448389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:52:46.023746Z","time spent":"424.634912ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-17T03:52:46.448179Z","caller":"traceutil/trace.go:171","msg":"trace[1757974178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2653; }","duration":"239.896889ms","start":"2026-07-17T03:52:46.208268Z","end":"2026-07-17T03:52:46.448165Z","steps":["trace[1757974178] 'agreement among raft nodes before linearized reading' (duration: 239.830107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:52:46.448533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.606042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T03:52:46.448582Z","caller":"traceutil/trace.go:171","msg":"trace[995855950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2653; }","duration":"328.695295ms","start":"2026-07-17T03:52:46.119871Z","end":"2026-07-17T03:52:46.448566Z","steps":["trace[995855950] 'agreement among raft nodes before linearized reading' (duration: 328.53712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:52:46.448617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:52:46.119851Z","time spent":"328.758207ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T03:52:46.448721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.023237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:52:46.44876Z","caller":"traceutil/trace.go:171","msg":"trace[1148227938] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:2653; }","duration":"266.076098ms","start":"2026-07-17T03:52:46.182672Z","end":"2026-07-17T03:52:46.448748Z","steps":["trace[1148227938] 'agreement among raft nodes before linearized reading' (duration: 266.016537ms)"],"step_count":1}