{"level":"warn","ts":"2026-07-09T16:43:25.982631Z","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-09T16:43:25.982718Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.224:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.224:2380","--initial-cluster=instance=https://199.19.213.224:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.224:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.224: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-09T16:43:25.982802Z","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-09T16:43:25.982814Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.224:2380"]} {"level":"info","ts":"2026-07-09T16:43:25.982844Z","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-09T16:43:25.983688Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.224:2379"]} {"level":"info","ts":"2026-07-09T16:43:25.983777Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.224:2380"],"listen-peer-urls":["https://199.19.213.224:2380"],"advertise-client-urls":["https://199.19.213.224:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.224:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.224: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-09T16:43:25.989532Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.500295ms"} {"level":"info","ts":"2026-07-09T16:43:26.000278Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9870e1635c99d2ec","cluster-id":"185398c074a8a76b"} {"level":"info","ts":"2026-07-09T16:43:26.000368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec switched to configuration voters=()"} {"level":"info","ts":"2026-07-09T16:43:26.000388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became follower at term 0"} {"level":"info","ts":"2026-07-09T16:43:26.000397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9870e1635c99d2ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-09T16:43:26.000406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became follower at term 1"} {"level":"info","ts":"2026-07-09T16:43:26.000439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec switched to configuration voters=(10984527308028236524)"} {"level":"warn","ts":"2026-07-09T16:43:26.014103Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-09T16:43:26.016346Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-09T16:43:26.018233Z","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-09T16:43:26.023693Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9870e1635c99d2ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-09T16:43:26.023971Z","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-09T16:43:26.024061Z","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-09T16:43:26.024118Z","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-09T16:43:26.031857Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9870e1635c99d2ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-09T16:43:26.035985Z","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-09T16:43:26.036103Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.224:2380"} {"level":"info","ts":"2026-07-09T16:43:26.036201Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.224:2380"} {"level":"info","ts":"2026-07-09T16:43:26.036408Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9870e1635c99d2ec","initial-advertise-peer-urls":["https://199.19.213.224:2380"],"listen-peer-urls":["https://199.19.213.224:2380"],"advertise-client-urls":["https://199.19.213.224:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.224:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-09T16:43:26.036463Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-09T16:43:26.037132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec switched to configuration voters=(10984527308028236524)"} {"level":"info","ts":"2026-07-09T16:43:26.037372Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"185398c074a8a76b","local-member-id":"9870e1635c99d2ec","added-peer-id":"9870e1635c99d2ec","added-peer-peer-urls":["https://199.19.213.224:2380"]} {"level":"info","ts":"2026-07-09T16:43:26.502339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec is starting a new election at term 1"} {"level":"info","ts":"2026-07-09T16:43:26.502419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became pre-candidate at term 1"} {"level":"info","ts":"2026-07-09T16:43:26.502468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec received MsgPreVoteResp from 9870e1635c99d2ec at term 1"} {"level":"info","ts":"2026-07-09T16:43:26.502497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became candidate at term 2"} {"level":"info","ts":"2026-07-09T16:43:26.502513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec received MsgVoteResp from 9870e1635c99d2ec at term 2"} {"level":"info","ts":"2026-07-09T16:43:26.502533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became leader at term 2"} {"level":"info","ts":"2026-07-09T16:43:26.50255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9870e1635c99d2ec elected leader 9870e1635c99d2ec at term 2"} {"level":"info","ts":"2026-07-09T16:43:26.526199Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:43:26.53808Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9870e1635c99d2ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.224:2379]}","request-path":"/0/members/9870e1635c99d2ec/attributes","cluster-id":"185398c074a8a76b","publish-timeout":"7s"} {"level":"info","ts":"2026-07-09T16:43:26.538899Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T16:43:26.539027Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T16:43:26.539543Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-09T16:43:26.539625Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-09T16:43:26.54775Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"185398c074a8a76b","local-member-id":"9870e1635c99d2ec","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:43:26.548394Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:43:26.550251Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-09T16:43:26.548432Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:43:26.551016Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.224:2379"} {"level":"info","ts":"2026-07-09T16:43:29.029726Z","caller":"traceutil/trace.go:171","msg":"trace[1765916654] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"172.789485ms","start":"2026-07-09T16:43:28.856909Z","end":"2026-07-09T16:43:29.029698Z","steps":["trace[1765916654] 'read index received' (duration: 95.850515ms)","trace[1765916654] 'applied index is now lower than readState.Index' (duration: 76.93789ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:43:29.029866Z","caller":"traceutil/trace.go:171","msg":"trace[744213327] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"174.583049ms","start":"2026-07-09T16:43:28.855245Z","end":"2026-07-09T16:43:29.029828Z","steps":["trace[744213327] 'process raft request' (duration: 97.582777ms)","trace[744213327] 'compare' (duration: 76.717805ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:43:29.030002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.071601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T16:43:29.030069Z","caller":"traceutil/trace.go:171","msg":"trace[509890636] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:72; }","duration":"173.173475ms","start":"2026-07-09T16:43:28.856884Z","end":"2026-07-09T16:43:29.030058Z","steps":["trace[509890636] 'agreement among raft nodes before linearized reading' (duration: 172.97317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:29.316884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.49177ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-09T16:43:29.317031Z","caller":"traceutil/trace.go:171","msg":"trace[712399089] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"202.180337ms","start":"2026-07-09T16:43:29.114833Z","end":"2026-07-09T16:43:29.317013Z","steps":["trace[712399089] 'process raft request' (duration: 59.045211ms)","trace[712399089] 'compare' (duration: 142.342067ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:43:32.365965Z","caller":"traceutil/trace.go:171","msg":"trace[114342995] linearizableReadLoop","detail":"{readStateIndex:271; appliedIndex:270; }","duration":"302.85852ms","start":"2026-07-09T16:43:32.063083Z","end":"2026-07-09T16:43:32.365942Z","steps":["trace[114342995] 'read index received' (duration: 302.699287ms)","trace[114342995] 'applied index is now lower than readState.Index' (duration: 158.133µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:43:32.366082Z","caller":"traceutil/trace.go:171","msg":"trace[614054418] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"317.603282ms","start":"2026-07-09T16:43:32.048457Z","end":"2026-07-09T16:43:32.36606Z","steps":["trace[614054418] 'process raft request' (duration: 317.349216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:32.366122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.028094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-09T16:43:32.366165Z","caller":"traceutil/trace.go:171","msg":"trace[1370701223] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:261; }","duration":"303.111227ms","start":"2026-07-09T16:43:32.063042Z","end":"2026-07-09T16:43:32.366154Z","steps":["trace[1370701223] 'agreement among raft nodes before linearized reading' (duration: 302.997554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:32.366178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:32.048438Z","time spent":"317.695025ms","remote":"127.0.0.1:48466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1934,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:43:32.366214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:32.06303Z","time spent":"303.175448ms","remote":"127.0.0.1:48476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-09T16:43:32.883121Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198697973035807598,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:43:33.853678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.413956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:43:33.85397Z","caller":"traceutil/trace.go:171","msg":"trace[1902318150] linearizableReadLoop","detail":"{readStateIndex:273; appliedIndex:271; }","duration":"1.47108373s","start":"2026-07-09T16:43:32.382869Z","end":"2026-07-09T16:43:33.853953Z","steps":["trace[1902318150] 'read index received' (duration: 803.219853ms)","trace[1902318150] 'applied index is now lower than readState.Index' (duration: 667.862907ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:43:33.854044Z","caller":"traceutil/trace.go:171","msg":"trace[1363082921] transaction","detail":"{read_only:false; number_of_response:0; response_revision:263; }","duration":"954.321895ms","start":"2026-07-09T16:43:32.899695Z","end":"2026-07-09T16:43:33.854016Z","steps":["trace[1363082921] 'process raft request' (duration: 954.198332ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:33.854062Z","caller":"traceutil/trace.go:171","msg":"trace[1888441396] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"1.481723972s","start":"2026-07-09T16:43:32.372246Z","end":"2026-07-09T16:43:33.85397Z","steps":["trace[1888441396] 'process raft request' (duration: 1.481546627s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:33.854196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:32.899688Z","time spent":"954.442828ms","remote":"127.0.0.1:48540","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:43:33.854233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.064848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"warn","ts":"2026-07-09T16:43:33.854255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:32.372228Z","time spent":"1.481938107s","remote":"127.0.0.1:48806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2651,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:43:33.854298Z","caller":"traceutil/trace.go:171","msg":"trace[1853162214] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:263; }","duration":"956.17203ms","start":"2026-07-09T16:43:32.898106Z","end":"2026-07-09T16:43:33.854279Z","steps":["trace[1853162214] 'agreement among raft nodes before linearized reading' (duration: 956.018377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:33.854335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:32.898086Z","time spent":"956.237931ms","remote":"127.0.0.1:48540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4003,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-07-09T16:43:33.854338Z","caller":"traceutil/trace.go:171","msg":"trace[1937832416] transaction","detail":"{read_only:false; number_of_response:0; response_revision:263; }","duration":"954.695424ms","start":"2026-07-09T16:43:32.899598Z","end":"2026-07-09T16:43:33.854294Z","steps":["trace[1937832416] 'process raft request' (duration: 954.260963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:33.85436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.776074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T16:43:33.854416Z","caller":"traceutil/trace.go:171","msg":"trace[1523566366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:263; }","duration":"428.931028ms","start":"2026-07-09T16:43:33.425469Z","end":"2026-07-09T16:43:33.8544Z","steps":["trace[1523566366] 'agreement among raft nodes before linearized reading' (duration: 428.800645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:33.85446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:33.425443Z","time spent":"429.005299ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T16:43:33.854443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.812314ms","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-09T16:43:33.854454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:32.899583Z","time spent":"954.817286ms","remote":"127.0.0.1:48540","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:43:33.854507Z","caller":"traceutil/trace.go:171","msg":"trace[1064357396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:263; }","duration":"430.912447ms","start":"2026-07-09T16:43:33.423588Z","end":"2026-07-09T16:43:33.854501Z","steps":["trace[1064357396] 'agreement among raft nodes before linearized reading' (duration: 430.828505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:33.854527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:33.423566Z","time spent":"430.955517ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T16:43:33.854622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.471747857s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:43:33.85467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.765936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-09T16:43:33.854664Z","caller":"traceutil/trace.go:171","msg":"trace[89483252] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:263; }","duration":"1.471787677s","start":"2026-07-09T16:43:32.382863Z","end":"2026-07-09T16:43:33.854651Z","steps":["trace[89483252] 'agreement among raft nodes before linearized reading' (duration: 1.471725996s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:33.854691Z","caller":"traceutil/trace.go:171","msg":"trace[1392263792] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:263; }","duration":"827.824796ms","start":"2026-07-09T16:43:33.02686Z","end":"2026-07-09T16:43:33.854685Z","steps":["trace[1392263792] 'agreement among raft nodes before linearized reading' (duration: 827.784265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:33.854709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:33.026833Z","time spent":"827.872078ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T16:43:33.854706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:32.3828Z","time spent":"1.471895951s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-09T16:43:33.854876Z","caller":"traceutil/trace.go:171","msg":"trace[1782621197] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"1.484155881s","start":"2026-07-09T16:43:32.370683Z","end":"2026-07-09T16:43:33.854839Z","steps":["trace[1782621197] 'process raft request' (duration: 815.458544ms)","trace[1782621197] 'compare' (duration: 666.937104ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:43:33.854092Z","caller":"traceutil/trace.go:171","msg":"trace[697178459] transaction","detail":"{read_only:false; number_of_response:0; response_revision:263; }","duration":"954.095729ms","start":"2026-07-09T16:43:32.89998Z","end":"2026-07-09T16:43:33.854076Z","steps":["trace[697178459] 'process raft request' (duration: 953.949866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:33.855008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:32.370667Z","time spent":"1.484277505s","remote":"127.0.0.1:48558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":180,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:43:33.855009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:32.899971Z","time spent":"954.991731ms","remote":"127.0.0.1:48540","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:43:39.677779Z","caller":"traceutil/trace.go:171","msg":"trace[16184404] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"101.034702ms","start":"2026-07-09T16:43:39.576723Z","end":"2026-07-09T16:43:39.677757Z","steps":["trace[16184404] 'process raft request' (duration: 100.840767ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:40.109447Z","caller":"traceutil/trace.go:171","msg":"trace[953601555] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"170.020707ms","start":"2026-07-09T16:43:39.939401Z","end":"2026-07-09T16:43:40.109422Z","steps":["trace[953601555] 'process raft request' (duration: 169.957996ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:40.109983Z","caller":"traceutil/trace.go:171","msg":"trace[1611193599] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"171.118224ms","start":"2026-07-09T16:43:39.938851Z","end":"2026-07-09T16:43:40.109969Z","steps":["trace[1611193599] 'process raft request' (duration: 94.742067ms)","trace[1611193599] 'compare' (duration: 75.596367ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:44:06.851254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.432675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:44:06.85145Z","caller":"traceutil/trace.go:171","msg":"trace[270433408] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"380.17427ms","start":"2026-07-09T16:44:06.471252Z","end":"2026-07-09T16:44:06.851427Z","steps":["trace[270433408] 'process raft request' (duration: 89.327515ms)","trace[270433408] 'compare' (duration: 289.85778ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:44:06.851529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:06.471218Z","time spent":"380.272042ms","remote":"127.0.0.1:48410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:44:07.205993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.814633ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:44:07.206164Z","caller":"traceutil/trace.go:171","msg":"trace[2090146497] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"620.547694ms","start":"2026-07-09T16:44:06.585544Z","end":"2026-07-09T16:44:07.206092Z","steps":["trace[2090146497] 'process raft request' (duration: 384.575297ms)","trace[2090146497] 'compare' (duration: 235.592688ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:44:07.206299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:06.585525Z","time spent":"620.738459ms","remote":"127.0.0.1:48410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:44:07.206632Z","caller":"traceutil/trace.go:171","msg":"trace[30952939] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"316.009563ms","start":"2026-07-09T16:44:06.890611Z","end":"2026-07-09T16:44:07.206621Z","steps":["trace[30952939] 'process raft request' (duration: 315.933381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:07.206746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:06.89059Z","time spent":"316.079875ms","remote":"127.0.0.1:48410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:44:07.212023Z","caller":"traceutil/trace.go:171","msg":"trace[45309507] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"157.420917ms","start":"2026-07-09T16:44:07.054582Z","end":"2026-07-09T16:44:07.212003Z","steps":["trace[45309507] 'process raft request' (duration: 157.1016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:08.372832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.86624ms","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-09T16:44:08.372936Z","caller":"traceutil/trace.go:171","msg":"trace[756108383] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:575; }","duration":"106.044825ms","start":"2026-07-09T16:44:08.266871Z","end":"2026-07-09T16:44:08.372916Z","steps":["trace[756108383] 'range keys from in-memory index tree' (duration: 105.659245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:08.373195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.103729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:44:08.373232Z","caller":"traceutil/trace.go:171","msg":"trace[1888248364] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"146.14131ms","start":"2026-07-09T16:44:08.227081Z","end":"2026-07-09T16:44:08.373223Z","steps":["trace[1888248364] 'range keys from in-memory index tree' (duration: 146.045667ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:08.544985Z","caller":"traceutil/trace.go:171","msg":"trace[1840211270] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"168.726085ms","start":"2026-07-09T16:44:08.376232Z","end":"2026-07-09T16:44:08.544958Z","steps":["trace[1840211270] 'process raft request' (duration: 168.525521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:15.726289Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198697973035808338,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:44:16.044967Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.024658592s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T16:44:16.045596Z","caller":"traceutil/trace.go:171","msg":"trace[1944885863] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.025420381s","start":"2026-07-09T16:44:15.020148Z","end":"2026-07-09T16:44:16.045568Z","steps":["trace[1944885863] 'process raft request' (duration: 1.025237536s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:16.045768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:15.020117Z","time spent":"1.025555704s","remote":"127.0.0.1:48540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:44:16.118603Z","caller":"traceutil/trace.go:171","msg":"trace[1630437825] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:644; }","duration":"892.750032ms","start":"2026-07-09T16:44:15.225831Z","end":"2026-07-09T16:44:16.118581Z","steps":["trace[1630437825] 'read index received' (duration: 819.402519ms)","trace[1630437825] 'applied index is now lower than readState.Index' (duration: 73.346663ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:44:16.118768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.917406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:44:16.118856Z","caller":"traceutil/trace.go:171","msg":"trace[670443327] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"893.05483ms","start":"2026-07-09T16:44:15.225779Z","end":"2026-07-09T16:44:16.118833Z","steps":["trace[670443327] 'agreement among raft nodes before linearized reading' (duration: 892.887656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:16.118904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:15.225758Z","time spent":"893.136591ms","remote":"127.0.0.1:48356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:44:16.724847Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198697973035808342,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:44:17.628151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.919785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:44:17.628264Z","caller":"traceutil/trace.go:171","msg":"trace[634477872] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:647; }","duration":"1.404043362s","start":"2026-07-09T16:44:16.22421Z","end":"2026-07-09T16:44:17.628254Z","steps":["trace[634477872] 'read index received' (duration: 587.887212ms)","trace[634477872] 'applied index is now lower than readState.Index' (duration: 816.15548ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:44:17.628411Z","caller":"traceutil/trace.go:171","msg":"trace[912312709] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"1.505897173s","start":"2026-07-09T16:44:16.122505Z","end":"2026-07-09T16:44:17.628402Z","steps":["trace[912312709] 'process raft request' (duration: 689.666252ms)","trace[912312709] 'compare' (duration: 815.626837ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:44:17.628448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.122478Z","time spent":"1.505951335s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:44:17.628579Z","caller":"traceutil/trace.go:171","msg":"trace[613759913] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"1.506012827s","start":"2026-07-09T16:44:16.122561Z","end":"2026-07-09T16:44:17.628574Z","steps":["trace[613759913] 'process raft request' (duration: 1.505659068s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:17.628607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.122549Z","time spent":"1.506042617s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:44:17.628671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.404473592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:44:17.628684Z","caller":"traceutil/trace.go:171","msg":"trace[2067010459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"1.404487082s","start":"2026-07-09T16:44:16.224192Z","end":"2026-07-09T16:44:17.628679Z","steps":["trace[2067010459] 'agreement among raft nodes before linearized reading' (duration: 1.404463592s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:17.628697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.224132Z","time spent":"1.404562475s","remote":"127.0.0.1:48370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:44:17.62883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038627006s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T16:44:17.628844Z","caller":"traceutil/trace.go:171","msg":"trace[112686793] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"1.038666116s","start":"2026-07-09T16:44:16.590173Z","end":"2026-07-09T16:44:17.62884Z","steps":["trace[112686793] 'agreement among raft nodes before linearized reading' (duration: 1.038586574s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:17.628857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.590157Z","time spent":"1.038695587s","remote":"127.0.0.1:48604","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-09T16:44:18.178917Z","caller":"traceutil/trace.go:171","msg":"trace[1202811681] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"128.140958ms","start":"2026-07-09T16:44:18.050753Z","end":"2026-07-09T16:44:18.178893Z","steps":["trace[1202811681] 'process raft request' (duration: 127.613875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:19.369113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.021304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:44:19.369187Z","caller":"traceutil/trace.go:171","msg":"trace[275886674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"143.140027ms","start":"2026-07-09T16:44:19.226032Z","end":"2026-07-09T16:44:19.369172Z","steps":["trace[275886674] 'range keys from in-memory index tree' (duration: 142.935431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:33.050401Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198697973035808652,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T16:44:33.146766Z","caller":"traceutil/trace.go:171","msg":"trace[1953986408] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"771.173385ms","start":"2026-07-09T16:44:32.375569Z","end":"2026-07-09T16:44:33.146742Z","steps":["trace[1953986408] 'process raft request' (duration: 770.97138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:33.146914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:32.375544Z","time spent":"771.292127ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:44:33.29809Z","caller":"traceutil/trace.go:171","msg":"trace[117651495] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"371.117527ms","start":"2026-07-09T16:44:32.926945Z","end":"2026-07-09T16:44:33.298062Z","steps":["trace[117651495] 'process raft request' (duration: 370.933253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:33.298278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:32.926916Z","time spent":"371.272981ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:44:33.29841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.526619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:44:33.298465Z","caller":"traceutil/trace.go:171","msg":"trace[9865771] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:780; }","duration":"748.656782ms","start":"2026-07-09T16:44:32.549794Z","end":"2026-07-09T16:44:33.29845Z","steps":["trace[9865771] 'agreement among raft nodes before linearized reading' (duration: 748.534489ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:33.298435Z","caller":"traceutil/trace.go:171","msg":"trace[347677667] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"157.817948ms","start":"2026-07-09T16:44:33.140577Z","end":"2026-07-09T16:44:33.298395Z","steps":["trace[347677667] 'process raft request' (duration: 157.434068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:33.298514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:32.549773Z","time spent":"748.726524ms","remote":"127.0.0.1:48594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-07-09T16:44:33.298289Z","caller":"traceutil/trace.go:171","msg":"trace[2066167189] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:810; }","duration":"748.414676ms","start":"2026-07-09T16:44:32.549847Z","end":"2026-07-09T16:44:33.298262Z","steps":["trace[2066167189] 'read index received' (duration: 596.677118ms)","trace[2066167189] 'applied index is now lower than readState.Index' (duration: 151.735788ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:44:33.305429Z","caller":"traceutil/trace.go:171","msg":"trace[1657577755] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"142.283495ms","start":"2026-07-09T16:44:33.163096Z","end":"2026-07-09T16:44:33.305379Z","steps":["trace[1657577755] 'process raft request' (duration: 142.144012ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:09.544508Z","caller":"traceutil/trace.go:171","msg":"trace[607727011] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"210.50111ms","start":"2026-07-09T16:45:09.333986Z","end":"2026-07-09T16:45:09.544487Z","steps":["trace[607727011] 'process raft request' (duration: 210.336386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:22.239464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.185117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:45:22.23961Z","caller":"traceutil/trace.go:171","msg":"trace[859240882] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"207.24601ms","start":"2026-07-09T16:45:22.032352Z","end":"2026-07-09T16:45:22.239598Z","steps":["trace[859240882] 'process raft request' (duration: 207.210189ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:22.239637Z","caller":"traceutil/trace.go:171","msg":"trace[1927040318] linearizableReadLoop","detail":"{readStateIndex:1085; appliedIndex:1084; }","duration":"211.075604ms","start":"2026-07-09T16:45:22.02855Z","end":"2026-07-09T16:45:22.239625Z","steps":["trace[1927040318] 'read index received' (duration: 103.63347ms)","trace[1927040318] 'applied index is now lower than readState.Index' (duration: 107.441274ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:22.239718Z","caller":"traceutil/trace.go:171","msg":"trace[1959621093] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"228.158192ms","start":"2026-07-09T16:45:22.011551Z","end":"2026-07-09T16:45:22.239709Z","steps":["trace[1959621093] 'process raft request' (duration: 120.659677ms)","trace[1959621093] 'compare' (duration: 107.090325ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:45:22.239847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.487224ms","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-09T16:45:22.239872Z","caller":"traceutil/trace.go:171","msg":"trace[387167186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1042; }","duration":"220.543426ms","start":"2026-07-09T16:45:22.019322Z","end":"2026-07-09T16:45:22.239865Z","steps":["trace[387167186] 'agreement among raft nodes before linearized reading' (duration: 220.448013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:22.239925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.633891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:45:22.240013Z","caller":"traceutil/trace.go:171","msg":"trace[849995336] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1042; }","duration":"121.763845ms","start":"2026-07-09T16:45:22.118225Z","end":"2026-07-09T16:45:22.239989Z","steps":["trace[849995336] 'agreement among raft nodes before linearized reading' (duration: 121.600981ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:30.313331Z","caller":"traceutil/trace.go:171","msg":"trace[127891433] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"294.444126ms","start":"2026-07-09T16:45:30.01886Z","end":"2026-07-09T16:45:30.313304Z","steps":["trace[127891433] 'process raft request' (duration: 294.321463ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:30.324911Z","caller":"traceutil/trace.go:171","msg":"trace[959053347] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1108; }","duration":"268.813309ms","start":"2026-07-09T16:45:30.056085Z","end":"2026-07-09T16:45:30.324898Z","steps":["trace[959053347] 'read index received' (duration: 257.061471ms)","trace[959053347] 'applied index is now lower than readState.Index' (duration: 11.751088ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:30.32495Z","caller":"traceutil/trace.go:171","msg":"trace[1307202983] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"290.47686ms","start":"2026-07-09T16:45:30.034456Z","end":"2026-07-09T16:45:30.324933Z","steps":["trace[1307202983] 'process raft request' (duration: 285.681802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:30.325063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.970402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-09T16:45:30.325089Z","caller":"traceutil/trace.go:171","msg":"trace[683653494] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1065; }","duration":"269.043754ms","start":"2026-07-09T16:45:30.056038Z","end":"2026-07-09T16:45:30.325081Z","steps":["trace[683653494] 'agreement among raft nodes before linearized reading' (duration: 268.943742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:30.325094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.398047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.224\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-09T16:45:30.325137Z","caller":"traceutil/trace.go:171","msg":"trace[921015989] range","detail":"{range_begin:/registry/masterleases/199.19.213.224; range_end:; response_count:1; response_revision:1065; }","duration":"168.477329ms","start":"2026-07-09T16:45:30.156648Z","end":"2026-07-09T16:45:30.325126Z","steps":["trace[921015989] 'agreement among raft nodes before linearized reading' (duration: 168.371807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:32.825961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.680089ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:45:32.826243Z","caller":"traceutil/trace.go:171","msg":"trace[551047230] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"433.690479ms","start":"2026-07-09T16:45:32.392526Z","end":"2026-07-09T16:45:32.826217Z","steps":["trace[551047230] 'process raft request' (duration: 433.634268ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:32.826267Z","caller":"traceutil/trace.go:171","msg":"trace[794807237] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"433.831733ms","start":"2026-07-09T16:45:32.39242Z","end":"2026-07-09T16:45:32.826252Z","steps":["trace[794807237] 'process raft request' (duration: 274.750524ms)","trace[794807237] 'compare' (duration: 158.540486ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:32.826288Z","caller":"traceutil/trace.go:171","msg":"trace[1933528227] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"433.801582ms","start":"2026-07-09T16:45:32.392459Z","end":"2026-07-09T16:45:32.82626Z","steps":["trace[1933528227] 'process raft request' (duration: 433.608857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:32.826332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:32.392398Z","time spent":"433.904375ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:45:32.826354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:32.392517Z","time spent":"433.786162ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:45:32.826449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:32.392445Z","time spent":"433.909085ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:45:33.875521Z","caller":"traceutil/trace.go:171","msg":"trace[2100695319] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"166.351777ms","start":"2026-07-09T16:45:33.709143Z","end":"2026-07-09T16:45:33.875495Z","steps":["trace[2100695319] 'process raft request' (duration: 166.193293ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:34.561751Z","caller":"traceutil/trace.go:171","msg":"trace[1826617891] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"137.130851ms","start":"2026-07-09T16:45:34.42459Z","end":"2026-07-09T16:45:34.56172Z","steps":["trace[1826617891] 'process raft request' (duration: 128.311035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:35.221586Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198697973035809356,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:45:35.722128Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198697973035809356,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:45:36.22233Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198697973035809356,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:45:36.646937Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.948212618s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-09T16:45:36.870487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.104868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:45:36.870623Z","caller":"traceutil/trace.go:171","msg":"trace[88657401] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"2.171994543s","start":"2026-07-09T16:45:34.698602Z","end":"2026-07-09T16:45:36.870597Z","steps":["trace[88657401] 'process raft request' (duration: 1.94867216s)","trace[88657401] 'compare' (duration: 222.227756ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:45:36.87071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:34.698576Z","time spent":"2.172091636s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:45:37.112664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.869392ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-09T16:45:37.113265Z","caller":"traceutil/trace.go:171","msg":"trace[543939945] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1125; }","duration":"2.392336254s","start":"2026-07-09T16:45:34.720894Z","end":"2026-07-09T16:45:37.11323Z","steps":["trace[543939945] 'read index received' (duration: 1.926198189s)","trace[543939945] 'applied index is now lower than readState.Index' (duration: 466.136835ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:37.113294Z","caller":"traceutil/trace.go:171","msg":"trace[1025193561] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"696.618314ms","start":"2026-07-09T16:45:36.416644Z","end":"2026-07-09T16:45:37.113263Z","steps":["trace[1025193561] 'process raft request' (duration: 696.181193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:37.113417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.392507398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:45:37.113465Z","caller":"traceutil/trace.go:171","msg":"trace[896276162] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1081; }","duration":"2.39260687s","start":"2026-07-09T16:45:34.720848Z","end":"2026-07-09T16:45:37.113455Z","steps":["trace[896276162] 'agreement among raft nodes before linearized reading' (duration: 2.392509428s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:37.113505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:34.720832Z","time spent":"2.392664641s","remote":"127.0.0.1:48532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:45:37.11355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:36.416621Z","time spent":"696.83842ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:45:37.113875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.283952758s","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-09T16:45:37.113916Z","caller":"traceutil/trace.go:171","msg":"trace[1542022893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1081; }","duration":"2.284029479s","start":"2026-07-09T16:45:34.829876Z","end":"2026-07-09T16:45:37.113906Z","steps":["trace[1542022893] 'agreement among raft nodes before linearized reading' (duration: 2.283885076s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:37.113946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:34.82986Z","time spent":"2.28407893s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T16:45:37.113914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033962632s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:45:37.114014Z","caller":"traceutil/trace.go:171","msg":"trace[2108340037] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1081; }","duration":"1.034120595s","start":"2026-07-09T16:45:36.079866Z","end":"2026-07-09T16:45:37.113987Z","steps":["trace[2108340037] 'agreement among raft nodes before linearized reading' (duration: 1.033938811s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:37.114096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:36.079846Z","time spent":"1.034231529s","remote":"127.0.0.1:48608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:45:37.114178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234574679s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T16:45:37.114211Z","caller":"traceutil/trace.go:171","msg":"trace[1332252124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1081; }","duration":"1.234642251s","start":"2026-07-09T16:45:35.87956Z","end":"2026-07-09T16:45:37.114202Z","steps":["trace[1332252124] 'agreement among raft nodes before linearized reading' (duration: 1.234553309s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:37.114248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.484812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:45:37.114474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.284280885s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T16:45:37.114539Z","caller":"traceutil/trace.go:171","msg":"trace[1953529682] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1081; }","duration":"2.284355528s","start":"2026-07-09T16:45:34.830164Z","end":"2026-07-09T16:45:37.11452Z","steps":["trace[1953529682] 'agreement among raft nodes before linearized reading' (duration: 2.284215344s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:37.114474Z","caller":"traceutil/trace.go:171","msg":"trace[1232715141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1081; }","duration":"888.059566ms","start":"2026-07-09T16:45:36.226382Z","end":"2026-07-09T16:45:37.114442Z","steps":["trace[1232715141] 'agreement among raft nodes before linearized reading' (duration: 887.462641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:37.114603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.745829ms","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-09T16:45:37.11464Z","caller":"traceutil/trace.go:171","msg":"trace[1150322911] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1081; }","duration":"548.829281ms","start":"2026-07-09T16:45:36.565801Z","end":"2026-07-09T16:45:37.11463Z","steps":["trace[1150322911] 'agreement among raft nodes before linearized reading' (duration: 548.057702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:37.114669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:36.565774Z","time spent":"548.888253ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T16:45:37.114288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.037933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-09T16:45:37.114651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:36.226366Z","time spent":"888.268201ms","remote":"127.0.0.1:48370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T16:45:37.114763Z","caller":"traceutil/trace.go:171","msg":"trace[301135851] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1081; }","duration":"901.554106ms","start":"2026-07-09T16:45:36.213194Z","end":"2026-07-09T16:45:37.114748Z","steps":["trace[301135851] 'agreement among raft nodes before linearized reading' (duration: 900.996442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:37.114799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:36.213171Z","time spent":"901.617608ms","remote":"127.0.0.1:48856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:45:37.114363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.283928977s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-09T16:45:37.114951Z","caller":"traceutil/trace.go:171","msg":"trace[564608236] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1081; }","duration":"2.284529681s","start":"2026-07-09T16:45:34.830412Z","end":"2026-07-09T16:45:37.114941Z","steps":["trace[564608236] 'agreement among raft nodes before linearized reading' (duration: 2.283896825s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:37.114985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:34.830402Z","time spent":"2.284574213s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T16:45:37.114286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:35.879543Z","time spent":"1.234730923s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T16:45:37.114594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:34.830156Z","time spent":"2.284424059s","remote":"127.0.0.1:48604","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-09T16:45:37.521247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.718513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:45:37.521368Z","caller":"traceutil/trace.go:171","msg":"trace[195176124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1086; }","duration":"294.878987ms","start":"2026-07-09T16:45:37.226449Z","end":"2026-07-09T16:45:37.521329Z","steps":["trace[195176124] 'range keys from in-memory index tree' (duration: 294.57072ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:39.637729Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198697973035809374,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:45:40.137984Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198697973035809374,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:45:40.312879Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.190725924s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T16:45:40.31352Z","caller":"traceutil/trace.go:171","msg":"trace[476082484] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1133; }","duration":"1.177330906s","start":"2026-07-09T16:45:39.136162Z","end":"2026-07-09T16:45:40.313493Z","steps":["trace[476082484] 'read index received' (duration: 1.176861745s)","trace[476082484] 'applied index is now lower than readState.Index' (duration: 467.951µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:40.313599Z","caller":"traceutil/trace.go:171","msg":"trace[1606981245] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"1.191535374s","start":"2026-07-09T16:45:39.122018Z","end":"2026-07-09T16:45:40.313554Z","steps":["trace[1606981245] 'process raft request' (duration: 1.191311918s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:40.313743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.177575992s","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-09T16:45:40.313743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.173490542s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T16:45:40.313786Z","caller":"traceutil/trace.go:171","msg":"trace[1733854219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1087; }","duration":"1.177685974s","start":"2026-07-09T16:45:39.136088Z","end":"2026-07-09T16:45:40.313774Z","steps":["trace[1733854219] 'agreement among raft nodes before linearized reading' (duration: 1.1775055s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:40.313796Z","caller":"traceutil/trace.go:171","msg":"trace[1332667913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1087; }","duration":"1.173596344s","start":"2026-07-09T16:45:39.140187Z","end":"2026-07-09T16:45:40.313783Z","steps":["trace[1332667913] 'agreement among raft nodes before linearized reading' (duration: 1.173439611s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:40.313789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:39.121989Z","time spent":"1.191695008s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:45:40.31382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:39.136038Z","time spent":"1.177774037s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T16:45:40.313837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:39.140168Z","time spent":"1.173658146s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T16:45:40.313826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165050195s","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-09T16:45:40.313869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.173568043s","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-09T16:45:40.313904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.173621885s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T16:45:40.31391Z","caller":"traceutil/trace.go:171","msg":"trace[1677417508] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1087; }","duration":"1.165174458s","start":"2026-07-09T16:45:39.148716Z","end":"2026-07-09T16:45:40.31389Z","steps":["trace[1677417508] 'agreement among raft nodes before linearized reading' (duration: 1.164938872s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:40.313928Z","caller":"traceutil/trace.go:171","msg":"trace[1047146027] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1087; }","duration":"1.173639866s","start":"2026-07-09T16:45:39.140273Z","end":"2026-07-09T16:45:40.313913Z","steps":["trace[1047146027] 'agreement among raft nodes before linearized reading' (duration: 1.17342503s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:40.31396Z","caller":"traceutil/trace.go:171","msg":"trace[2121864193] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1087; }","duration":"1.173696046s","start":"2026-07-09T16:45:39.140249Z","end":"2026-07-09T16:45:40.313945Z","steps":["trace[2121864193] 'agreement among raft nodes before linearized reading' (duration: 1.173529842s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:40.313969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:39.1487Z","time spent":"1.16525955s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T16:45:40.313971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086694515s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:45:40.313972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:39.140269Z","time spent":"1.173692757s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T16:45:40.314001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:39.140244Z","time spent":"1.173746988s","remote":"127.0.0.1:48604","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-09T16:45:40.314012Z","caller":"traceutil/trace.go:171","msg":"trace[123830708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1087; }","duration":"1.086769596s","start":"2026-07-09T16:45:39.22723Z","end":"2026-07-09T16:45:40.314Z","steps":["trace[123830708] 'agreement among raft nodes before linearized reading' (duration: 1.086703284s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:40.314013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.28306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.224\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-09T16:45:40.314102Z","caller":"traceutil/trace.go:171","msg":"trace[950423624] range","detail":"{range_begin:/registry/masterleases/199.19.213.224; range_end:; response_count:1; response_revision:1087; }","duration":"156.412984ms","start":"2026-07-09T16:45:40.157659Z","end":"2026-07-09T16:45:40.314072Z","steps":["trace[950423624] 'agreement among raft nodes before linearized reading' (duration: 156.165277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:40.314155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:39.227211Z","time spent":"1.086834258s","remote":"127.0.0.1:48370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:45:40.572718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.440744ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-09T16:45:40.57362Z","caller":"traceutil/trace.go:171","msg":"trace[1291647481] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"254.924578ms","start":"2026-07-09T16:45:40.318679Z","end":"2026-07-09T16:45:40.573604Z","steps":["trace[1291647481] 'process raft request' (duration: 254.876987ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:40.573622Z","caller":"traceutil/trace.go:171","msg":"trace[491394498] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"255.200915ms","start":"2026-07-09T16:45:40.318398Z","end":"2026-07-09T16:45:40.573599Z","steps":["trace[491394498] 'process raft request' (duration: 254.514608ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:40.573735Z","caller":"traceutil/trace.go:171","msg":"trace[1681112040] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"255.188874ms","start":"2026-07-09T16:45:40.318497Z","end":"2026-07-09T16:45:40.573685Z","steps":["trace[1681112040] 'process raft request' (duration: 255.02916ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:40.573864Z","caller":"traceutil/trace.go:171","msg":"trace[1366289369] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"254.696852ms","start":"2026-07-09T16:45:40.319147Z","end":"2026-07-09T16:45:40.573844Z","steps":["trace[1366289369] 'process raft request' (duration: 254.432566ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:40.57409Z","caller":"traceutil/trace.go:171","msg":"trace[190148282] linearizableReadLoop","detail":"{readStateIndex:1140; appliedIndex:1134; }","duration":"250.052739ms","start":"2026-07-09T16:45:40.324021Z","end":"2026-07-09T16:45:40.574074Z","steps":["trace[190148282] 'read index received' (duration: 81.241061ms)","trace[190148282] 'applied index is now lower than readState.Index' (duration: 168.810178ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:40.574304Z","caller":"traceutil/trace.go:171","msg":"trace[1710081616] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"255.106722ms","start":"2026-07-09T16:45:40.31918Z","end":"2026-07-09T16:45:40.574287Z","steps":["trace[1710081616] 'process raft request' (duration: 254.5987ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:40.574468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.437688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:45:40.57452Z","caller":"traceutil/trace.go:171","msg":"trace[321018788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1092; }","duration":"250.52085ms","start":"2026-07-09T16:45:40.323985Z","end":"2026-07-09T16:45:40.574506Z","steps":["trace[321018788] 'agreement among raft nodes before linearized reading' (duration: 250.428808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:40.574696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.665212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:45:40.57478Z","caller":"traceutil/trace.go:171","msg":"trace[810348298] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1092; }","duration":"121.842367ms","start":"2026-07-09T16:45:40.452915Z","end":"2026-07-09T16:45:40.574758Z","steps":["trace[810348298] 'agreement among raft nodes before linearized reading' (duration: 121.684523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:40.924206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.806581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:45:40.924336Z","caller":"traceutil/trace.go:171","msg":"trace[1431757760] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"349.785423ms","start":"2026-07-09T16:45:40.574531Z","end":"2026-07-09T16:45:40.924316Z","steps":["trace[1431757760] 'process raft request' (duration: 106.789767ms)","trace[1431757760] 'compare' (duration: 242.696159ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:45:40.924396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:40.574473Z","time spent":"349.895046ms","remote":"127.0.0.1:48390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:45:41.999033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.507884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:45:41.99915Z","caller":"traceutil/trace.go:171","msg":"trace[1093619974] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1141; }","duration":"1.072856784s","start":"2026-07-09T16:45:40.926249Z","end":"2026-07-09T16:45:41.999106Z","steps":["trace[1093619974] 'read index received' (duration: 118.193776ms)","trace[1093619974] 'applied index is now lower than readState.Index' (duration: 954.661748ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:41.999374Z","caller":"traceutil/trace.go:171","msg":"trace[246363972] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"1.368979812s","start":"2026-07-09T16:45:40.630381Z","end":"2026-07-09T16:45:41.999361Z","steps":["trace[246363972] 'process raft request' (duration: 414.066048ms)","trace[246363972] 'compare' (duration: 954.32891ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:45:41.999457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:40.630352Z","time spent":"1.369058494s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:45:41.999621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.862084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:45:41.999672Z","caller":"traceutil/trace.go:171","msg":"trace[828938796] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1094; }","duration":"198.996897ms","start":"2026-07-09T16:45:41.800663Z","end":"2026-07-09T16:45:41.99966Z","steps":["trace[828938796] 'agreement among raft nodes before linearized reading' (duration: 198.886524ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:41.999845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073604993s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-09T16:45:41.99988Z","caller":"traceutil/trace.go:171","msg":"trace[258942240] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1094; }","duration":"1.073686905s","start":"2026-07-09T16:45:40.926185Z","end":"2026-07-09T16:45:41.999872Z","steps":["trace[258942240] 'agreement among raft nodes before linearized reading' (duration: 1.073625493s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:41.999907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:40.926162Z","time spent":"1.073736916s","remote":"127.0.0.1:48516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-07-09T16:45:42.57014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.311289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:45:42.570221Z","caller":"traceutil/trace.go:171","msg":"trace[1320749691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1094; }","duration":"344.434322ms","start":"2026-07-09T16:45:42.225765Z","end":"2026-07-09T16:45:42.570199Z","steps":["trace[1320749691] 'range keys from in-memory index tree' (duration: 344.219726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:42.570268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:42.225746Z","time spent":"344.511444ms","remote":"127.0.0.1:48370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T16:45:42.570445Z","caller":"traceutil/trace.go:171","msg":"trace[222319138] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"251.008592ms","start":"2026-07-09T16:45:42.319403Z","end":"2026-07-09T16:45:42.570411Z","steps":["trace[222319138] 'process raft request' (duration: 186.380678ms)","trace[222319138] 'compare' (duration: 64.205204ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:45:42.876427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.962296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:45:42.876714Z","caller":"traceutil/trace.go:171","msg":"trace[1305867608] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"296.535418ms","start":"2026-07-09T16:45:42.579984Z","end":"2026-07-09T16:45:42.876519Z","steps":["trace[1305867608] 'process raft request' (duration: 168.388257ms)","trace[1305867608] 'compare' (duration: 127.819183ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:45:45.226918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000370628s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-09T16:45:45.227001Z","caller":"traceutil/trace.go:171","msg":"trace[360538749] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000524172s","start":"2026-07-09T16:45:43.226459Z","end":"2026-07-09T16:45:45.226983Z","steps":["trace[360538749] 'agreement among raft nodes before linearized reading' (duration: 2.000418709s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:45.227065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:43.226438Z","time spent":"2.000616214s","remote":"127.0.0.1:48370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:45:45.856106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.409452094s","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-09T16:45:45.856264Z","caller":"traceutil/trace.go:171","msg":"trace[439783239] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1096; }","duration":"2.409622468s","start":"2026-07-09T16:45:43.446617Z","end":"2026-07-09T16:45:45.85624Z","steps":["trace[439783239] 'range keys from in-memory index tree' (duration: 2.409433863s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:45.85638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.329819961s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:45:45.856608Z","caller":"traceutil/trace.go:171","msg":"trace[1321689618] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"1.281409775s","start":"2026-07-09T16:45:44.575188Z","end":"2026-07-09T16:45:45.856598Z","steps":["trace[1321689618] 'process raft request' (duration: 1.281376765s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:45.856659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:44.575162Z","time spent":"1.281473037s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:45:45.856707Z","caller":"traceutil/trace.go:171","msg":"trace[1237957457] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"3.27595136s","start":"2026-07-09T16:45:42.580714Z","end":"2026-07-09T16:45:45.856665Z","steps":["trace[1237957457] 'process raft request' (duration: 3.275730645s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:45.856869Z","caller":"traceutil/trace.go:171","msg":"trace[1084010770] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"3.275660003s","start":"2026-07-09T16:45:42.5812Z","end":"2026-07-09T16:45:45.856861Z","steps":["trace[1084010770] 'process raft request' (duration: 3.275308824s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:45.856919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:42.581185Z","time spent":"3.275707885s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:45:45.856908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:42.580702Z","time spent":"3.276116185s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:45:45.857005Z","caller":"traceutil/trace.go:171","msg":"trace[1508134643] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1144; }","duration":"2.630429179s","start":"2026-07-09T16:45:43.226525Z","end":"2026-07-09T16:45:45.856955Z","steps":["trace[1508134643] 'read index received' (duration: 299.9093ms)","trace[1508134643] 'applied index is now lower than readState.Index' (duration: 2.330513899s)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:45.857148Z","caller":"traceutil/trace.go:171","msg":"trace[1093932134] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"3.275776937s","start":"2026-07-09T16:45:42.581359Z","end":"2026-07-09T16:45:45.857136Z","steps":["trace[1093932134] 'process raft request' (duration: 3.275176582s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:45.857199Z","caller":"traceutil/trace.go:171","msg":"trace[1377657074] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"3.277109569s","start":"2026-07-09T16:45:42.580067Z","end":"2026-07-09T16:45:45.857177Z","steps":["trace[1377657074] 'process raft request' (duration: 946.428396ms)","trace[1377657074] 'compare' (duration: 2.329591696s)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:45:45.857279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:42.581351Z","time spent":"3.275831638s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:45:45.857294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:42.580047Z","time spent":"3.277203441s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:45:46.062022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.669083ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-09T16:45:46.062239Z","caller":"traceutil/trace.go:171","msg":"trace[2009647576] linearizableReadLoop","detail":"{readStateIndex:1150; appliedIndex:1149; }","duration":"205.110267ms","start":"2026-07-09T16:45:45.857095Z","end":"2026-07-09T16:45:46.062205Z","steps":["trace[2009647576] 'read index received' (duration: 43.183378ms)","trace[2009647576] 'applied index is now lower than readState.Index' (duration: 161.924739ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:45:46.062302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.374876ms","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-09T16:45:46.063072Z","caller":"traceutil/trace.go:171","msg":"trace[2100551625] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1101; }","duration":"535.258668ms","start":"2026-07-09T16:45:45.527787Z","end":"2026-07-09T16:45:46.063046Z","steps":["trace[2100551625] 'agreement among raft nodes before linearized reading' (duration: 534.445988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:46.06318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:45.527766Z","time spent":"535.377121ms","remote":"127.0.0.1:38532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"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-09T16:45:46.062297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.772588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:45:46.063245Z","caller":"traceutil/trace.go:171","msg":"trace[682829528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1101; }","duration":"835.762323ms","start":"2026-07-09T16:45:45.227462Z","end":"2026-07-09T16:45:46.063224Z","steps":["trace[682829528] 'agreement among raft nodes before linearized reading' (duration: 834.774959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:46.062397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.034120674s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-09T16:45:46.063317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:45.22744Z","time spent":"835.859376ms","remote":"127.0.0.1:48356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T16:45:46.063382Z","caller":"traceutil/trace.go:171","msg":"trace[1940364258] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1101; }","duration":"2.035083688s","start":"2026-07-09T16:45:44.028267Z","end":"2026-07-09T16:45:46.063351Z","steps":["trace[1940364258] 'agreement among raft nodes before linearized reading' (duration: 2.034096884s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:46.063454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:44.028213Z","time spent":"2.035218891s","remote":"127.0.0.1:48454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":9,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:45:46.062482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181981219s","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-09T16:45:46.063548Z","caller":"traceutil/trace.go:171","msg":"trace[1973404150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1101; }","duration":"1.183080516s","start":"2026-07-09T16:45:44.880452Z","end":"2026-07-09T16:45:46.063532Z","steps":["trace[1973404150] 'agreement among raft nodes before linearized reading' (duration: 1.181904507s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:46.063611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:44.880431Z","time spent":"1.183166648s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-09T16:45:46.206672Z","caller":"traceutil/trace.go:171","msg":"trace[355615879] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1150; }","duration":"136.977967ms","start":"2026-07-09T16:45:46.069667Z","end":"2026-07-09T16:45:46.206645Z","steps":["trace[355615879] 'read index received' (duration: 84.349897ms)","trace[355615879] 'applied index is now lower than readState.Index' (duration: 52.62566ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:45:46.206973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.134401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:45:46.206965Z","caller":"traceutil/trace.go:171","msg":"trace[572197289] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"138.528516ms","start":"2026-07-09T16:45:46.068399Z","end":"2026-07-09T16:45:46.206927Z","steps":["trace[572197289] 'process raft request' (duration: 110.102619ms)","trace[572197289] 'compare' (duration: 27.511684ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:46.207072Z","caller":"traceutil/trace.go:171","msg":"trace[596347952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1103; }","duration":"137.401898ms","start":"2026-07-09T16:45:46.069647Z","end":"2026-07-09T16:45:46.207049Z","steps":["trace[596347952] 'agreement among raft nodes before linearized reading' (duration: 137.09463ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:52.085221Z","caller":"traceutil/trace.go:171","msg":"trace[76090057] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"142.077382ms","start":"2026-07-09T16:45:51.943133Z","end":"2026-07-09T16:45:52.08521Z","steps":["trace[76090057] 'process raft request' (duration: 141.874217ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:52.085502Z","caller":"traceutil/trace.go:171","msg":"trace[1792099688] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"142.404081ms","start":"2026-07-09T16:45:51.943072Z","end":"2026-07-09T16:45:52.085476Z","steps":["trace[1792099688] 'process raft request' (duration: 141.678363ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:52.085187Z","caller":"traceutil/trace.go:171","msg":"trace[1300506284] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"142.0002ms","start":"2026-07-09T16:45:51.943165Z","end":"2026-07-09T16:45:52.085165Z","steps":["trace[1300506284] 'process raft request' (duration: 141.932448ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:04.502199Z","caller":"traceutil/trace.go:171","msg":"trace[285396530] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1262; }","duration":"485.031297ms","start":"2026-07-09T16:46:04.017147Z","end":"2026-07-09T16:46:04.502178Z","steps":["trace[285396530] 'read index received' (duration: 484.851653ms)","trace[285396530] 'applied index is now lower than readState.Index' (duration: 178.704µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:04.502591Z","caller":"traceutil/trace.go:171","msg":"trace[1414974765] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"525.5427ms","start":"2026-07-09T16:46:03.977031Z","end":"2026-07-09T16:46:04.502574Z","steps":["trace[1414974765] 'process raft request' (duration: 525.030288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:04.502818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:03.976983Z","time spent":"525.667224ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:04.503182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.033953ms","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-09T16:46:04.503226Z","caller":"traceutil/trace.go:171","msg":"trace[457606636] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1209; }","duration":"486.080724ms","start":"2026-07-09T16:46:04.017134Z","end":"2026-07-09T16:46:04.503215Z","steps":["trace[457606636] 'agreement among raft nodes before linearized reading' (duration: 485.94757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:04.503509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:04.017072Z","time spent":"486.426842ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T16:46:04.503582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.777844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-09T16:46:04.503622Z","caller":"traceutil/trace.go:171","msg":"trace[1389255761] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1209; }","duration":"358.974578ms","start":"2026-07-09T16:46:04.144635Z","end":"2026-07-09T16:46:04.503609Z","steps":["trace[1389255761] 'agreement among raft nodes before linearized reading' (duration: 358.654641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:04.504287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:04.144607Z","time spent":"359.673215ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T16:46:04.504009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.266106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:04.504445Z","caller":"traceutil/trace.go:171","msg":"trace[1194523225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1209; }","duration":"277.743458ms","start":"2026-07-09T16:46:04.22669Z","end":"2026-07-09T16:46:04.504434Z","steps":["trace[1194523225] 'agreement among raft nodes before linearized reading' (duration: 277.290156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:04.504092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.784896ms","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-09T16:46:04.504723Z","caller":"traceutil/trace.go:171","msg":"trace[336221053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1209; }","duration":"180.437052ms","start":"2026-07-09T16:46:04.32427Z","end":"2026-07-09T16:46:04.504707Z","steps":["trace[336221053] 'agreement among raft nodes before linearized reading' (duration: 179.760426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:04.504168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.175258ms","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-09T16:46:04.505053Z","caller":"traceutil/trace.go:171","msg":"trace[1066266760] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1209; }","duration":"350.07143ms","start":"2026-07-09T16:46:04.154968Z","end":"2026-07-09T16:46:04.505039Z","steps":["trace[1066266760] 'agreement among raft nodes before linearized reading' (duration: 349.151538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:04.505102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:04.154956Z","time spent":"350.131342ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T16:46:04.504237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.844371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-09T16:46:04.505917Z","caller":"traceutil/trace.go:171","msg":"trace[1068033509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1209; }","duration":"359.539621ms","start":"2026-07-09T16:46:04.146361Z","end":"2026-07-09T16:46:04.505901Z","steps":["trace[1068033509] 'agreement among raft nodes before linearized reading' (duration: 357.83296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:04.506126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:04.146349Z","time spent":"359.758788ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T16:46:07.003721Z","caller":"traceutil/trace.go:171","msg":"trace[1181606789] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1269; }","duration":"487.733878ms","start":"2026-07-09T16:46:06.515966Z","end":"2026-07-09T16:46:07.0037Z","steps":["trace[1181606789] 'read index received' (duration: 487.585735ms)","trace[1181606789] 'applied index is now lower than readState.Index' (duration: 146.963µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:07.004022Z","caller":"traceutil/trace.go:171","msg":"trace[2106160980] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"495.701894ms","start":"2026-07-09T16:46:06.508305Z","end":"2026-07-09T16:46:07.004007Z","steps":["trace[2106160980] 'process raft request' (duration: 495.259013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:07.004115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:06.50828Z","time spent":"495.769326ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:07.004309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.349393ms","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-09T16:46:07.004331Z","caller":"traceutil/trace.go:171","msg":"trace[607421059] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1215; }","duration":"488.407255ms","start":"2026-07-09T16:46:06.515917Z","end":"2026-07-09T16:46:07.004325Z","steps":["trace[607421059] 'agreement among raft nodes before linearized reading' (duration: 488.324833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:07.00435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:06.515897Z","time spent":"488.449116ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T16:46:07.004473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.943943ms","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-09T16:46:07.004491Z","caller":"traceutil/trace.go:171","msg":"trace[1233460358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1215; }","duration":"487.980835ms","start":"2026-07-09T16:46:06.516505Z","end":"2026-07-09T16:46:07.004486Z","steps":["trace[1233460358] 'agreement among raft nodes before linearized reading' (duration: 487.927623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:07.004508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:06.516497Z","time spent":"488.007335ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T16:46:07.004674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.242223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-09T16:46:07.004695Z","caller":"traceutil/trace.go:171","msg":"trace[1645886510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1215; }","duration":"484.282864ms","start":"2026-07-09T16:46:06.520406Z","end":"2026-07-09T16:46:07.004689Z","steps":["trace[1645886510] 'agreement among raft nodes before linearized reading' (duration: 484.226442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:07.004715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:06.520396Z","time spent":"484.311555ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T16:46:07.004885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.709298ms","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-09T16:46:07.004905Z","caller":"traceutil/trace.go:171","msg":"trace[517449056] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1215; }","duration":"487.757059ms","start":"2026-07-09T16:46:06.517142Z","end":"2026-07-09T16:46:07.004899Z","steps":["trace[517449056] 'agreement among raft nodes before linearized reading' (duration: 487.643046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:07.004923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:06.517137Z","time spent":"487.781919ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T16:46:07.005041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.045866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-09T16:46:07.005101Z","caller":"traceutil/trace.go:171","msg":"trace[903278072] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1215; }","duration":"488.140348ms","start":"2026-07-09T16:46:06.516946Z","end":"2026-07-09T16:46:07.005087Z","steps":["trace[903278072] 'agreement among raft nodes before linearized reading' (duration: 487.971124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:07.005128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:06.516932Z","time spent":"488.191229ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T16:46:07.570568Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198697973035809663,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:46:08.070845Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198697973035809663,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T16:46:08.074799Z","caller":"traceutil/trace.go:171","msg":"trace[2057653424] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"1.065636367s","start":"2026-07-09T16:46:07.009136Z","end":"2026-07-09T16:46:08.074773Z","steps":["trace[2057653424] 'process raft request' (duration: 1.064666634s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:08.074953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:07.009122Z","time spent":"1.065754771s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:46:08.083172Z","caller":"traceutil/trace.go:171","msg":"trace[429288567] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"1.073993723s","start":"2026-07-09T16:46:07.009161Z","end":"2026-07-09T16:46:08.083154Z","steps":["trace[429288567] 'process raft request' (duration: 1.07386784s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:08.083315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:07.009147Z","time spent":"1.074078125s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:46:08.083436Z","caller":"traceutil/trace.go:171","msg":"trace[1735265582] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"1.074045993s","start":"2026-07-09T16:46:07.009372Z","end":"2026-07-09T16:46:08.083418Z","steps":["trace[1735265582] 'process raft request' (duration: 1.073755796s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:08.083489Z","caller":"traceutil/trace.go:171","msg":"trace[1873468200] linearizableReadLoop","detail":"{readStateIndex:1276; appliedIndex:1270; }","duration":"1.013567511s","start":"2026-07-09T16:46:07.06991Z","end":"2026-07-09T16:46:08.083478Z","steps":["trace[1873468200] 'read index received' (duration: 1.003970805s)","trace[1873468200] 'applied index is now lower than readState.Index' (duration: 9.594306ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:08.083526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:07.009365Z","time spent":"1.074108966s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:46:08.083594Z","caller":"traceutil/trace.go:171","msg":"trace[1032962176] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"316.294525ms","start":"2026-07-09T16:46:07.76729Z","end":"2026-07-09T16:46:08.083585Z","steps":["trace[1032962176] 'process raft request' (duration: 316.158082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:08.083623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013718455s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:46:08.083648Z","caller":"traceutil/trace.go:171","msg":"trace[415398345] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1221; }","duration":"1.013787427s","start":"2026-07-09T16:46:07.069854Z","end":"2026-07-09T16:46:08.083641Z","steps":["trace[415398345] 'agreement among raft nodes before linearized reading' (duration: 1.013741326s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:08.083664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:07.767266Z","time spent":"316.350287ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:08.083671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:07.069835Z","time spent":"1.013828707s","remote":"127.0.0.1:48718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:46:08.083786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.254004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:08.083804Z","caller":"traceutil/trace.go:171","msg":"trace[1062948131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1221; }","duration":"858.307445ms","start":"2026-07-09T16:46:07.225491Z","end":"2026-07-09T16:46:08.083799Z","steps":["trace[1062948131] 'agreement among raft nodes before linearized reading' (duration: 858.278174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:08.083818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:07.225468Z","time spent":"858.346796ms","remote":"127.0.0.1:48370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T16:46:08.083879Z","caller":"traceutil/trace.go:171","msg":"trace[709158864] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"1.073706045s","start":"2026-07-09T16:46:07.010164Z","end":"2026-07-09T16:46:08.08387Z","steps":["trace[709158864] 'process raft request' (duration: 1.073253144s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:08.083923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:07.010135Z","time spent":"1.073762837s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:08.083952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.980762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:46:08.084035Z","caller":"traceutil/trace.go:171","msg":"trace[1693897096] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1221; }","duration":"609.133166ms","start":"2026-07-09T16:46:07.474881Z","end":"2026-07-09T16:46:08.084014Z","steps":["trace[1693897096] 'agreement among raft nodes before linearized reading' (duration: 608.945451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:08.084077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:07.474858Z","time spent":"609.209677ms","remote":"127.0.0.1:36152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-07-09T16:46:08.084378Z","caller":"traceutil/trace.go:171","msg":"trace[1833659151] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"1.074363171s","start":"2026-07-09T16:46:07.009998Z","end":"2026-07-09T16:46:08.084361Z","steps":["trace[1833659151] 'process raft request' (duration: 1.073347557s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:08.084471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:07.00999Z","time spent":"1.074425063s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:46:12.3193Z","caller":"traceutil/trace.go:171","msg":"trace[350929191] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"188.729768ms","start":"2026-07-09T16:46:12.130552Z","end":"2026-07-09T16:46:12.319282Z","steps":["trace[350929191] 'process raft request' (duration: 188.614495ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:12.380647Z","caller":"traceutil/trace.go:171","msg":"trace[827861796] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1286; }","duration":"217.077883ms","start":"2026-07-09T16:46:12.163546Z","end":"2026-07-09T16:46:12.380624Z","steps":["trace[827861796] 'read index received' (duration: 155.635316ms)","trace[827861796] 'applied index is now lower than readState.Index' (duration: 61.441597ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:12.380996Z","caller":"traceutil/trace.go:171","msg":"trace[1087183062] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"249.490678ms","start":"2026-07-09T16:46:12.131491Z","end":"2026-07-09T16:46:12.380982Z","steps":["trace[1087183062] 'process raft request' (duration: 248.951695ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:12.381198Z","caller":"traceutil/trace.go:171","msg":"trace[294594890] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"249.558229ms","start":"2026-07-09T16:46:12.13163Z","end":"2026-07-09T16:46:12.381188Z","steps":["trace[294594890] 'process raft request' (duration: 248.920493ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:12.381319Z","caller":"traceutil/trace.go:171","msg":"trace[1236590854] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"249.045637ms","start":"2026-07-09T16:46:12.132264Z","end":"2026-07-09T16:46:12.38131Z","steps":["trace[1236590854] 'process raft request' (duration: 248.324369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:12.381473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.905073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:46:12.381521Z","caller":"traceutil/trace.go:171","msg":"trace[669469744] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1234; }","duration":"217.993976ms","start":"2026-07-09T16:46:12.163516Z","end":"2026-07-09T16:46:12.38151Z","steps":["trace[669469744] 'agreement among raft nodes before linearized reading' (duration: 217.903044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:12.381686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.848211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:12.381712Z","caller":"traceutil/trace.go:171","msg":"trace[1237896184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1234; }","duration":"157.903591ms","start":"2026-07-09T16:46:12.223801Z","end":"2026-07-09T16:46:12.381704Z","steps":["trace[1237896184] 'agreement among raft nodes before linearized reading' (duration: 157.86306ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:20.502476Z","caller":"traceutil/trace.go:171","msg":"trace[388148404] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1312; }","duration":"277.015722ms","start":"2026-07-09T16:46:20.225439Z","end":"2026-07-09T16:46:20.502455Z","steps":["trace[388148404] 'read index received' (duration: 146.793209ms)","trace[388148404] 'applied index is now lower than readState.Index' (duration: 130.221903ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:20.502561Z","caller":"traceutil/trace.go:171","msg":"trace[1550951285] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"338.474509ms","start":"2026-07-09T16:46:20.164057Z","end":"2026-07-09T16:46:20.502532Z","steps":["trace[1550951285] 'process raft request' (duration: 320.547769ms)","trace[1550951285] 'compare' (duration: 17.730895ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:20.502603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.143225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:20.502628Z","caller":"traceutil/trace.go:171","msg":"trace[850348908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1256; }","duration":"277.223967ms","start":"2026-07-09T16:46:20.225398Z","end":"2026-07-09T16:46:20.502622Z","steps":["trace[850348908] 'agreement among raft nodes before linearized reading' (duration: 277.160835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:20.502697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:20.164039Z","time spent":"338.588862ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:46:20.528199Z","caller":"traceutil/trace.go:171","msg":"trace[109194721] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"154.392195ms","start":"2026-07-09T16:46:20.373779Z","end":"2026-07-09T16:46:20.528171Z","steps":["trace[109194721] 'process raft request' (duration: 154.257442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:20.528268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.787078ms","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-09T16:46:20.528314Z","caller":"traceutil/trace.go:171","msg":"trace[1602974007] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1257; }","duration":"179.891621ms","start":"2026-07-09T16:46:20.348411Z","end":"2026-07-09T16:46:20.528303Z","steps":["trace[1602974007] 'agreement among raft nodes before linearized reading' (duration: 179.739037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:20.528342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.979716ms","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-09T16:46:20.528365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.17747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T16:46:20.528391Z","caller":"traceutil/trace.go:171","msg":"trace[944670221] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1257; }","duration":"112.098408ms","start":"2026-07-09T16:46:20.416282Z","end":"2026-07-09T16:46:20.52838Z","steps":["trace[944670221] 'agreement among raft nodes before linearized reading' (duration: 111.926694ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:20.528443Z","caller":"traceutil/trace.go:171","msg":"trace[1624582270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1257; }","duration":"112.294294ms","start":"2026-07-09T16:46:20.416131Z","end":"2026-07-09T16:46:20.528425Z","steps":["trace[1624582270] 'agreement among raft nodes before linearized reading' (duration: 112.052988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:20.528472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.824855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T16:46:20.528562Z","caller":"traceutil/trace.go:171","msg":"trace[911963504] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1257; }","duration":"116.969148ms","start":"2026-07-09T16:46:20.411569Z","end":"2026-07-09T16:46:20.528538Z","steps":["trace[911963504] 'agreement among raft nodes before linearized reading' (duration: 116.729562ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:21.100063Z","caller":"traceutil/trace.go:171","msg":"trace[569714478] linearizableReadLoop","detail":"{readStateIndex:1319; appliedIndex:1316; }","duration":"553.86065ms","start":"2026-07-09T16:46:20.546175Z","end":"2026-07-09T16:46:21.100036Z","steps":["trace[569714478] 'read index received' (duration: 472.985377ms)","trace[569714478] 'applied index is now lower than readState.Index' (duration: 80.874203ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:21.100136Z","caller":"traceutil/trace.go:171","msg":"trace[1202070905] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"568.046578ms","start":"2026-07-09T16:46:20.53205Z","end":"2026-07-09T16:46:21.100096Z","steps":["trace[1202070905] 'process raft request' (duration: 487.070893ms)","trace[1202070905] 'compare' (duration: 80.695268ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:21.100198Z","caller":"traceutil/trace.go:171","msg":"trace[291473748] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"567.942256ms","start":"2026-07-09T16:46:20.532212Z","end":"2026-07-09T16:46:21.100154Z","steps":["trace[291473748] 'process raft request' (duration: 567.739651ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:21.100215Z","caller":"traceutil/trace.go:171","msg":"trace[1464704229] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"566.849298ms","start":"2026-07-09T16:46:20.533334Z","end":"2026-07-09T16:46:21.100184Z","steps":["trace[1464704229] 'process raft request' (duration: 566.659624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:21.100267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.070965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-07-09T16:46:21.100305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:20.532036Z","time spent":"568.181621ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:21.100349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:20.532187Z","time spent":"568.088119ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:21.100389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:20.533312Z","time spent":"566.974942ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:46:21.100322Z","caller":"traceutil/trace.go:171","msg":"trace[1767094123] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1261; }","duration":"554.182378ms","start":"2026-07-09T16:46:20.546128Z","end":"2026-07-09T16:46:21.10031Z","steps":["trace[1767094123] 'agreement among raft nodes before linearized reading' (duration: 554.057755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:21.100474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:20.546108Z","time spent":"554.355732ms","remote":"127.0.0.1:48608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-07-09T16:46:23.04986Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198697973035809782,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T16:46:23.45873Z","caller":"traceutil/trace.go:171","msg":"trace[581088051] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"948.867625ms","start":"2026-07-09T16:46:22.509838Z","end":"2026-07-09T16:46:23.458706Z","steps":["trace[581088051] 'process raft request' (duration: 948.64769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:23.4589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:22.509826Z","time spent":"948.987768ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:24.543757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.062264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:46:24.543961Z","caller":"traceutil/trace.go:171","msg":"trace[1421289039] linearizableReadLoop","detail":"{readStateIndex:1322; appliedIndex:1320; }","duration":"1.995049756s","start":"2026-07-09T16:46:22.548895Z","end":"2026-07-09T16:46:24.543944Z","steps":["trace[1421289039] 'read index received' (duration: 909.570211ms)","trace[1421289039] 'applied index is now lower than readState.Index' (duration: 1.085478655s)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:24.544019Z","caller":"traceutil/trace.go:171","msg":"trace[394006523] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"1.7822781s","start":"2026-07-09T16:46:22.761714Z","end":"2026-07-09T16:46:24.543992Z","steps":["trace[394006523] 'process raft request' (duration: 1.402901358s)","trace[394006523] 'compare' (duration: 378.847659ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:24.544122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.827876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:24.544152Z","caller":"traceutil/trace.go:171","msg":"trace[1427280823] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1265; }","duration":"860.901208ms","start":"2026-07-09T16:46:23.683242Z","end":"2026-07-09T16:46:24.544143Z","steps":["trace[1427280823] 'agreement among raft nodes before linearized reading' (duration: 860.843127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:24.544149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:22.761679Z","time spent":"1.782411893s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:24.544188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:23.68322Z","time spent":"860.95766ms","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:46:24.544223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.439476164s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-09T16:46:24.544297Z","caller":"traceutil/trace.go:171","msg":"trace[1774294087] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1265; }","duration":"1.439603278s","start":"2026-07-09T16:46:23.104677Z","end":"2026-07-09T16:46:24.54428Z","steps":["trace[1774294087] 'agreement among raft nodes before linearized reading' (duration: 1.439405043s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:24.544344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:23.104663Z","time spent":"1.439670369s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-09T16:46:24.544053Z","caller":"traceutil/trace.go:171","msg":"trace[1358750522] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"1.346431503s","start":"2026-07-09T16:46:23.197609Z","end":"2026-07-09T16:46:24.544041Z","steps":["trace[1358750522] 'process raft request' (duration: 1.346251428s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:24.54443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:23.197592Z","time spent":"1.346798412s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:24.544457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.729066ms","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-09T16:46:24.544522Z","caller":"traceutil/trace.go:171","msg":"trace[54296591] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1265; }","duration":"639.836218ms","start":"2026-07-09T16:46:23.90467Z","end":"2026-07-09T16:46:24.544506Z","steps":["trace[54296591] 'agreement among raft nodes before linearized reading' (duration: 639.721885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:24.544546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.318740444s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:24.544577Z","caller":"traceutil/trace.go:171","msg":"trace[1487065884] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1265; }","duration":"1.318797816s","start":"2026-07-09T16:46:23.22577Z","end":"2026-07-09T16:46:24.544568Z","steps":["trace[1487065884] 'agreement among raft nodes before linearized reading' (duration: 1.318748374s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:24.544584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:23.904648Z","time spent":"639.917731ms","remote":"127.0.0.1:48642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:46:24.544613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:23.225758Z","time spent":"1.318849067s","remote":"127.0.0.1:48370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:46:24.544656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.995747463s","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-09T16:46:24.544726Z","caller":"traceutil/trace.go:171","msg":"trace[1182364181] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1265; }","duration":"1.995852106s","start":"2026-07-09T16:46:22.548858Z","end":"2026-07-09T16:46:24.54471Z","steps":["trace[1182364181] 'agreement among raft nodes before linearized reading' (duration: 1.995665312s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:24.544766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:22.548843Z","time spent":"1.995914217s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T16:46:24.54486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.438717306s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T16:46:24.544899Z","caller":"traceutil/trace.go:171","msg":"trace[1333663547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1265; }","duration":"1.438784028s","start":"2026-07-09T16:46:23.106106Z","end":"2026-07-09T16:46:24.544889Z","steps":["trace[1333663547] 'agreement among raft nodes before linearized reading' (duration: 1.438653004s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:24.544932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:23.10609Z","time spent":"1.438835858s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T16:46:24.545066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.440341836s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T16:46:24.545113Z","caller":"traceutil/trace.go:171","msg":"trace[1595633252] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1265; }","duration":"1.440424778s","start":"2026-07-09T16:46:23.104676Z","end":"2026-07-09T16:46:24.545101Z","steps":["trace[1595633252] 'agreement among raft nodes before linearized reading' (duration: 1.440290435s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:24.545148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:23.104652Z","time spent":"1.44048942s","remote":"127.0.0.1:48604","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-09T16:46:24.798701Z","caller":"traceutil/trace.go:171","msg":"trace[1456804853] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"249.496487ms","start":"2026-07-09T16:46:24.549174Z","end":"2026-07-09T16:46:24.79867Z","steps":["trace[1456804853] 'process raft request' (duration: 171.338231ms)","trace[1456804853] 'compare' (duration: 78.063134ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:25.15093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.447676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:46:25.15119Z","caller":"traceutil/trace.go:171","msg":"trace[600503659] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1323; }","duration":"597.361066ms","start":"2026-07-09T16:46:24.553804Z","end":"2026-07-09T16:46:25.151165Z","steps":["trace[600503659] 'read index received' (duration: 166.720178ms)","trace[600503659] 'applied index is now lower than readState.Index' (duration: 430.639798ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:25.151218Z","caller":"traceutil/trace.go:171","msg":"trace[916239133] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"601.148369ms","start":"2026-07-09T16:46:24.550057Z","end":"2026-07-09T16:46:25.151205Z","steps":["trace[916239133] 'process raft request' (duration: 600.969204ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:25.151309Z","caller":"traceutil/trace.go:171","msg":"trace[2007317690] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"596.763061ms","start":"2026-07-09T16:46:24.554533Z","end":"2026-07-09T16:46:25.151296Z","steps":["trace[2007317690] 'process raft request' (duration: 596.581327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:25.15132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:24.550051Z","time spent":"601.21221ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:46:25.151216Z","caller":"traceutil/trace.go:171","msg":"trace[1204210020] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"601.267131ms","start":"2026-07-09T16:46:24.549921Z","end":"2026-07-09T16:46:25.151189Z","steps":["trace[1204210020] 'process raft request' (duration: 488.476517ms)","trace[1204210020] 'compare' (duration: 112.18786ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:25.151383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:24.554523Z","time spent":"596.816002ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:25.151558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.916558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:46:25.151609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.814007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:25.151598Z","caller":"traceutil/trace.go:171","msg":"trace[1711096258] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1269; }","duration":"422.98721ms","start":"2026-07-09T16:46:24.728601Z","end":"2026-07-09T16:46:25.151588Z","steps":["trace[1711096258] 'agreement among raft nodes before linearized reading' (duration: 422.920759ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:25.151638Z","caller":"traceutil/trace.go:171","msg":"trace[277506309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1269; }","duration":"597.842237ms","start":"2026-07-09T16:46:24.553789Z","end":"2026-07-09T16:46:25.151631Z","steps":["trace[277506309] 'agreement among raft nodes before linearized reading' (duration: 597.801986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:25.15165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:24.728586Z","time spent":"423.054391ms","remote":"127.0.0.1:48570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:46:25.151659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:24.553743Z","time spent":"597.909919ms","remote":"127.0.0.1:48370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:46:25.151449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:24.549911Z","time spent":"601.488907ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:26.285824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.879751ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-09T16:46:26.285968Z","caller":"traceutil/trace.go:171","msg":"trace[124490894] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1327; }","duration":"946.341943ms","start":"2026-07-09T16:46:25.339613Z","end":"2026-07-09T16:46:26.285955Z","steps":["trace[124490894] 'read index received' (duration: 29.308248ms)","trace[124490894] 'applied index is now lower than readState.Index' (duration: 917.032715ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:26.286323Z","caller":"traceutil/trace.go:171","msg":"trace[704451519] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"714.9486ms","start":"2026-07-09T16:46:25.571328Z","end":"2026-07-09T16:46:26.286276Z","steps":["trace[704451519] 'process raft request' (duration: 714.569691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:26.286515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:25.571295Z","time spent":"715.143435ms","remote":"127.0.0.1:48436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":839,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:46:26.286346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.692111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:46:26.286836Z","caller":"traceutil/trace.go:171","msg":"trace[1512316088] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1269; }","duration":"947.246995ms","start":"2026-07-09T16:46:25.339571Z","end":"2026-07-09T16:46:26.286818Z","steps":["trace[1512316088] 'agreement among raft nodes before linearized reading' (duration: 946.563448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:26.286903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:25.339505Z","time spent":"947.370599ms","remote":"127.0.0.1:48674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":70,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:46:26.435262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.773694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:26.435352Z","caller":"traceutil/trace.go:171","msg":"trace[779010207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1270; }","duration":"209.903497ms","start":"2026-07-09T16:46:26.225423Z","end":"2026-07-09T16:46:26.435327Z","steps":["trace[779010207] 'agreement among raft nodes before linearized reading' (duration: 60.873532ms)","trace[779010207] 'range keys from in-memory index tree' (duration: 148.901591ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:26.435692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.371172ms","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-09T16:46:26.43583Z","caller":"traceutil/trace.go:171","msg":"trace[1456222489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1270; }","duration":"972.468634ms","start":"2026-07-09T16:46:25.463277Z","end":"2026-07-09T16:46:26.435745Z","steps":["trace[1456222489] 'agreement among raft nodes before linearized reading' (duration: 822.998549ms)","trace[1456222489] 'range keys from in-memory index tree' (duration: 149.189788ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:26.435866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:25.463262Z","time spent":"972.594488ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-09T16:46:26.949134Z","caller":"traceutil/trace.go:171","msg":"trace[86314181] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1330; }","duration":"201.677005ms","start":"2026-07-09T16:46:26.747439Z","end":"2026-07-09T16:46:26.949116Z","steps":["trace[86314181] 'read index received' (duration: 201.519031ms)","trace[86314181] 'applied index is now lower than readState.Index' (duration: 157.264µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:26.949212Z","caller":"traceutil/trace.go:171","msg":"trace[1380203776] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"399.72726ms","start":"2026-07-09T16:46:26.549445Z","end":"2026-07-09T16:46:26.949172Z","steps":["trace[1380203776] 'process raft request' (duration: 399.527965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:26.94927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.824459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:26.949297Z","caller":"traceutil/trace.go:171","msg":"trace[1561366612] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1272; }","duration":"201.90831ms","start":"2026-07-09T16:46:26.747381Z","end":"2026-07-09T16:46:26.94929Z","steps":["trace[1561366612] 'agreement among raft nodes before linearized reading' (duration: 201.836128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:26.94937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:26.549421Z","time spent":"399.862334ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:26.949418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.992513ms","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-09T16:46:26.949496Z","caller":"traceutil/trace.go:171","msg":"trace[638986184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1272; }","duration":"147.126967ms","start":"2026-07-09T16:46:26.802352Z","end":"2026-07-09T16:46:26.949479Z","steps":["trace[638986184] 'agreement among raft nodes before linearized reading' (duration: 146.899201ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:27.285861Z","caller":"traceutil/trace.go:171","msg":"trace[1676991788] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1331; }","duration":"270.075102ms","start":"2026-07-09T16:46:27.015753Z","end":"2026-07-09T16:46:27.285828Z","steps":["trace[1676991788] 'read index received' (duration: 180.595628ms)","trace[1676991788] 'applied index is now lower than readState.Index' (duration: 89.478154ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:27.286207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.198243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-07-09T16:46:27.286222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.430031ms","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":"info","ts":"2026-07-09T16:46:27.286255Z","caller":"traceutil/trace.go:171","msg":"trace[1883489121] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1273; }","duration":"130.287105ms","start":"2026-07-09T16:46:27.155957Z","end":"2026-07-09T16:46:27.286244Z","steps":["trace[1883489121] 'agreement among raft nodes before linearized reading' (duration: 130.144062ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:27.286299Z","caller":"traceutil/trace.go:171","msg":"trace[1420118246] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1273; }","duration":"270.519453ms","start":"2026-07-09T16:46:27.015738Z","end":"2026-07-09T16:46:27.286258Z","steps":["trace[1420118246] 'agreement among raft nodes before linearized reading' (duration: 270.228076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:27.28661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.276315ms","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-09T16:46:27.286662Z","caller":"traceutil/trace.go:171","msg":"trace[339526598] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1273; }","duration":"130.329296ms","start":"2026-07-09T16:46:27.156322Z","end":"2026-07-09T16:46:27.286652Z","steps":["trace[339526598] 'agreement among raft nodes before linearized reading' (duration: 130.222463ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:27.286642Z","caller":"traceutil/trace.go:171","msg":"trace[1586567137] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"333.605879ms","start":"2026-07-09T16:46:26.953017Z","end":"2026-07-09T16:46:27.286623Z","steps":["trace[1586567137] 'process raft request' (duration: 243.10939ms)","trace[1586567137] 'compare' (duration: 88.137491ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:27.286791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.468069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-07-09T16:46:27.286789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:26.953001Z","time spent":"333.704642ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:46:27.286822Z","caller":"traceutil/trace.go:171","msg":"trace[1454197473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1273; }","duration":"130.52528ms","start":"2026-07-09T16:46:27.156289Z","end":"2026-07-09T16:46:27.286814Z","steps":["trace[1454197473] 'agreement among raft nodes before linearized reading' (duration: 130.441678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:28.17333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.983935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:46:28.173557Z","caller":"traceutil/trace.go:171","msg":"trace[1981781822] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1333; }","duration":"705.306903ms","start":"2026-07-09T16:46:27.468205Z","end":"2026-07-09T16:46:28.173512Z","steps":["trace[1981781822] 'read index received' (duration: 466.99658ms)","trace[1981781822] 'applied index is now lower than readState.Index' (duration: 238.308453ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:28.173732Z","caller":"traceutil/trace.go:171","msg":"trace[1524285840] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"878.676645ms","start":"2026-07-09T16:46:27.295034Z","end":"2026-07-09T16:46:28.173711Z","steps":["trace[1524285840] 'process raft request' (duration: 640.221418ms)","trace[1524285840] 'compare' (duration: 237.801541ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:28.173766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.344664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:28.173795Z","caller":"traceutil/trace.go:171","msg":"trace[522345610] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"878.622853ms","start":"2026-07-09T16:46:27.295158Z","end":"2026-07-09T16:46:28.173781Z","steps":["trace[522345610] 'process raft request' (duration: 878.260844ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:28.173827Z","caller":"traceutil/trace.go:171","msg":"trace[447526759] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1276; }","duration":"488.431695ms","start":"2026-07-09T16:46:27.685376Z","end":"2026-07-09T16:46:28.173808Z","steps":["trace[447526759] 'agreement among raft nodes before linearized reading' (duration: 488.321563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:28.17384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:27.295023Z","time spent":"878.775077ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:28.173871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:27.68536Z","time spent":"488.500478ms","remote":"127.0.0.1:48580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:46:28.173877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:27.295132Z","time spent":"878.693685ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:28.173737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.520639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:28.173972Z","caller":"traceutil/trace.go:171","msg":"trace[1369719237] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1276; }","duration":"705.811986ms","start":"2026-07-09T16:46:27.468151Z","end":"2026-07-09T16:46:28.173963Z","steps":["trace[1369719237] 'agreement among raft nodes before linearized reading' (duration: 705.526749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:28.173956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.895103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-07-09T16:46:28.173978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.600535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:46:28.173994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:27.468129Z","time spent":"705.858767ms","remote":"127.0.0.1:38506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-07-09T16:46:28.174025Z","caller":"traceutil/trace.go:171","msg":"trace[1551555894] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1276; }","duration":"467.680577ms","start":"2026-07-09T16:46:27.706332Z","end":"2026-07-09T16:46:28.174012Z","steps":["trace[1551555894] 'agreement among raft nodes before linearized reading' (duration: 467.602385ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:28.174031Z","caller":"traceutil/trace.go:171","msg":"trace[383914095] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1276; }","duration":"299.976885ms","start":"2026-07-09T16:46:27.874032Z","end":"2026-07-09T16:46:28.174008Z","steps":["trace[383914095] 'agreement among raft nodes before linearized reading' (duration: 299.819571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:28.17404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.316645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:120829"} {"level":"warn","ts":"2026-07-09T16:46:28.174059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:27.706317Z","time spent":"467.732138ms","remote":"127.0.0.1:38620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:46:28.174075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:27.873973Z","time spent":"300.092748ms","remote":"127.0.0.1:48424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-07-09T16:46:28.17408Z","caller":"traceutil/trace.go:171","msg":"trace[385702001] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1276; }","duration":"298.397977ms","start":"2026-07-09T16:46:27.875672Z","end":"2026-07-09T16:46:28.17407Z","steps":["trace[385702001] 'agreement among raft nodes before linearized reading' (duration: 298.188972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:28.631807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.078732ms","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-09T16:46:28.631889Z","caller":"traceutil/trace.go:171","msg":"trace[1501758138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1276; }","duration":"102.212236ms","start":"2026-07-09T16:46:28.529658Z","end":"2026-07-09T16:46:28.63187Z","steps":["trace[1501758138] 'range keys from in-memory index tree' (duration: 101.888768ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:28.788954Z","caller":"traceutil/trace.go:171","msg":"trace[1324846092] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"241.632124ms","start":"2026-07-09T16:46:28.547292Z","end":"2026-07-09T16:46:28.788924Z","steps":["trace[1324846092] 'process raft request' (duration: 241.414928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:29.711555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.869439ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:46:29.711626Z","caller":"traceutil/trace.go:171","msg":"trace[1222796726] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"1.075807447s","start":"2026-07-09T16:46:28.635805Z","end":"2026-07-09T16:46:29.711613Z","steps":["trace[1222796726] 'process raft request' (duration: 641.832286ms)","trace[1222796726] 'compare' (duration: 433.803987ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:29.711662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:28.63578Z","time spent":"1.075864538s","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:46:29.810499Z","caller":"traceutil/trace.go:171","msg":"trace[789229504] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1336; }","duration":"587.411663ms","start":"2026-07-09T16:46:29.223042Z","end":"2026-07-09T16:46:29.810453Z","steps":["trace[789229504] 'read index received' (duration: 54.64278ms)","trace[789229504] 'applied index is now lower than readState.Index' (duration: 532.767553ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:29.810652Z","caller":"traceutil/trace.go:171","msg":"trace[754692493] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"856.251534ms","start":"2026-07-09T16:46:28.954385Z","end":"2026-07-09T16:46:29.810637Z","steps":["trace[754692493] 'process raft request' (duration: 855.914916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:29.810768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:28.954363Z","time spent":"856.326566ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:29.810952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.504003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:29.811039Z","caller":"traceutil/trace.go:171","msg":"trace[406841117] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1279; }","duration":"290.681968ms","start":"2026-07-09T16:46:29.520338Z","end":"2026-07-09T16:46:29.81102Z","steps":["trace[406841117] 'agreement among raft nodes before linearized reading' (duration: 290.499843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:29.811052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.877923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-09T16:46:29.811098Z","caller":"traceutil/trace.go:171","msg":"trace[379042521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1279; }","duration":"496.944955ms","start":"2026-07-09T16:46:29.314133Z","end":"2026-07-09T16:46:29.811078Z","steps":["trace[379042521] 'agreement among raft nodes before linearized reading' (duration: 496.877893ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:29.81113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:29.314112Z","time spent":"497.011236ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T16:46:29.811141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.095379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:29.81118Z","caller":"traceutil/trace.go:171","msg":"trace[119654190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1279; }","duration":"588.175971ms","start":"2026-07-09T16:46:29.222994Z","end":"2026-07-09T16:46:29.81117Z","steps":["trace[119654190] 'agreement among raft nodes before linearized reading' (duration: 588.11223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:29.811215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:29.222958Z","time spent":"588.250623ms","remote":"127.0.0.1:48370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:46:29.810975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.304773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T16:46:29.811295Z","caller":"traceutil/trace.go:171","msg":"trace[1905062142] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1279; }","duration":"519.672262ms","start":"2026-07-09T16:46:29.291606Z","end":"2026-07-09T16:46:29.811278Z","steps":["trace[1905062142] 'agreement among raft nodes before linearized reading' (duration: 519.227521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:29.811631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:29.291587Z","time spent":"519.985989ms","remote":"127.0.0.1:48604","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-09T16:46:30.387341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.730642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:30.387429Z","caller":"traceutil/trace.go:171","msg":"trace[1783666423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1279; }","duration":"574.856115ms","start":"2026-07-09T16:46:29.81255Z","end":"2026-07-09T16:46:30.387406Z","steps":["trace[1783666423] 'range keys from in-memory index tree' (duration: 574.65171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:30.38748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:29.812533Z","time spent":"574.933997ms","remote":"127.0.0.1:48356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:46:30.3875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.576096ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:46:30.387693Z","caller":"traceutil/trace.go:171","msg":"trace[202825299] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1338; }","duration":"226.672718ms","start":"2026-07-09T16:46:30.161003Z","end":"2026-07-09T16:46:30.387676Z","steps":["trace[202825299] 'read index received' (duration: 75.501µs)","trace[202825299] 'applied index is now lower than readState.Index' (duration: 226.596337ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:30.388027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.550923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-09T16:46:30.388031Z","caller":"traceutil/trace.go:171","msg":"trace[2019326282] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"572.244042ms","start":"2026-07-09T16:46:29.815749Z","end":"2026-07-09T16:46:30.387993Z","steps":["trace[2019326282] 'process raft request' (duration: 201.087731ms)","trace[2019326282] 'compare' (duration: 370.366031ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:30.388066Z","caller":"traceutil/trace.go:171","msg":"trace[998052238] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1281; }","duration":"210.616864ms","start":"2026-07-09T16:46:30.177439Z","end":"2026-07-09T16:46:30.388056Z","steps":["trace[998052238] 'agreement among raft nodes before linearized reading' (duration: 210.490871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:30.388072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.051238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.224\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-09T16:46:30.388146Z","caller":"traceutil/trace.go:171","msg":"trace[84543908] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"572.337624ms","start":"2026-07-09T16:46:29.815787Z","end":"2026-07-09T16:46:30.388125Z","steps":["trace[84543908] 'process raft request' (duration: 571.811131ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:30.38816Z","caller":"traceutil/trace.go:171","msg":"trace[1024968889] range","detail":"{range_begin:/registry/masterleases/199.19.213.224; range_end:; response_count:1; response_revision:1281; }","duration":"227.187431ms","start":"2026-07-09T16:46:30.16095Z","end":"2026-07-09T16:46:30.388138Z","steps":["trace[1024968889] 'agreement among raft nodes before linearized reading' (duration: 226.937275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:30.388197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:29.815729Z","time spent":"572.371044ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:30.388205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.767493ms","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-09T16:46:30.388255Z","caller":"traceutil/trace.go:171","msg":"trace[885471573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1281; }","duration":"209.843276ms","start":"2026-07-09T16:46:30.178398Z","end":"2026-07-09T16:46:30.388241Z","steps":["trace[885471573] 'agreement among raft nodes before linearized reading' (duration: 209.664901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:30.388264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:29.815768Z","time spent":"572.424265ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:46:30.742437Z","caller":"traceutil/trace.go:171","msg":"trace[1728416434] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"350.223407ms","start":"2026-07-09T16:46:30.392172Z","end":"2026-07-09T16:46:30.742395Z","steps":["trace[1728416434] 'process raft request' (duration: 349.94006ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:30.742505Z","caller":"traceutil/trace.go:171","msg":"trace[362159723] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"343.333658ms","start":"2026-07-09T16:46:30.399146Z","end":"2026-07-09T16:46:30.74248Z","steps":["trace[362159723] 'process raft request' (duration: 343.127973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:30.742628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:30.392152Z","time spent":"350.376511ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:46:30.742746Z","caller":"traceutil/trace.go:171","msg":"trace[324682516] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1340; }","duration":"342.455066ms","start":"2026-07-09T16:46:30.399868Z","end":"2026-07-09T16:46:30.742323Z","steps":["trace[324682516] 'read index received' (duration: 79.13471ms)","trace[324682516] 'applied index is now lower than readState.Index' (duration: 263.319336ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:30.742765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.890908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:30.742831Z","caller":"traceutil/trace.go:171","msg":"trace[1781957105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1283; }","duration":"342.992479ms","start":"2026-07-09T16:46:30.399826Z","end":"2026-07-09T16:46:30.742818Z","steps":["trace[1781957105] 'agreement among raft nodes before linearized reading' (duration: 342.873947ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:30.742864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:30.399814Z","time spent":"343.042181ms","remote":"127.0.0.1:48370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:46:30.743309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:30.399119Z","time spent":"343.518453ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:30.762805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.798566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:46:30.76289Z","caller":"traceutil/trace.go:171","msg":"trace[638462787] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1284; }","duration":"163.93825ms","start":"2026-07-09T16:46:30.598934Z","end":"2026-07-09T16:46:30.762872Z","steps":["trace[638462787] 'agreement among raft nodes before linearized reading' (duration: 163.758176ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:30.763157Z","caller":"traceutil/trace.go:171","msg":"trace[953479064] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"282.842355ms","start":"2026-07-09T16:46:30.480303Z","end":"2026-07-09T16:46:30.763145Z","steps":["trace[953479064] 'process raft request' (duration: 282.2481ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:33.970448Z","caller":"traceutil/trace.go:171","msg":"trace[1762753083] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"131.463354ms","start":"2026-07-09T16:46:33.838962Z","end":"2026-07-09T16:46:33.970425Z","steps":["trace[1762753083] 'process raft request' (duration: 130.383937ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:36.81478Z","caller":"traceutil/trace.go:171","msg":"trace[1110383907] linearizableReadLoop","detail":"{readStateIndex:1388; appliedIndex:1387; }","duration":"370.797882ms","start":"2026-07-09T16:46:36.443958Z","end":"2026-07-09T16:46:36.814756Z","steps":["trace[1110383907] 'read index received' (duration: 370.496405ms)","trace[1110383907] 'applied index is now lower than readState.Index' (duration: 300.387µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:36.814868Z","caller":"traceutil/trace.go:171","msg":"trace[1719941250] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"383.543154ms","start":"2026-07-09T16:46:36.431292Z","end":"2026-07-09T16:46:36.814835Z","steps":["trace[1719941250] 'process raft request' (duration: 383.250097ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:36.815007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.026077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-09T16:46:36.815037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:36.431194Z","time spent":"383.75594ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:46:36.815054Z","caller":"traceutil/trace.go:171","msg":"trace[2018825450] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1327; }","duration":"371.113469ms","start":"2026-07-09T16:46:36.44393Z","end":"2026-07-09T16:46:36.815044Z","steps":["trace[2018825450] 'agreement among raft nodes before linearized reading' (duration: 370.957475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:36.815093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:36.44392Z","time spent":"371.164981ms","remote":"127.0.0.1:48604","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-09T16:46:37.455083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.236553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:46:37.455163Z","caller":"traceutil/trace.go:171","msg":"trace[1207509762] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"636.252851ms","start":"2026-07-09T16:46:36.818896Z","end":"2026-07-09T16:46:37.455149Z","steps":["trace[1207509762] 'process raft request' (duration: 78.880975ms)","trace[1207509762] 'compare' (duration: 557.047768ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:37.455237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:36.818875Z","time spent":"636.308972ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:46:37.510157Z","caller":"traceutil/trace.go:171","msg":"trace[2145966539] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"690.993513ms","start":"2026-07-09T16:46:36.819139Z","end":"2026-07-09T16:46:37.510132Z","steps":["trace[2145966539] 'process raft request' (duration: 690.05805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:37.510328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:36.819123Z","time spent":"691.110955ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:37.510411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.313151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:37.51033Z","caller":"traceutil/trace.go:171","msg":"trace[144517884] linearizableReadLoop","detail":"{readStateIndex:1391; appliedIndex:1389; }","duration":"284.199868ms","start":"2026-07-09T16:46:37.226105Z","end":"2026-07-09T16:46:37.510305Z","steps":["trace[144517884] 'read index received' (duration: 283.04528ms)","trace[144517884] 'applied index is now lower than readState.Index' (duration: 1.152868ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:37.510467Z","caller":"traceutil/trace.go:171","msg":"trace[2031703971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1330; }","duration":"284.416664ms","start":"2026-07-09T16:46:37.226037Z","end":"2026-07-09T16:46:37.510453Z","steps":["trace[2031703971] 'agreement among raft nodes before linearized reading' (duration: 284.328792ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:37.51038Z","caller":"traceutil/trace.go:171","msg":"trace[396210340] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"691.123725ms","start":"2026-07-09T16:46:36.81923Z","end":"2026-07-09T16:46:37.510353Z","steps":["trace[396210340] 'process raft request' (duration: 690.830498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:37.510656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:36.819222Z","time spent":"691.325851ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:46:44.749138Z","caller":"traceutil/trace.go:171","msg":"trace[502221128] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"294.601304ms","start":"2026-07-09T16:46:44.454488Z","end":"2026-07-09T16:46:44.749089Z","steps":["trace[502221128] 'process raft request' (duration: 294.44558ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:48:08.569175Z","caller":"traceutil/trace.go:171","msg":"trace[1529863504] transaction","detail":"{read_only:false; response_revision:1776; number_of_response:1; }","duration":"121.695134ms","start":"2026-07-09T16:48:08.447456Z","end":"2026-07-09T16:48:08.569151Z","steps":["trace[1529863504] 'process raft request' (duration: 121.52047ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:49:35.247995Z","caller":"traceutil/trace.go:171","msg":"trace[1278748290] linearizableReadLoop","detail":"{readStateIndex:2269; appliedIndex:2268; }","duration":"108.906317ms","start":"2026-07-09T16:49:35.139063Z","end":"2026-07-09T16:49:35.247969Z","steps":["trace[1278748290] 'read index received' (duration: 105.401452ms)","trace[1278748290] 'applied index is now lower than readState.Index' (duration: 3.503845ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:49:35.248063Z","caller":"traceutil/trace.go:171","msg":"trace[1281406072] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"126.814456ms","start":"2026-07-09T16:49:35.121218Z","end":"2026-07-09T16:49:35.248032Z","steps":["trace[1281406072] 'process raft request' (duration: 123.340351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:49:35.248193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.117703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-07-09T16:49:35.248228Z","caller":"traceutil/trace.go:171","msg":"trace[1300536296] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2173; }","duration":"109.189345ms","start":"2026-07-09T16:49:35.139031Z","end":"2026-07-09T16:49:35.24822Z","steps":["trace[1300536296] 'agreement among raft nodes before linearized reading' (duration: 109.069042ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:49:36.627377Z","caller":"traceutil/trace.go:171","msg":"trace[123876714] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"150.006654ms","start":"2026-07-09T16:49:36.477344Z","end":"2026-07-09T16:49:36.627351Z","steps":["trace[123876714] 'process raft request' (duration: 122.035379ms)","trace[123876714] 'compare' (duration: 27.788081ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:50:47.196751Z","caller":"traceutil/trace.go:171","msg":"trace[1857172267] transaction","detail":"{read_only:false; response_revision:2520; number_of_response:1; }","duration":"158.066585ms","start":"2026-07-09T16:50:47.038651Z","end":"2026-07-09T16:50:47.196718Z","steps":["trace[1857172267] 'process raft request' (duration: 79.035802ms)","trace[1857172267] 'compare' (duration: 78.871499ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:50:53.700957Z","caller":"traceutil/trace.go:171","msg":"trace[167731931] transaction","detail":"{read_only:false; response_revision:2553; number_of_response:1; }","duration":"143.384216ms","start":"2026-07-09T16:50:53.557548Z","end":"2026-07-09T16:50:53.700932Z","steps":["trace[167731931] 'process raft request' (duration: 143.216402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:51:02.277233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.315209ms","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-09T16:51:02.277356Z","caller":"traceutil/trace.go:171","msg":"trace[823680527] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2597; }","duration":"146.506813ms","start":"2026-07-09T16:51:02.130825Z","end":"2026-07-09T16:51:02.277332Z","steps":["trace[823680527] 'range keys from in-memory index tree' (duration: 146.195985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:51:02.277388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.714881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T16:51:02.277497Z","caller":"traceutil/trace.go:171","msg":"trace[364019086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2597; }","duration":"140.852605ms","start":"2026-07-09T16:51:02.136619Z","end":"2026-07-09T16:51:02.277472Z","steps":["trace[364019086] 'range keys from in-memory index tree' (duration: 140.546978ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:51:02.389624Z","caller":"traceutil/trace.go:171","msg":"trace[591013481] linearizableReadLoop","detail":"{readStateIndex:2713; appliedIndex:2712; }","duration":"107.136081ms","start":"2026-07-09T16:51:02.28246Z","end":"2026-07-09T16:51:02.389596Z","steps":["trace[591013481] 'read index received' (duration: 106.975987ms)","trace[591013481] 'applied index is now lower than readState.Index' (duration: 158.674µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:51:02.38967Z","caller":"traceutil/trace.go:171","msg":"trace[1037891438] transaction","detail":"{read_only:false; response_revision:2598; number_of_response:1; }","duration":"108.415741ms","start":"2026-07-09T16:51:02.281218Z","end":"2026-07-09T16:51:02.389634Z","steps":["trace[1037891438] 'process raft request' (duration: 108.194776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:51:02.389758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.278614ms","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-09T16:51:02.389796Z","caller":"traceutil/trace.go:171","msg":"trace[194998308] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2598; }","duration":"107.374066ms","start":"2026-07-09T16:51:02.28241Z","end":"2026-07-09T16:51:02.389784Z","steps":["trace[194998308] 'agreement among raft nodes before linearized reading' (duration: 107.291094ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:51:03.154478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.623213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-09T16:51:03.154551Z","caller":"traceutil/trace.go:171","msg":"trace[765358791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2598; }","duration":"271.770386ms","start":"2026-07-09T16:51:02.882764Z","end":"2026-07-09T16:51:03.154534Z","steps":["trace[765358791] 'range keys from in-memory index tree' (duration: 271.478709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:51:03.154744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.918985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-09T16:51:03.154799Z","caller":"traceutil/trace.go:171","msg":"trace[2098799492] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2598; }","duration":"234.975046ms","start":"2026-07-09T16:51:02.919811Z","end":"2026-07-09T16:51:03.154786Z","steps":["trace[2098799492] 'range keys from in-memory index tree' (duration: 234.772651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:51:03.155059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.888087ms","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-09T16:51:03.155083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.92052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:51:03.155115Z","caller":"traceutil/trace.go:171","msg":"trace[888153507] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2598; }","duration":"273.97863ms","start":"2026-07-09T16:51:02.881121Z","end":"2026-07-09T16:51:03.155099Z","steps":["trace[888153507] 'range keys from in-memory index tree' (duration: 273.579731ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:51:03.155129Z","caller":"traceutil/trace.go:171","msg":"trace[1011397539] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2598; }","duration":"483.014912ms","start":"2026-07-09T16:51:02.6721Z","end":"2026-07-09T16:51:03.155115Z","steps":["trace[1011397539] 'count revisions from in-memory index tree' (duration: 482.837358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:51:03.155162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:51:02.672077Z","time spent":"483.075284ms","remote":"127.0.0.1:48600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "}