{"level":"warn","ts":"2026-07-21T06:45:58.010528Z","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-21T06:45:58.010614Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.150: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.150:2380","--initial-cluster=instance=https://199.19.213.150:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.150:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.150: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-21T06:45:58.010678Z","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-21T06:45:58.010691Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.150:2380"]} {"level":"info","ts":"2026-07-21T06:45:58.010709Z","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-21T06:45:58.011328Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.150:2379"]} {"level":"info","ts":"2026-07-21T06:45:58.011396Z","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.150:2380"],"listen-peer-urls":["https://199.19.213.150:2380"],"advertise-client-urls":["https://199.19.213.150:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.150:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.150: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-21T06:45:58.014837Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.251044ms"} {"level":"info","ts":"2026-07-21T06:45:58.022089Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"dbd0ffa4f8c99812","cluster-id":"98aad832cd49bc30"} {"level":"info","ts":"2026-07-21T06:45:58.02221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 switched to configuration voters=()"} {"level":"info","ts":"2026-07-21T06:45:58.022245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became follower at term 0"} {"level":"info","ts":"2026-07-21T06:45:58.022256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft dbd0ffa4f8c99812 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-21T06:45:58.022264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became follower at term 1"} {"level":"info","ts":"2026-07-21T06:45:58.022301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 switched to configuration voters=(15839440973475715090)"} {"level":"warn","ts":"2026-07-21T06:45:58.028358Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-21T06:45:58.029885Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-21T06:45:58.039341Z","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-21T06:45:58.041882Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"dbd0ffa4f8c99812","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-21T06:45:58.046122Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"dbd0ffa4f8c99812","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-21T06:45:58.047734Z","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-21T06:45:58.047804Z","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-21T06:45:58.047815Z","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-21T06:45:58.049249Z","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-21T06:45:58.049446Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"dbd0ffa4f8c99812","initial-advertise-peer-urls":["https://199.19.213.150:2380"],"listen-peer-urls":["https://199.19.213.150:2380"],"advertise-client-urls":["https://199.19.213.150:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.150:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-21T06:45:58.049481Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-21T06:45:58.049589Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.150:2380"} {"level":"info","ts":"2026-07-21T06:45:58.049616Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.150:2380"} {"level":"info","ts":"2026-07-21T06:45:58.049733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 switched to configuration voters=(15839440973475715090)"} {"level":"info","ts":"2026-07-21T06:45:58.049869Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98aad832cd49bc30","local-member-id":"dbd0ffa4f8c99812","added-peer-id":"dbd0ffa4f8c99812","added-peer-peer-urls":["https://199.19.213.150:2380"]} {"level":"info","ts":"2026-07-21T06:45:58.822516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 is starting a new election at term 1"} {"level":"info","ts":"2026-07-21T06:45:58.822771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-21T06:45:58.822868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 received MsgPreVoteResp from dbd0ffa4f8c99812 at term 1"} {"level":"info","ts":"2026-07-21T06:45:58.823061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became candidate at term 2"} {"level":"info","ts":"2026-07-21T06:45:58.823199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 received MsgVoteResp from dbd0ffa4f8c99812 at term 2"} {"level":"info","ts":"2026-07-21T06:45:58.823366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became leader at term 2"} {"level":"info","ts":"2026-07-21T06:45:58.823441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: dbd0ffa4f8c99812 elected leader dbd0ffa4f8c99812 at term 2"} {"level":"info","ts":"2026-07-21T06:45:58.82476Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T06:45:58.82477Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"dbd0ffa4f8c99812","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.150:2379]}","request-path":"/0/members/dbd0ffa4f8c99812/attributes","cluster-id":"98aad832cd49bc30","publish-timeout":"7s"} {"level":"info","ts":"2026-07-21T06:45:58.825345Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T06:45:58.825887Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-21T06:45:58.826025Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-21T06:45:58.826931Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.150:2379"} {"level":"info","ts":"2026-07-21T06:45:58.827502Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:45:58.82864Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98aad832cd49bc30","local-member-id":"dbd0ffa4f8c99812","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:45:58.828734Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:45:58.828775Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:45:58.829283Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-21T06:46:32.268266Z","caller":"traceutil/trace.go:171","msg":"trace[1571932814] linearizableReadLoop","detail":"{readStateIndex:453; appliedIndex:452; }","duration":"169.081344ms","start":"2026-07-21T06:46:32.099155Z","end":"2026-07-21T06:46:32.268237Z","steps":["trace[1571932814] 'read index received' (duration: 168.96748ms)","trace[1571932814] 'applied index is now lower than readState.Index' (duration: 112.294µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:46:32.268291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:31.803108Z","time spent":"465.173114ms","remote":"127.0.0.1:39468","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-21T06:46:32.268729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.388793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-21T06:46:32.2688Z","caller":"traceutil/trace.go:171","msg":"trace[761835931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:437; }","duration":"169.720035ms","start":"2026-07-21T06:46:32.099069Z","end":"2026-07-21T06:46:32.268789Z","steps":["trace[761835931] 'agreement among raft nodes before linearized reading' (duration: 169.361883ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:46:32.598891Z","caller":"traceutil/trace.go:171","msg":"trace[979316162] linearizableReadLoop","detail":"{readStateIndex:455; appliedIndex:454; }","duration":"319.885142ms","start":"2026-07-21T06:46:32.278973Z","end":"2026-07-21T06:46:32.598858Z","steps":["trace[979316162] 'read index received' (duration: 319.694046ms)","trace[979316162] 'applied index is now lower than readState.Index' (duration: 189.916µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:46:32.599024Z","caller":"traceutil/trace.go:171","msg":"trace[309594732] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"323.687404ms","start":"2026-07-21T06:46:32.275284Z","end":"2026-07-21T06:46:32.598971Z","steps":["trace[309594732] 'process raft request' (duration: 323.400224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:32.599112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.10801ms","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-21T06:46:32.599424Z","caller":"traceutil/trace.go:171","msg":"trace[1369141728] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:439; }","duration":"320.465461ms","start":"2026-07-21T06:46:32.278936Z","end":"2026-07-21T06:46:32.599401Z","steps":["trace[1369141728] 'agreement among raft nodes before linearized reading' (duration: 320.053758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:32.599493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:32.278924Z","time spent":"320.550494ms","remote":"127.0.0.1:39578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-07-21T06:46:32.600023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:32.275252Z","time spent":"323.988013ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:46:38.973374Z","caller":"traceutil/trace.go:171","msg":"trace[149056737] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:579; }","duration":"322.018041ms","start":"2026-07-21T06:46:38.65133Z","end":"2026-07-21T06:46:38.973348Z","steps":["trace[149056737] 'read index received' (duration: 321.178314ms)","trace[149056737] 'applied index is now lower than readState.Index' (duration: 838.667µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:46:38.973447Z","caller":"traceutil/trace.go:171","msg":"trace[1727619459] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"960.744391ms","start":"2026-07-21T06:46:38.012672Z","end":"2026-07-21T06:46:38.973416Z","steps":["trace[1727619459] 'process raft request' (duration: 959.993757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:38.973614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.287158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-21T06:46:38.973635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:38.012654Z","time spent":"960.891616ms","remote":"127.0.0.1:39476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:46:38.973661Z","caller":"traceutil/trace.go:171","msg":"trace[497741684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:558; }","duration":"322.395052ms","start":"2026-07-21T06:46:38.651251Z","end":"2026-07-21T06:46:38.973646Z","steps":["trace[497741684] 'agreement among raft nodes before linearized reading' (duration: 322.193526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:38.973709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:38.651233Z","time spent":"322.460265ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T06:46:38.973634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.796552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-21T06:46:38.974004Z","caller":"traceutil/trace.go:171","msg":"trace[1403587519] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:558; }","duration":"237.181864ms","start":"2026-07-21T06:46:38.736808Z","end":"2026-07-21T06:46:38.97399Z","steps":["trace[1403587519] 'agreement among raft nodes before linearized reading' (duration: 236.684348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:38.974082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.665878ms","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-21T06:46:38.97414Z","caller":"traceutil/trace.go:171","msg":"trace[1093717001] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:558; }","duration":"152.756531ms","start":"2026-07-21T06:46:38.821371Z","end":"2026-07-21T06:46:38.974127Z","steps":["trace[1093717001] 'agreement among raft nodes before linearized reading' (duration: 152.633247ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:46:45.576542Z","caller":"traceutil/trace.go:171","msg":"trace[606691127] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"486.129446ms","start":"2026-07-21T06:46:45.090381Z","end":"2026-07-21T06:46:45.57651Z","steps":["trace[606691127] 'process raft request' (duration: 485.986991ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:46:45.576579Z","caller":"traceutil/trace.go:171","msg":"trace[2032015438] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:618; }","duration":"485.086853ms","start":"2026-07-21T06:46:45.091453Z","end":"2026-07-21T06:46:45.576539Z","steps":["trace[2032015438] 'read index received' (duration: 485.070602ms)","trace[2032015438] 'applied index is now lower than readState.Index' (duration: 13.351µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:46:45.576707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:45.090367Z","time spent":"486.26116ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:46:45.577009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.523817ms","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-21T06:46:45.57707Z","caller":"traceutil/trace.go:171","msg":"trace[647395121] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"485.64389ms","start":"2026-07-21T06:46:45.091412Z","end":"2026-07-21T06:46:45.577056Z","steps":["trace[647395121] 'agreement among raft nodes before linearized reading' (duration: 485.336841ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:45.577138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:45.091387Z","time spent":"485.742294ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-21T06:46:45.881769Z","caller":"traceutil/trace.go:171","msg":"trace[450404076] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"305.052016ms","start":"2026-07-21T06:46:45.576688Z","end":"2026-07-21T06:46:45.88174Z","steps":["trace[450404076] 'read index received' (duration: 303.817357ms)","trace[450404076] 'applied index is now lower than readState.Index' (duration: 1.233689ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:46:45.881951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.809033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T06:46:45.881995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.400428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-21T06:46:45.88202Z","caller":"traceutil/trace.go:171","msg":"trace[587462104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"712.914147ms","start":"2026-07-21T06:46:45.169087Z","end":"2026-07-21T06:46:45.882001Z","steps":["trace[587462104] 'agreement among raft nodes before linearized reading' (duration: 712.771842ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:46:45.882046Z","caller":"traceutil/trace.go:171","msg":"trace[1087094993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:596; }","duration":"790.491361ms","start":"2026-07-21T06:46:45.091541Z","end":"2026-07-21T06:46:45.882033Z","steps":["trace[1087094993] 'agreement among raft nodes before linearized reading' (duration: 790.309785ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:45.882059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:45.169071Z","time spent":"712.979058ms","remote":"127.0.0.1:39430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T06:46:45.882091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:45.091525Z","time spent":"790.555422ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-21T06:46:45.882191Z","caller":"traceutil/trace.go:171","msg":"trace[713993741] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"678.085282ms","start":"2026-07-21T06:46:45.20407Z","end":"2026-07-21T06:46:45.882156Z","steps":["trace[713993741] 'process raft request' (duration: 676.623905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:45.882256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.29104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-21T06:46:45.882318Z","caller":"traceutil/trace.go:171","msg":"trace[985284054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:596; }","duration":"778.388743ms","start":"2026-07-21T06:46:45.103912Z","end":"2026-07-21T06:46:45.882301Z","steps":["trace[985284054] 'agreement among raft nodes before linearized reading' (duration: 778.181357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:45.882345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:45.103896Z","time spent":"778.444965ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T06:46:45.882146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.211152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-d45kx\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-21T06:46:45.882462Z","caller":"traceutil/trace.go:171","msg":"trace[776143048] range","detail":"{range_begin:/registry/pods/kube-system/cilium-d45kx; range_end:; response_count:1; response_revision:596; }","duration":"320.602524ms","start":"2026-07-21T06:46:45.561826Z","end":"2026-07-21T06:46:45.882428Z","steps":["trace[776143048] 'agreement among raft nodes before linearized reading' (duration: 319.952893ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:45.88273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:45.561719Z","time spent":"320.961576ms","remote":"127.0.0.1:39606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-d45kx\" "} {"level":"warn","ts":"2026-07-21T06:46:45.882741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:45.204039Z","time spent":"678.207035ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:46:45.936514Z","caller":"traceutil/trace.go:171","msg":"trace[1230220776] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"356.0912ms","start":"2026-07-21T06:46:45.580377Z","end":"2026-07-21T06:46:45.936468Z","steps":["trace[1230220776] 'process raft request' (duration: 355.928835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:45.936732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:45.580359Z","time spent":"356.259526ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:46:46.093568Z","caller":"traceutil/trace.go:171","msg":"trace[1732378349] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"192.116112ms","start":"2026-07-21T06:46:45.901359Z","end":"2026-07-21T06:46:46.093475Z","steps":["trace[1732378349] 'process raft request' (duration: 192.06628ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:46:46.093627Z","caller":"traceutil/trace.go:171","msg":"trace[1616300208] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"207.297907ms","start":"2026-07-21T06:46:45.88631Z","end":"2026-07-21T06:46:46.093608Z","steps":["trace[1616300208] 'process raft request' (duration: 173.575167ms)","trace[1616300208] 'compare' (duration: 33.386269ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:46:46.093809Z","caller":"traceutil/trace.go:171","msg":"trace[946660894] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"206.931286ms","start":"2026-07-21T06:46:45.886825Z","end":"2026-07-21T06:46:46.093756Z","steps":["trace[946660894] 'process raft request' (duration: 206.566704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:47.388134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183659428s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:46:47.388213Z","caller":"traceutil/trace.go:171","msg":"trace[1358058885] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"1.218157213s","start":"2026-07-21T06:46:46.170044Z","end":"2026-07-21T06:46:47.388201Z","steps":["trace[1358058885] 'read index received' (duration: 34.101292ms)","trace[1358058885] 'applied index is now lower than readState.Index' (duration: 1.184054741s)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:46:47.38828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.218241285s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:46:47.388297Z","caller":"traceutil/trace.go:171","msg":"trace[515266934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"1.218306308s","start":"2026-07-21T06:46:46.169984Z","end":"2026-07-21T06:46:47.388291Z","steps":["trace[515266934] 'agreement among raft nodes before linearized reading' (duration: 1.218247676s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:47.38832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:46.169953Z","time spent":"1.21836012s","remote":"127.0.0.1:39414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-21T06:46:47.388516Z","caller":"traceutil/trace.go:171","msg":"trace[694937934] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"1.447723563s","start":"2026-07-21T06:46:45.940766Z","end":"2026-07-21T06:46:47.388489Z","steps":["trace[694937934] 'process raft request' (duration: 263.480897ms)","trace[694937934] 'compare' (duration: 1.183132801s)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:46:47.388634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:45.940744Z","time spent":"1.447832917s","remote":"127.0.0.1:39664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T06:46:47.833978Z","caller":"traceutil/trace.go:171","msg":"trace[1861121649] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"252.546926ms","start":"2026-07-21T06:46:47.581406Z","end":"2026-07-21T06:46:47.833952Z","steps":["trace[1861121649] 'read index received' (duration: 252.393551ms)","trace[1861121649] 'applied index is now lower than readState.Index' (duration: 151.985µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:46:47.834094Z","caller":"traceutil/trace.go:171","msg":"trace[419642797] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"431.414043ms","start":"2026-07-21T06:46:47.402645Z","end":"2026-07-21T06:46:47.834059Z","steps":["trace[419642797] 'process raft request' (duration: 431.127014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:47.834279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:47.402594Z","time spent":"431.60069ms","remote":"127.0.0.1:39606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:46:47.834485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.060182ms","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-21T06:46:47.834534Z","caller":"traceutil/trace.go:171","msg":"trace[167714332] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:602; }","duration":"253.132104ms","start":"2026-07-21T06:46:47.581391Z","end":"2026-07-21T06:46:47.834523Z","steps":["trace[167714332] 'agreement among raft nodes before linearized reading' (duration: 252.659509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:49.701459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.604451352s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-21T06:46:49.701578Z","caller":"traceutil/trace.go:171","msg":"trace[1826621457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:607; }","duration":"1.604631527s","start":"2026-07-21T06:46:48.096923Z","end":"2026-07-21T06:46:49.701555Z","steps":["trace[1826621457] 'range keys from in-memory index tree' (duration: 1.604234795s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:49.701629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:48.096894Z","time spent":"1.60472192s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T06:46:49.702203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.210879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:46:49.702262Z","caller":"traceutil/trace.go:171","msg":"trace[970621098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"531.287531ms","start":"2026-07-21T06:46:49.170957Z","end":"2026-07-21T06:46:49.702245Z","steps":["trace[970621098] 'range keys from in-memory index tree' (duration: 531.167858ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:49.702298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:49.170941Z","time spent":"531.347693ms","remote":"127.0.0.1:39414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T06:46:49.702507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.604342648s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-21T06:46:49.702695Z","caller":"traceutil/trace.go:171","msg":"trace[1049618633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:607; }","duration":"1.604571585s","start":"2026-07-21T06:46:48.098101Z","end":"2026-07-21T06:46:49.702672Z","steps":["trace[1049618633] 'range keys from in-memory index tree' (duration: 1.603613495s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:49.702751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:48.098083Z","time spent":"1.604654868s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T06:46:49.702881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.762121209s","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-21T06:46:49.702939Z","caller":"traceutil/trace.go:171","msg":"trace[1168704744] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:607; }","duration":"1.762262984s","start":"2026-07-21T06:46:47.940661Z","end":"2026-07-21T06:46:49.702924Z","steps":["trace[1168704744] 'range keys from in-memory index tree' (duration: 1.760470137s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:49.702985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:47.940634Z","time spent":"1.762338797s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-21T06:46:50.424535Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957996230156782446,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:46:50.776798Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.069337169s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T06:46:50.778066Z","caller":"traceutil/trace.go:171","msg":"trace[1276834245] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"1.070692782s","start":"2026-07-21T06:46:49.707342Z","end":"2026-07-21T06:46:50.778035Z","steps":["trace[1276834245] 'process raft request' (duration: 1.070138934s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:50.77828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:49.707317Z","time spent":"1.070833837s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:46:50.882437Z","caller":"traceutil/trace.go:171","msg":"trace[171817444] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"1.173305618s","start":"2026-07-21T06:46:49.709099Z","end":"2026-07-21T06:46:50.882404Z","steps":["trace[171817444] 'process raft request' (duration: 1.139721763s)","trace[171817444] 'compare' (duration: 33.167882ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:46:50.882593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:49.709077Z","time spent":"1.173437902s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:46:50.882718Z","caller":"traceutil/trace.go:171","msg":"trace[734139373] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:631; }","duration":"958.713687ms","start":"2026-07-21T06:46:49.923978Z","end":"2026-07-21T06:46:50.882692Z","steps":["trace[734139373] 'read index received' (duration: 853.095185ms)","trace[734139373] 'applied index is now lower than readState.Index' (duration: 105.617382ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:46:50.882761Z","caller":"traceutil/trace.go:171","msg":"trace[2026695227] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"1.164895729s","start":"2026-07-21T06:46:49.717851Z","end":"2026-07-21T06:46:50.882747Z","steps":["trace[2026695227] 'process raft request' (duration: 1.164350401s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:50.88296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.979815ms","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-21T06:46:50.883019Z","caller":"traceutil/trace.go:171","msg":"trace[299617875] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:610; }","duration":"959.041117ms","start":"2026-07-21T06:46:49.923963Z","end":"2026-07-21T06:46:50.883004Z","steps":["trace[299617875] 'agreement among raft nodes before linearized reading' (duration: 958.840861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:50.882995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:49.717837Z","time spent":"1.165043203s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:46:50.882987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.46322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:46:50.883123Z","caller":"traceutil/trace.go:171","msg":"trace[657079566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"711.656287ms","start":"2026-07-21T06:46:50.171454Z","end":"2026-07-21T06:46:50.88311Z","steps":["trace[657079566] 'agreement among raft nodes before linearized reading' (duration: 711.46791ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:50.883195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:50.171435Z","time spent":"711.748419ms","remote":"127.0.0.1:39414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T06:46:50.883067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:49.923916Z","time spent":"959.140311ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-21T06:46:51.369847Z","caller":"traceutil/trace.go:171","msg":"trace[171358925] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"179.014762ms","start":"2026-07-21T06:46:51.190814Z","end":"2026-07-21T06:46:51.369829Z","steps":["trace[171358925] 'process raft request' (duration: 178.905688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:52.034521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.688327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.150\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-21T06:46:52.034605Z","caller":"traceutil/trace.go:171","msg":"trace[1814020867] range","detail":"{range_begin:/registry/masterleases/199.19.213.150; range_end:; response_count:1; response_revision:616; }","duration":"230.837981ms","start":"2026-07-21T06:46:51.803746Z","end":"2026-07-21T06:46:52.034584Z","steps":["trace[1814020867] 'range keys from in-memory index tree' (duration: 230.444189ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:53.134866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.446412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:46:53.134963Z","caller":"traceutil/trace.go:171","msg":"trace[926053099] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"351.96389ms","start":"2026-07-21T06:46:52.782983Z","end":"2026-07-21T06:46:53.134947Z","steps":["trace[926053099] 'read index received' (duration: 169.371933ms)","trace[926053099] 'applied index is now lower than readState.Index' (duration: 182.590827ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:46:53.135272Z","caller":"traceutil/trace.go:171","msg":"trace[673261795] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"438.234052ms","start":"2026-07-21T06:46:52.697023Z","end":"2026-07-21T06:46:53.135257Z","steps":["trace[673261795] 'process raft request' (duration: 255.320175ms)","trace[673261795] 'compare' (duration: 182.280056ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:46:53.135345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:52.697008Z","time spent":"438.301523ms","remote":"127.0.0.1:39476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4059,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:46:53.135408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.411824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-21T06:46:53.135469Z","caller":"traceutil/trace.go:171","msg":"trace[1248959847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:646; }","duration":"352.525907ms","start":"2026-07-21T06:46:52.782929Z","end":"2026-07-21T06:46:53.135455Z","steps":["trace[1248959847] 'agreement among raft nodes before linearized reading' (duration: 352.31114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:53.135506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:52.782902Z","time spent":"352.59813ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T06:46:53.135496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.158157ms","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-21T06:46:53.135537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.879734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:46:53.135569Z","caller":"traceutil/trace.go:171","msg":"trace[515972204] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:646; }","duration":"239.283571ms","start":"2026-07-21T06:46:52.896266Z","end":"2026-07-21T06:46:53.13555Z","steps":["trace[515972204] 'agreement among raft nodes before linearized reading' (duration: 239.049554ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:46:53.13559Z","caller":"traceutil/trace.go:171","msg":"trace[1894516559] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:646; }","duration":"246.964537ms","start":"2026-07-21T06:46:52.888608Z","end":"2026-07-21T06:46:53.135573Z","steps":["trace[1894516559] 'agreement among raft nodes before linearized reading' (duration: 246.893165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:53.135818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.463423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-21T06:46:53.135857Z","caller":"traceutil/trace.go:171","msg":"trace[1760160314] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:646; }","duration":"247.545826ms","start":"2026-07-21T06:46:52.888302Z","end":"2026-07-21T06:46:53.135848Z","steps":["trace[1760160314] 'agreement among raft nodes before linearized reading' (duration: 247.422962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:53.13582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.916208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-21T06:46:53.136616Z","caller":"traceutil/trace.go:171","msg":"trace[256331620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:646; }","duration":"248.794576ms","start":"2026-07-21T06:46:52.887797Z","end":"2026-07-21T06:46:53.136592Z","steps":["trace[256331620] 'agreement among raft nodes before linearized reading' (duration: 247.464703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:07.060037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.146754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-21T06:47:07.060075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.688077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-b2qx6\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-21T06:47:07.060103Z","caller":"traceutil/trace.go:171","msg":"trace[526041879] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:760; }","duration":"203.244498ms","start":"2026-07-21T06:47:06.856845Z","end":"2026-07-21T06:47:07.060089Z","steps":["trace[526041879] 'count revisions from in-memory index tree' (duration: 203.090723ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:47:07.060107Z","caller":"traceutil/trace.go:171","msg":"trace[1818815914] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-b2qx6; range_end:; response_count:1; response_revision:760; }","duration":"389.751299ms","start":"2026-07-21T06:47:06.670347Z","end":"2026-07-21T06:47:07.060098Z","steps":["trace[1818815914] 'range keys from in-memory index tree' (duration: 389.561743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:07.060131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:06.670332Z","time spent":"389.793221ms","remote":"127.0.0.1:39606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3989,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-b2qx6\" "} {"level":"warn","ts":"2026-07-21T06:47:07.060317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.874139ms","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-21T06:47:07.060339Z","caller":"traceutil/trace.go:171","msg":"trace[1693701494] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:760; }","duration":"704.936571ms","start":"2026-07-21T06:47:06.355396Z","end":"2026-07-21T06:47:07.060333Z","steps":["trace[1693701494] 'range keys from in-memory index tree' (duration: 704.771805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:07.060357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:06.355372Z","time spent":"704.980712ms","remote":"127.0.0.1:39702","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-21T06:47:07.670379Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957996230156782750,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T06:47:08.015079Z","caller":"traceutil/trace.go:171","msg":"trace[1789643773] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"951.106653ms","start":"2026-07-21T06:47:07.063954Z","end":"2026-07-21T06:47:08.01506Z","steps":["trace[1789643773] 'process raft request' (duration: 950.977899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:08.015261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:07.06394Z","time spent":"951.209347ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:47:08.121412Z","caller":"traceutil/trace.go:171","msg":"trace[900335506] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"1.053924535s","start":"2026-07-21T06:47:07.067468Z","end":"2026-07-21T06:47:08.121392Z","steps":["trace[900335506] 'process raft request' (duration: 1.046171397s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:08.121597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:07.067445Z","time spent":"1.05409024s","remote":"127.0.0.1:39606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:47:08.133766Z","caller":"traceutil/trace.go:171","msg":"trace[1084288340] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:790; }","duration":"963.904853ms","start":"2026-07-21T06:47:07.169839Z","end":"2026-07-21T06:47:08.133744Z","steps":["trace[1084288340] 'read index received' (duration: 845.14926ms)","trace[1084288340] 'applied index is now lower than readState.Index' (duration: 118.754553ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:47:08.134144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.289525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:47:08.134201Z","caller":"traceutil/trace.go:171","msg":"trace[122164738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:762; }","duration":"964.379248ms","start":"2026-07-21T06:47:07.169814Z","end":"2026-07-21T06:47:08.134193Z","steps":["trace[122164738] 'agreement among raft nodes before linearized reading' (duration: 964.291706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:08.134247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:07.169801Z","time spent":"964.43856ms","remote":"127.0.0.1:39414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T06:47:08.134413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.991827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:47:08.134463Z","caller":"traceutil/trace.go:171","msg":"trace[746503457] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:762; }","duration":"930.0698ms","start":"2026-07-21T06:47:07.204384Z","end":"2026-07-21T06:47:08.134454Z","steps":["trace[746503457] 'agreement among raft nodes before linearized reading' (duration: 930.005528ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:08.134509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:07.204366Z","time spent":"930.134861ms","remote":"127.0.0.1:39934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:47:08.134783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.534828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:47:08.134834Z","caller":"traceutil/trace.go:171","msg":"trace[887601448] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:762; }","duration":"456.620421ms","start":"2026-07-21T06:47:07.678207Z","end":"2026-07-21T06:47:08.134828Z","steps":["trace[887601448] 'agreement among raft nodes before linearized reading' (duration: 456.556409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:08.134876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:07.678192Z","time spent":"456.676063ms","remote":"127.0.0.1:39534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:47:08.134962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.635958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-21T06:47:08.135014Z","caller":"traceutil/trace.go:171","msg":"trace[1331896422] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:762; }","duration":"851.723391ms","start":"2026-07-21T06:47:07.283279Z","end":"2026-07-21T06:47:08.135003Z","steps":["trace[1331896422] 'agreement among raft nodes before linearized reading' (duration: 851.583167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:08.135055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:07.283264Z","time spent":"851.785353ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-21T06:47:08.135117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.577736ms","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-21T06:47:08.135161Z","caller":"traceutil/trace.go:171","msg":"trace[985812941] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:762; }","duration":"920.648448ms","start":"2026-07-21T06:47:07.214506Z","end":"2026-07-21T06:47:08.135154Z","steps":["trace[985812941] 'agreement among raft nodes before linearized reading' (duration: 920.470382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:08.135201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:07.214491Z","time spent":"920.700929ms","remote":"127.0.0.1:39702","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-21T06:47:08.135366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.733608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T06:47:08.135392Z","caller":"traceutil/trace.go:171","msg":"trace[1962006091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:762; }","duration":"849.785838ms","start":"2026-07-21T06:47:07.2856Z","end":"2026-07-21T06:47:08.135386Z","steps":["trace[1962006091] 'agreement among raft nodes before linearized reading' (duration: 849.683875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:08.135406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:07.285588Z","time spent":"849.81489ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T06:47:08.135766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.434721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-21T06:47:08.13579Z","caller":"traceutil/trace.go:171","msg":"trace[1396668084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:762; }","duration":"851.478663ms","start":"2026-07-21T06:47:07.284304Z","end":"2026-07-21T06:47:08.135783Z","steps":["trace[1396668084] 'agreement among raft nodes before linearized reading' (duration: 851.425451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:08.137047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:07.284294Z","time spent":"852.741103ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-21T06:47:43.275506Z","caller":"traceutil/trace.go:171","msg":"trace[613392330] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"399.287212ms","start":"2026-07-21T06:47:42.876194Z","end":"2026-07-21T06:47:43.275481Z","steps":["trace[613392330] 'process raft request' (duration: 399.141728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:43.275689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:42.876169Z","time spent":"399.416717ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:47:43.505901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.937905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:47:43.506034Z","caller":"traceutil/trace.go:171","msg":"trace[1395530817] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"619.48422ms","start":"2026-07-21T06:47:42.886513Z","end":"2026-07-21T06:47:43.505997Z","steps":["trace[1395530817] 'process raft request' (duration: 474.364499ms)","trace[1395530817] 'compare' (duration: 144.807011ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:47:43.5061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:42.886489Z","time spent":"619.578323ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:47:44.837825Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957996230156783234,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:47:45.338967Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957996230156783234,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:47:45.840062Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957996230156783234,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:47:45.880883Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.687220387s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T06:47:45.881323Z","caller":"traceutil/trace.go:171","msg":"trace[1786943987] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"1.687826087s","start":"2026-07-21T06:47:44.193468Z","end":"2026-07-21T06:47:45.881294Z","steps":["trace[1786943987] 'process raft request' (duration: 1.68761518s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:45.881619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:44.193448Z","time spent":"1.687997462s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:47:47.116534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.620134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:47:47.116696Z","caller":"traceutil/trace.go:171","msg":"trace[15452231] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1022; }","duration":"2.779739083s","start":"2026-07-21T06:47:44.336941Z","end":"2026-07-21T06:47:47.11668Z","steps":["trace[15452231] 'read index received' (duration: 1.544109161s)","trace[15452231] 'applied index is now lower than readState.Index' (duration: 1.235628502s)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:47:47.116801Z","caller":"traceutil/trace.go:171","msg":"trace[1476959952] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"1.836946327s","start":"2026-07-21T06:47:45.279834Z","end":"2026-07-21T06:47:47.11678Z","steps":["trace[1476959952] 'process raft request' (duration: 1.487000823s)","trace[1476959952] 'compare' (duration: 349.472179ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:47:47.116806Z","caller":"traceutil/trace.go:171","msg":"trace[1880556970] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"1.68792905s","start":"2026-07-21T06:47:45.428845Z","end":"2026-07-21T06:47:47.116774Z","steps":["trace[1880556970] 'process raft request' (duration: 1.687774035s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:47.116887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.775694402s","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-21T06:47:47.1169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:45.279812Z","time spent":"1.83703248s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:47:47.116885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.779925189s","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-21T06:47:47.116923Z","caller":"traceutil/trace.go:171","msg":"trace[690407060] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:986; }","duration":"2.775760625s","start":"2026-07-21T06:47:44.341153Z","end":"2026-07-21T06:47:47.116913Z","steps":["trace[690407060] 'agreement among raft nodes before linearized reading' (duration: 2.77562637s)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:47:47.116945Z","caller":"traceutil/trace.go:171","msg":"trace[1803968600] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:986; }","duration":"2.780020392s","start":"2026-07-21T06:47:44.336909Z","end":"2026-07-21T06:47:47.116929Z","steps":["trace[1803968600] 'agreement among raft nodes before linearized reading' (duration: 2.779856927s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:47.116947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:45.428826Z","time spent":"1.688048624s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:47:47.11698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:44.336894Z","time spent":"2.780079614s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T06:47:47.11696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:44.341137Z","time spent":"2.775811337s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-21T06:47:47.11708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.751933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:47:47.117102Z","caller":"traceutil/trace.go:171","msg":"trace[1183913285] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:986; }","duration":"301.813925ms","start":"2026-07-21T06:47:46.815281Z","end":"2026-07-21T06:47:47.117095Z","steps":["trace[1183913285] 'agreement among raft nodes before linearized reading' (duration: 301.776064ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:47.117105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.530827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T06:47:47.117111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395602722s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:47:47.117126Z","caller":"traceutil/trace.go:171","msg":"trace[1891466367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:986; }","duration":"946.581778ms","start":"2026-07-21T06:47:46.170538Z","end":"2026-07-21T06:47:47.11712Z","steps":["trace[1891466367] 'agreement among raft nodes before linearized reading' (duration: 946.544047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:47.117125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:46.815235Z","time spent":"301.883127ms","remote":"127.0.0.1:39558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:47:47.117142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:46.170523Z","time spent":"946.61622ms","remote":"127.0.0.1:39414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-21T06:47:47.117147Z","caller":"traceutil/trace.go:171","msg":"trace[1717423593] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:986; }","duration":"1.395673814s","start":"2026-07-21T06:47:45.721461Z","end":"2026-07-21T06:47:47.117135Z","steps":["trace[1717423593] 'agreement among raft nodes before linearized reading' (duration: 1.395593021s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:47.117176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:45.721442Z","time spent":"1.395726076s","remote":"127.0.0.1:39664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:47:47.117201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.604489175s","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-21T06:47:47.117239Z","caller":"traceutil/trace.go:171","msg":"trace[1055534185] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:986; }","duration":"1.604563418s","start":"2026-07-21T06:47:45.512666Z","end":"2026-07-21T06:47:47.117229Z","steps":["trace[1055534185] 'agreement among raft nodes before linearized reading' (duration: 1.604504696s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:47.117268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:45.512645Z","time spent":"1.604615139s","remote":"127.0.0.1:39680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:47:47.117321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.775687303s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T06:47:47.117344Z","caller":"traceutil/trace.go:171","msg":"trace[804201547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:986; }","duration":"2.775734084s","start":"2026-07-21T06:47:44.341603Z","end":"2026-07-21T06:47:47.117337Z","steps":["trace[804201547] 'agreement among raft nodes before linearized reading' (duration: 2.775670052s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:47.117367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:44.341592Z","time spent":"2.775770065s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T06:47:47.117359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.776080045s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-21T06:47:47.117432Z","caller":"traceutil/trace.go:171","msg":"trace[1527139988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:986; }","duration":"2.776186199s","start":"2026-07-21T06:47:44.341237Z","end":"2026-07-21T06:47:47.117423Z","steps":["trace[1527139988] 'agreement among raft nodes before linearized reading' (duration: 2.776065205s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:47.11747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:44.341218Z","time spent":"2.77624252s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T06:48:09.710024Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957996230156783430,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:48:10.211334Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957996230156783430,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:48:10.318705Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.13122562s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T06:48:10.319551Z","caller":"traceutil/trace.go:171","msg":"trace[848476966] linearizableReadLoop","detail":"{readStateIndex:1102; appliedIndex:1101; }","duration":"1.110044959s","start":"2026-07-21T06:48:09.209478Z","end":"2026-07-21T06:48:10.319523Z","steps":["trace[848476966] 'read index received' (duration: 1.10946809s)","trace[848476966] 'applied index is now lower than readState.Index' (duration: 575.829µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:48:10.319677Z","caller":"traceutil/trace.go:171","msg":"trace[1368459147] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"1.132271624s","start":"2026-07-21T06:48:09.187359Z","end":"2026-07-21T06:48:10.319631Z","steps":["trace[1368459147] 'process raft request' (duration: 1.131945934s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:10.319823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:09.187322Z","time spent":"1.132405189s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:48:10.399929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.882616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:48:10.400022Z","caller":"traceutil/trace.go:171","msg":"trace[2037533686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1058; }","duration":"229.037231ms","start":"2026-07-21T06:48:10.17096Z","end":"2026-07-21T06:48:10.399997Z","steps":["trace[2037533686] 'agreement among raft nodes before linearized reading' (duration: 148.754477ms)","trace[2037533686] 'range keys from in-memory index tree' (duration: 80.142289ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:48:10.400215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.829491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-21T06:48:10.400324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183558954s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-21T06:48:10.400308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187878024s","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-21T06:48:10.400119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.175539516s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-21T06:48:10.40036Z","caller":"traceutil/trace.go:171","msg":"trace[72098711] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1058; }","duration":"1.187932125s","start":"2026-07-21T06:48:09.212418Z","end":"2026-07-21T06:48:10.40035Z","steps":["trace[72098711] 'agreement among raft nodes before linearized reading' (duration: 1.10728413s)","trace[72098711] 'range keys from in-memory index tree' (duration: 80.555173ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:48:10.400385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:09.212372Z","time spent":"1.188005838s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-21T06:48:10.400632Z","caller":"traceutil/trace.go:171","msg":"trace[1420891811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1058; }","duration":"1.175923899s","start":"2026-07-21T06:48:09.22454Z","end":"2026-07-21T06:48:10.400464Z","steps":["trace[1420891811] 'agreement among raft nodes before linearized reading' (duration: 1.095194581s)","trace[1420891811] 'range keys from in-memory index tree' (duration: 80.210762ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:48:10.400317Z","caller":"traceutil/trace.go:171","msg":"trace[1426796022] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1058; }","duration":"406.007338ms","start":"2026-07-21T06:48:09.994287Z","end":"2026-07-21T06:48:10.400294Z","steps":["trace[1426796022] 'agreement among raft nodes before linearized reading' (duration: 325.440955ms)","trace[1426796022] 'range keys from in-memory index tree' (duration: 80.296424ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:48:10.400355Z","caller":"traceutil/trace.go:171","msg":"trace[26133805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1058; }","duration":"1.183597696s","start":"2026-07-21T06:48:09.216747Z","end":"2026-07-21T06:48:10.400344Z","steps":["trace[26133805] 'agreement among raft nodes before linearized reading' (duration: 1.102995552s)","trace[26133805] 'range keys from in-memory index tree' (duration: 80.538072ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:48:10.400902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:09.21674Z","time spent":"1.184148004s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T06:48:10.400899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:09.224531Z","time spent":"1.176179497s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T06:48:10.400884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:09.99426Z","time spent":"406.570756ms","remote":"127.0.0.1:39702","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-21T06:48:10.40021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190699364s","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-21T06:48:10.401026Z","caller":"traceutil/trace.go:171","msg":"trace[830406833] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1058; }","duration":"1.191586863s","start":"2026-07-21T06:48:09.209427Z","end":"2026-07-21T06:48:10.401014Z","steps":["trace[830406833] 'agreement among raft nodes before linearized reading' (duration: 1.110250985s)","trace[830406833] 'range keys from in-memory index tree' (duration: 80.415539ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:48:10.401077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:09.209409Z","time spent":"1.191655675s","remote":"127.0.0.1:39702","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-21T06:48:12.671034Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957996230156783440,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:48:12.802414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:11.809309Z","time spent":"993.099346ms","remote":"127.0.0.1:39468","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-21T06:48:13.12635Z","caller":"traceutil/trace.go:171","msg":"trace[1101264676] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"322.304565ms","start":"2026-07-21T06:48:12.804014Z","end":"2026-07-21T06:48:13.126319Z","steps":["trace[1101264676] 'process raft request' (duration: 322.15358ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:13.126534Z","caller":"traceutil/trace.go:171","msg":"trace[1972023686] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1107; }","duration":"955.531467ms","start":"2026-07-21T06:48:12.170983Z","end":"2026-07-21T06:48:13.126515Z","steps":["trace[1972023686] 'read index received' (duration: 631.28319ms)","trace[1972023686] 'applied index is now lower than readState.Index' (duration: 324.246017ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:48:13.126551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:12.803987Z","time spent":"322.45572ms","remote":"127.0.0.1:39468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:48:13.126404Z","caller":"traceutil/trace.go:171","msg":"trace[271577672] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"800.689193ms","start":"2026-07-21T06:48:12.325666Z","end":"2026-07-21T06:48:13.126355Z","steps":["trace[271577672] 'process raft request' (duration: 732.147046ms)","trace[271577672] 'compare' (duration: 67.875675ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:48:13.126922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.93456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:48:13.126973Z","caller":"traceutil/trace.go:171","msg":"trace[623342814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1065; }","duration":"956.036113ms","start":"2026-07-21T06:48:12.170924Z","end":"2026-07-21T06:48:13.12696Z","steps":["trace[623342814] 'agreement among raft nodes before linearized reading' (duration: 955.784425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:13.126931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:12.325633Z","time spent":"801.186899ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:48:13.127182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.38561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-21T06:48:13.127242Z","caller":"traceutil/trace.go:171","msg":"trace[1989185978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1065; }","duration":"666.505814ms","start":"2026-07-21T06:48:12.460723Z","end":"2026-07-21T06:48:13.127229Z","steps":["trace[1989185978] 'agreement among raft nodes before linearized reading' (duration: 666.283367ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:13.127289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:12.460716Z","time spent":"666.564616ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T06:48:13.127292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.153705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-21T06:48:13.127303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.591837ms","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-21T06:48:13.127422Z","caller":"traceutil/trace.go:171","msg":"trace[816274648] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1065; }","duration":"666.712701ms","start":"2026-07-21T06:48:12.460668Z","end":"2026-07-21T06:48:13.12738Z","steps":["trace[816274648] 'agreement among raft nodes before linearized reading' (duration: 666.437722ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:13.127335Z","caller":"traceutil/trace.go:171","msg":"trace[1428551600] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1065; }","duration":"667.221857ms","start":"2026-07-21T06:48:12.4601Z","end":"2026-07-21T06:48:13.127322Z","steps":["trace[1428551600] 'agreement among raft nodes before linearized reading' (duration: 667.032411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:13.127501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:12.460652Z","time spent":"666.805213ms","remote":"127.0.0.1:39702","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-21T06:48:13.127528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:12.460087Z","time spent":"667.422863ms","remote":"127.0.0.1:39702","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-21T06:48:13.1275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.310327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-21T06:48:13.127298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.220006ms","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-21T06:48:13.127634Z","caller":"traceutil/trace.go:171","msg":"trace[1299373442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1065; }","duration":"666.505704ms","start":"2026-07-21T06:48:12.461089Z","end":"2026-07-21T06:48:13.127594Z","steps":["trace[1299373442] 'agreement among raft nodes before linearized reading' (duration: 666.001428ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:13.127658Z","caller":"traceutil/trace.go:171","msg":"trace[1051298702] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1065; }","duration":"685.66767ms","start":"2026-07-21T06:48:12.441978Z","end":"2026-07-21T06:48:13.127645Z","steps":["trace[1051298702] 'agreement among raft nodes before linearized reading' (duration: 685.0314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:13.127681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:12.461077Z","time spent":"666.595606ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T06:48:13.127749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:12.441953Z","time spent":"685.761654ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-21T06:48:13.127045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:12.170902Z","time spent":"956.129336ms","remote":"127.0.0.1:39414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-21T06:48:13.343545Z","caller":"traceutil/trace.go:171","msg":"trace[1602407377] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"211.112886ms","start":"2026-07-21T06:48:13.132398Z","end":"2026-07-21T06:48:13.343511Z","steps":["trace[1602407377] 'process raft request' (duration: 124.434885ms)","trace[1602407377] 'compare' (duration: 86.430073ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:48:13.343886Z","caller":"traceutil/trace.go:171","msg":"trace[222679197] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"211.132086ms","start":"2026-07-21T06:48:13.132721Z","end":"2026-07-21T06:48:13.343854Z","steps":["trace[222679197] 'process raft request' (duration: 210.738763ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:13.344167Z","caller":"traceutil/trace.go:171","msg":"trace[745367941] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"211.279911ms","start":"2026-07-21T06:48:13.132854Z","end":"2026-07-21T06:48:13.344134Z","steps":["trace[745367941] 'process raft request' (duration: 210.885458ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:13.344551Z","caller":"traceutil/trace.go:171","msg":"trace[1153519295] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1110; }","duration":"210.437114ms","start":"2026-07-21T06:48:13.134098Z","end":"2026-07-21T06:48:13.344535Z","steps":["trace[1153519295] 'read index received' (duration: 123.291548ms)","trace[1153519295] 'applied index is now lower than readState.Index' (duration: 87.144756ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:48:13.344757Z","caller":"traceutil/trace.go:171","msg":"trace[659768483] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"211.446206ms","start":"2026-07-21T06:48:13.133296Z","end":"2026-07-21T06:48:13.344742Z","steps":["trace[659768483] 'process raft request' (duration: 211.108285ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:13.344775Z","caller":"traceutil/trace.go:171","msg":"trace[1273727781] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"210.994622ms","start":"2026-07-21T06:48:13.133768Z","end":"2026-07-21T06:48:13.344763Z","steps":["trace[1273727781] 'process raft request' (duration: 210.730803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:13.344874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.763534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-21T06:48:13.344984Z","caller":"traceutil/trace.go:171","msg":"trace[1124941873] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1070; }","duration":"210.861257ms","start":"2026-07-21T06:48:13.134085Z","end":"2026-07-21T06:48:13.344946Z","steps":["trace[1124941873] 'agreement among raft nodes before linearized reading' (duration: 210.65335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:13.344886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.949997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:48:13.345047Z","caller":"traceutil/trace.go:171","msg":"trace[1200364189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1070; }","duration":"173.207616ms","start":"2026-07-21T06:48:13.171824Z","end":"2026-07-21T06:48:13.345032Z","steps":["trace[1200364189] 'agreement among raft nodes before linearized reading' (duration: 172.956927ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:14.420211Z","caller":"traceutil/trace.go:171","msg":"trace[1946716769] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1115; }","duration":"264.69192ms","start":"2026-07-21T06:48:14.155487Z","end":"2026-07-21T06:48:14.420179Z","steps":["trace[1946716769] 'read index received' (duration: 264.358889ms)","trace[1946716769] 'applied index is now lower than readState.Index' (duration: 331.801µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:48:14.420257Z","caller":"traceutil/trace.go:171","msg":"trace[1245330952] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"332.880275ms","start":"2026-07-21T06:48:14.087336Z","end":"2026-07-21T06:48:14.420216Z","steps":["trace[1245330952] 'process raft request' (duration: 332.634647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:14.420423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.905162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T06:48:14.420469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:14.087309Z","time spent":"333.05165ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:48:14.420497Z","caller":"traceutil/trace.go:171","msg":"trace[2061979793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1071; }","duration":"249.061867ms","start":"2026-07-21T06:48:14.171424Z","end":"2026-07-21T06:48:14.420486Z","steps":["trace[2061979793] 'agreement among raft nodes before linearized reading' (duration: 248.932783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:14.42045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.922437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:48:14.420675Z","caller":"traceutil/trace.go:171","msg":"trace[1019353059] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1071; }","duration":"265.151744ms","start":"2026-07-21T06:48:14.155405Z","end":"2026-07-21T06:48:14.420557Z","steps":["trace[1019353059] 'agreement among raft nodes before linearized reading' (duration: 264.897356ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:15.372643Z","caller":"traceutil/trace.go:171","msg":"trace[1481037214] linearizableReadLoop","detail":"{readStateIndex:1117; appliedIndex:1116; }","duration":"201.576359ms","start":"2026-07-21T06:48:15.171043Z","end":"2026-07-21T06:48:15.37262Z","steps":["trace[1481037214] 'read index received' (duration: 201.487556ms)","trace[1481037214] 'applied index is now lower than readState.Index' (duration: 85.873µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:48:15.372793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.577714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T06:48:15.372783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.727383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:48:15.372826Z","caller":"traceutil/trace.go:171","msg":"trace[2069181708] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1072; }","duration":"158.675687ms","start":"2026-07-21T06:48:15.21414Z","end":"2026-07-21T06:48:15.372816Z","steps":["trace[2069181708] 'agreement among raft nodes before linearized reading' (duration: 158.618645ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:15.37285Z","caller":"traceutil/trace.go:171","msg":"trace[1177206042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1072; }","duration":"201.807515ms","start":"2026-07-21T06:48:15.171025Z","end":"2026-07-21T06:48:15.372832Z","steps":["trace[1177206042] 'agreement among raft nodes before linearized reading' (duration: 201.697592ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:15.372693Z","caller":"traceutil/trace.go:171","msg":"trace[881695637] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"238.669292ms","start":"2026-07-21T06:48:15.133998Z","end":"2026-07-21T06:48:15.372667Z","steps":["trace[881695637] 'process raft request' (duration: 238.476056ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:15.816257Z","caller":"traceutil/trace.go:171","msg":"trace[1827231055] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1118; }","duration":"313.107419ms","start":"2026-07-21T06:48:15.503123Z","end":"2026-07-21T06:48:15.81623Z","steps":["trace[1827231055] 'read index received' (duration: 262.231601ms)","trace[1827231055] 'applied index is now lower than readState.Index' (duration: 50.875008ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:48:15.816265Z","caller":"traceutil/trace.go:171","msg":"trace[416125725] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"437.531634ms","start":"2026-07-21T06:48:15.378689Z","end":"2026-07-21T06:48:15.816221Z","steps":["trace[416125725] 'process raft request' (duration: 386.743868ms)","trace[416125725] 'compare' (duration: 50.496356ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:48:15.816434Z","caller":"traceutil/trace.go:171","msg":"trace[1764940839] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"437.325507ms","start":"2026-07-21T06:48:15.379086Z","end":"2026-07-21T06:48:15.816412Z","steps":["trace[1764940839] 'process raft request' (duration: 437.023377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:15.816528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.402548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:48:15.816561Z","caller":"traceutil/trace.go:171","msg":"trace[222548598] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1077; }","duration":"313.47466ms","start":"2026-07-21T06:48:15.503078Z","end":"2026-07-21T06:48:15.816552Z","steps":["trace[222548598] 'agreement among raft nodes before linearized reading' (duration: 313.408278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:15.81659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:15.503055Z","time spent":"313.529882ms","remote":"127.0.0.1:39522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":14,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:48:15.816455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:15.378652Z","time spent":"437.699089ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:48:15.816518Z","caller":"traceutil/trace.go:171","msg":"trace[1557455861] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"437.282276ms","start":"2026-07-21T06:48:15.379187Z","end":"2026-07-21T06:48:15.816469Z","steps":["trace[1557455861] 'process raft request' (duration: 437.010137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:15.816698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:15.379071Z","time spent":"437.430151ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:48:15.816726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:15.379171Z","time spent":"437.475622ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:48:15.81684Z","caller":"traceutil/trace.go:171","msg":"trace[816428136] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"437.709329ms","start":"2026-07-21T06:48:15.379117Z","end":"2026-07-21T06:48:15.816827Z","steps":["trace[816428136] 'process raft request' (duration: 437.038827ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:15.817245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:15.379102Z","time spent":"438.117963ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:48:33.604171Z","caller":"traceutil/trace.go:171","msg":"trace[557987301] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"110.079758ms","start":"2026-07-21T06:48:33.494045Z","end":"2026-07-21T06:48:33.604124Z","steps":["trace[557987301] 'process raft request' (duration: 109.565702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:35.669691Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957996230156783726,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T06:48:35.999017Z","caller":"traceutil/trace.go:171","msg":"trace[319989736] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"918.242728ms","start":"2026-07-21T06:48:35.080714Z","end":"2026-07-21T06:48:35.998956Z","steps":["trace[319989736] 'process raft request' (duration: 918.042062ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:35.999254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:35.080679Z","time spent":"918.455495ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:48:36.039597Z","caller":"traceutil/trace.go:171","msg":"trace[518648388] linearizableReadLoop","detail":"{readStateIndex:1249; appliedIndex:1247; }","duration":"870.934207ms","start":"2026-07-21T06:48:35.168634Z","end":"2026-07-21T06:48:36.039568Z","steps":["trace[518648388] 'read index received' (duration: 830.133032ms)","trace[518648388] 'applied index is now lower than readState.Index' (duration: 40.800185ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:48:36.040102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.452174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:48:36.040155Z","caller":"traceutil/trace.go:171","msg":"trace[1531233971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1198; }","duration":"871.561847ms","start":"2026-07-21T06:48:35.168579Z","end":"2026-07-21T06:48:36.040141Z","steps":["trace[1531233971] 'agreement among raft nodes before linearized reading' (duration: 871.464474ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:36.040108Z","caller":"traceutil/trace.go:171","msg":"trace[384980849] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"423.58002ms","start":"2026-07-21T06:48:35.616485Z","end":"2026-07-21T06:48:36.040065Z","steps":["trace[384980849] 'process raft request' (duration: 422.889547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:36.040195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:35.168556Z","time spent":"871.628829ms","remote":"127.0.0.1:39414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T06:48:36.040203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.883878ms","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":"warn","ts":"2026-07-21T06:48:36.040265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.962605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-21T06:48:36.040311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:35.616469Z","time spent":"423.740794ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:48:36.040358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.681732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:48:36.040364Z","caller":"traceutil/trace.go:171","msg":"trace[938792008] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1198; }","duration":"309.144941ms","start":"2026-07-21T06:48:35.731195Z","end":"2026-07-21T06:48:36.04034Z","steps":["trace[938792008] 'agreement among raft nodes before linearized reading' (duration: 308.908134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:36.040421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:35.731166Z","time spent":"309.244215ms","remote":"127.0.0.1:39910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:48:36.04045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.845014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-21T06:48:36.040478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.065515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T06:48:36.040389Z","caller":"traceutil/trace.go:171","msg":"trace[2034336937] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1198; }","duration":"234.880398ms","start":"2026-07-21T06:48:35.805498Z","end":"2026-07-21T06:48:36.040379Z","steps":["trace[2034336937] 'agreement among raft nodes before linearized reading' (duration: 234.806975ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:36.040517Z","caller":"traceutil/trace.go:171","msg":"trace[2110896399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1198; }","duration":"126.947168ms","start":"2026-07-21T06:48:35.913553Z","end":"2026-07-21T06:48:36.0405Z","steps":["trace[2110896399] 'agreement among raft nodes before linearized reading' (duration: 126.738611ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:36.040548Z","caller":"traceutil/trace.go:171","msg":"trace[54042743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1198; }","duration":"424.194799ms","start":"2026-07-21T06:48:35.616339Z","end":"2026-07-21T06:48:36.040533Z","steps":["trace[54042743] 'agreement among raft nodes before linearized reading' (duration: 423.901329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:36.040583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:35.616314Z","time spent":"424.260211ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T06:48:36.040744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.527274ms","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-21T06:48:36.040779Z","caller":"traceutil/trace.go:171","msg":"trace[778087367] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1198; }","duration":"131.604947ms","start":"2026-07-21T06:48:35.909163Z","end":"2026-07-21T06:48:36.040768Z","steps":["trace[778087367] 'agreement among raft nodes before linearized reading' (duration: 131.501174ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:36.040833Z","caller":"traceutil/trace.go:171","msg":"trace[1986068535] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1198; }","duration":"556.054613ms","start":"2026-07-21T06:48:35.48421Z","end":"2026-07-21T06:48:36.040265Z","steps":["trace[1986068535] 'agreement among raft nodes before linearized reading' (duration: 555.860137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:36.040901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:35.484184Z","time spent":"556.676013ms","remote":"127.0.0.1:52966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:48:36.040981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.889484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-21T06:48:36.041062Z","caller":"traceutil/trace.go:171","msg":"trace[1350955284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1198; }","duration":"136.007987ms","start":"2026-07-21T06:48:35.905041Z","end":"2026-07-21T06:48:36.041049Z","steps":["trace[1350955284] 'agreement among raft nodes before linearized reading' (duration: 135.860802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:36.670947Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957996230156783732,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T06:48:36.79459Z","caller":"traceutil/trace.go:171","msg":"trace[1479075802] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"750.529179ms","start":"2026-07-21T06:48:36.044036Z","end":"2026-07-21T06:48:36.794565Z","steps":["trace[1479075802] 'process raft request' (duration: 750.373654ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:36.794736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:36.044015Z","time spent":"750.648223ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:48:36.826445Z","caller":"traceutil/trace.go:171","msg":"trace[821158093] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"780.990353ms","start":"2026-07-21T06:48:36.045421Z","end":"2026-07-21T06:48:36.826412Z","steps":["trace[821158093] 'process raft request' (duration: 780.725895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:36.826644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:36.04539Z","time spent":"781.145328ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:48:36.827089Z","caller":"traceutil/trace.go:171","msg":"trace[279697055] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"781.448498ms","start":"2026-07-21T06:48:36.045621Z","end":"2026-07-21T06:48:36.827069Z","steps":["trace[279697055] 'process raft request' (duration: 781.240661ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:36.827106Z","caller":"traceutil/trace.go:171","msg":"trace[1219217208] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"779.34449ms","start":"2026-07-21T06:48:36.047731Z","end":"2026-07-21T06:48:36.827076Z","steps":["trace[1219217208] 'process raft request' (duration: 779.252267ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:36.827168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:36.045613Z","time spent":"781.51476ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:48:36.827197Z","caller":"traceutil/trace.go:171","msg":"trace[591349039] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"781.587043ms","start":"2026-07-21T06:48:36.045565Z","end":"2026-07-21T06:48:36.827152Z","steps":["trace[591349039] 'process raft request' (duration: 780.800708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:36.827272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:36.047726Z","time spent":"779.461955ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:48:36.827279Z","caller":"traceutil/trace.go:171","msg":"trace[2044908292] linearizableReadLoop","detail":"{readStateIndex:1254; appliedIndex:1249; }","duration":"656.980549ms","start":"2026-07-21T06:48:36.170287Z","end":"2026-07-21T06:48:36.827268Z","steps":["trace[2044908292] 'read index received' (duration: 624.079978ms)","trace[2044908292] 'applied index is now lower than readState.Index' (duration: 32.899381ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:48:36.827373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:36.045555Z","time spent":"781.727767ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:48:36.82744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.134744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:48:36.827509Z","caller":"traceutil/trace.go:171","msg":"trace[721248204] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1204; }","duration":"657.214657ms","start":"2026-07-21T06:48:36.170277Z","end":"2026-07-21T06:48:36.827492Z","steps":["trace[721248204] 'agreement among raft nodes before linearized reading' (duration: 657.105603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:36.827542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:36.170222Z","time spent":"657.31443ms","remote":"127.0.0.1:39414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T06:48:36.827575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.111469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:48:36.827623Z","caller":"traceutil/trace.go:171","msg":"trace[1456593325] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1204; }","duration":"466.217082ms","start":"2026-07-21T06:48:36.361392Z","end":"2026-07-21T06:48:36.82761Z","steps":["trace[1456593325] 'agreement among raft nodes before linearized reading' (duration: 466.129119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:36.827672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:36.361361Z","time spent":"466.301055ms","remote":"127.0.0.1:39606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-07-21T06:48:36.827747Z","caller":"traceutil/trace.go:171","msg":"trace[1939051460] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"127.175485ms","start":"2026-07-21T06:48:36.700557Z","end":"2026-07-21T06:48:36.827732Z","steps":["trace[1939051460] 'process raft request' (duration: 126.472553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:36.827804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.553297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:48:36.827843Z","caller":"traceutil/trace.go:171","msg":"trace[70832054] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1204; }","duration":"356.641989ms","start":"2026-07-21T06:48:36.471189Z","end":"2026-07-21T06:48:36.827831Z","steps":["trace[70832054] 'agreement among raft nodes before linearized reading' (duration: 356.574057ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:36.827868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:36.471161Z","time spent":"356.701361ms","remote":"127.0.0.1:39572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-07-21T06:48:47.170767Z","caller":"traceutil/trace.go:171","msg":"trace[401784279] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1286; }","duration":"267.290894ms","start":"2026-07-21T06:48:46.903442Z","end":"2026-07-21T06:48:47.170733Z","steps":["trace[401784279] 'read index received' (duration: 251.637483ms)","trace[401784279] 'applied index is now lower than readState.Index' (duration: 15.652181ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:48:47.170794Z","caller":"traceutil/trace.go:171","msg":"trace[1015506047] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"287.393206ms","start":"2026-07-21T06:48:46.883355Z","end":"2026-07-21T06:48:47.170748Z","steps":["trace[1015506047] 'process raft request' (duration: 271.739646ms)","trace[1015506047] 'compare' (duration: 15.495865ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:48:47.170983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.519081ms","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-21T06:48:47.171077Z","caller":"traceutil/trace.go:171","msg":"trace[1688826894] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1234; }","duration":"267.653835ms","start":"2026-07-21T06:48:46.903389Z","end":"2026-07-21T06:48:47.171043Z","steps":["trace[1688826894] 'agreement among raft nodes before linearized reading' (duration: 267.441998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:47.171127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.334085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-21T06:48:47.171165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.167066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T06:48:47.171185Z","caller":"traceutil/trace.go:171","msg":"trace[928991767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1234; }","duration":"267.431998ms","start":"2026-07-21T06:48:46.90374Z","end":"2026-07-21T06:48:47.171172Z","steps":["trace[928991767] 'agreement among raft nodes before linearized reading' (duration: 267.19592ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:47.171206Z","caller":"traceutil/trace.go:171","msg":"trace[933341891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1234; }","duration":"265.238308ms","start":"2026-07-21T06:48:46.905957Z","end":"2026-07-21T06:48:47.171195Z","steps":["trace[933341891] 'agreement among raft nodes before linearized reading' (duration: 265.027951ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:50.94175Z","caller":"traceutil/trace.go:171","msg":"trace[649828149] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"259.293778ms","start":"2026-07-21T06:48:50.682413Z","end":"2026-07-21T06:48:50.941707Z","steps":["trace[649828149] 'process raft request' (duration: 258.900595ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:50.968504Z","caller":"traceutil/trace.go:171","msg":"trace[1152702150] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"204.515216ms","start":"2026-07-21T06:48:50.763949Z","end":"2026-07-21T06:48:50.968463Z","steps":["trace[1152702150] 'process raft request' (duration: 204.246668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:51.577165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.869847ms","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-21T06:48:51.577249Z","caller":"traceutil/trace.go:171","msg":"trace[980362490] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1259; }","duration":"392.998892ms","start":"2026-07-21T06:48:51.184231Z","end":"2026-07-21T06:48:51.57723Z","steps":["trace[980362490] 'range keys from in-memory index tree' (duration: 392.616079ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:51.577282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:51.184211Z","time spent":"393.063074ms","remote":"127.0.0.1:39702","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-21T06:48:51.577495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.506322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-07-21T06:48:51.577504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.217063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-21T06:48:51.577601Z","caller":"traceutil/trace.go:171","msg":"trace[1313776531] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1259; }","duration":"385.673567ms","start":"2026-07-21T06:48:51.191898Z","end":"2026-07-21T06:48:51.577572Z","steps":["trace[1313776531] 'range keys from in-memory index tree' (duration: 385.276915ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:51.577614Z","caller":"traceutil/trace.go:171","msg":"trace[2020390616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1259; }","duration":"380.378608ms","start":"2026-07-21T06:48:51.197215Z","end":"2026-07-21T06:48:51.577593Z","steps":["trace[2020390616] 'range keys from in-memory index tree' (duration: 380.037898ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:51.57764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:51.191872Z","time spent":"385.75931ms","remote":"127.0.0.1:39702","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-21T06:48:51.577655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:51.197195Z","time spent":"380.452641ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T06:48:51.57776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.357177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-21T06:48:51.577864Z","caller":"traceutil/trace.go:171","msg":"trace[1677615453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1259; }","duration":"380.524863ms","start":"2026-07-21T06:48:51.197313Z","end":"2026-07-21T06:48:51.577837Z","steps":["trace[1677615453] 'range keys from in-memory index tree' (duration: 379.996916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:51.577924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:51.197285Z","time spent":"380.627006ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T06:48:52.086339Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957996230156783869,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T06:48:52.271623Z","caller":"traceutil/trace.go:171","msg":"trace[1870952361] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"690.572433ms","start":"2026-07-21T06:48:51.58102Z","end":"2026-07-21T06:48:52.271593Z","steps":["trace[1870952361] 'process raft request' (duration: 690.520101ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:52.27172Z","caller":"traceutil/trace.go:171","msg":"trace[813785574] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"690.648455ms","start":"2026-07-21T06:48:51.58102Z","end":"2026-07-21T06:48:52.271669Z","steps":["trace[813785574] 'process raft request' (duration: 690.327915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:52.271808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:51.580996Z","time spent":"690.720818ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:48:52.271918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:51.580996Z","time spent":"690.819501ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:48:52.765946Z","caller":"traceutil/trace.go:171","msg":"trace[819311619] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1313; }","duration":"1.179871323s","start":"2026-07-21T06:48:51.586052Z","end":"2026-07-21T06:48:52.765923Z","steps":["trace[819311619] 'read index received' (duration: 685.277034ms)","trace[819311619] 'applied index is now lower than readState.Index' (duration: 494.593619ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:48:52.766258Z","caller":"traceutil/trace.go:171","msg":"trace[1963929688] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"1.184624544s","start":"2026-07-21T06:48:51.581624Z","end":"2026-07-21T06:48:52.766249Z","steps":["trace[1963929688] 'process raft request' (duration: 1.184110708s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:52.766345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:51.581617Z","time spent":"1.184665495s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:48:52.766475Z","caller":"traceutil/trace.go:171","msg":"trace[1182813959] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"1.184620644s","start":"2026-07-21T06:48:51.581848Z","end":"2026-07-21T06:48:52.766469Z","steps":["trace[1182813959] 'process raft request' (duration: 1.184030395s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:52.766508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:51.581812Z","time spent":"1.184679796s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:48:52.766568Z","caller":"traceutil/trace.go:171","msg":"trace[937785693] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"676.826494ms","start":"2026-07-21T06:48:52.089737Z","end":"2026-07-21T06:48:52.766564Z","steps":["trace[937785693] 'process raft request' (duration: 676.163633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:52.766611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:52.089711Z","time spent":"676.877286ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:48:52.76673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.180674458s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:48:52.766768Z","caller":"traceutil/trace.go:171","msg":"trace[107378775] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1264; }","duration":"1.18074426s","start":"2026-07-21T06:48:51.586013Z","end":"2026-07-21T06:48:52.766757Z","steps":["trace[107378775] 'agreement among raft nodes before linearized reading' (duration: 1.180690269s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:52.766791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:51.585999Z","time spent":"1.180785811s","remote":"127.0.0.1:41602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:48:52.767004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.184415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.150\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-21T06:48:52.767028Z","caller":"traceutil/trace.go:171","msg":"trace[357885324] range","detail":"{range_begin:/registry/masterleases/199.19.213.150; range_end:; response_count:1; response_revision:1264; }","duration":"957.264718ms","start":"2026-07-21T06:48:51.809758Z","end":"2026-07-21T06:48:52.767023Z","steps":["trace[357885324] 'agreement among raft nodes before linearized reading' (duration: 957.170775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:52.767044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:51.809729Z","time spent":"957.311328ms","remote":"127.0.0.1:39468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.150\" "} {"level":"warn","ts":"2026-07-21T06:48:52.767205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.931192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:48:52.767224Z","caller":"traceutil/trace.go:171","msg":"trace[1955879388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1264; }","duration":"597.996764ms","start":"2026-07-21T06:48:52.169223Z","end":"2026-07-21T06:48:52.76722Z","steps":["trace[1955879388] 'agreement among raft nodes before linearized reading' (duration: 597.965283ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:52.767236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:52.169197Z","time spent":"598.036425ms","remote":"127.0.0.1:39414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T06:48:52.76731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.665821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:48:52.767327Z","caller":"traceutil/trace.go:171","msg":"trace[774416075] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1264; }","duration":"769.742343ms","start":"2026-07-21T06:48:51.997581Z","end":"2026-07-21T06:48:52.767323Z","steps":["trace[774416075] 'agreement among raft nodes before linearized reading' (duration: 769.714823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:52.767339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:51.997542Z","time spent":"769.794215ms","remote":"127.0.0.1:39870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":31,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-07-21T06:48:52.99601Z","caller":"traceutil/trace.go:171","msg":"trace[636076870] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"170.685266ms","start":"2026-07-21T06:48:52.825287Z","end":"2026-07-21T06:48:52.995972Z","steps":["trace[636076870] 'process raft request' (duration: 170.580873ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:52.996436Z","caller":"traceutil/trace.go:171","msg":"trace[1448605666] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"225.426886ms","start":"2026-07-21T06:48:52.770984Z","end":"2026-07-21T06:48:52.996411Z","steps":["trace[1448605666] 'process raft request' (duration: 143.785676ms)","trace[1448605666] 'compare' (duration: 80.889816ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:48:53.213899Z","caller":"traceutil/trace.go:171","msg":"trace[1890010511] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"212.982697ms","start":"2026-07-21T06:48:53.000867Z","end":"2026-07-21T06:48:53.21385Z","steps":["trace[1890010511] 'process raft request' (duration: 181.667746ms)","trace[1890010511] 'compare' (duration: 31.131225ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:48:54.799885Z","caller":"traceutil/trace.go:171","msg":"trace[1712478793] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"519.477464ms","start":"2026-07-21T06:48:54.280375Z","end":"2026-07-21T06:48:54.799852Z","steps":["trace[1712478793] 'process raft request' (duration: 519.291068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:54.800074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:54.280348Z","time spent":"519.631189ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:48:55.504288Z","caller":"traceutil/trace.go:171","msg":"trace[1892432697] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1325; }","duration":"733.281348ms","start":"2026-07-21T06:48:54.770977Z","end":"2026-07-21T06:48:55.504258Z","steps":["trace[1892432697] 'read index received' (duration: 29.811373ms)","trace[1892432697] 'applied index is now lower than readState.Index' (duration: 703.468835ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:48:55.504608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.621448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-21T06:48:55.504637Z","caller":"traceutil/trace.go:171","msg":"trace[1059707110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1272; }","duration":"733.699341ms","start":"2026-07-21T06:48:54.770929Z","end":"2026-07-21T06:48:55.504629Z","steps":["trace[1059707110] 'agreement among raft nodes before linearized reading' (duration: 733.582717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:55.504658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:54.770908Z","time spent":"733.744992ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T06:48:55.504675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.228653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T06:48:55.504713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.589836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-21T06:48:55.504752Z","caller":"traceutil/trace.go:171","msg":"trace[547045071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1272; }","duration":"334.352207ms","start":"2026-07-21T06:48:55.170378Z","end":"2026-07-21T06:48:55.50473Z","steps":["trace[547045071] 'agreement among raft nodes before linearized reading' (duration: 334.238083ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:55.504828Z","caller":"traceutil/trace.go:171","msg":"trace[139566431] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"732.238205ms","start":"2026-07-21T06:48:54.772582Z","end":"2026-07-21T06:48:55.50482Z","steps":["trace[139566431] 'process raft request' (duration: 731.593274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:55.504831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:55.170356Z","time spent":"334.458961ms","remote":"127.0.0.1:39414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T06:48:55.504887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:54.772563Z","time spent":"732.283236ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:48:55.504633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.952853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T06:48:55.504906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.705396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-21T06:48:55.504928Z","caller":"traceutil/trace.go:171","msg":"trace[1766503981] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1272; }","duration":"369.300534ms","start":"2026-07-21T06:48:55.135613Z","end":"2026-07-21T06:48:55.504913Z","steps":["trace[1766503981] 'agreement among raft nodes before linearized reading' (duration: 368.982614ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:55.504949Z","caller":"traceutil/trace.go:171","msg":"trace[754512935] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1272; }","duration":"435.80718ms","start":"2026-07-21T06:48:55.069132Z","end":"2026-07-21T06:48:55.504939Z","steps":["trace[754512935] 'agreement among raft nodes before linearized reading' (duration: 435.669835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:55.504989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:55.069112Z","time spent":"435.865742ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-21T06:48:55.504656Z","caller":"traceutil/trace.go:171","msg":"trace[1343773582] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"1.223834447s","start":"2026-07-21T06:48:54.28077Z","end":"2026-07-21T06:48:55.504604Z","steps":["trace[1343773582] 'process raft request' (duration: 1.164541482s)","trace[1343773582] 'compare' (duration: 58.424267ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:48:55.504773Z","caller":"traceutil/trace.go:171","msg":"trace[2126340431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1272; }","duration":"732.668838ms","start":"2026-07-21T06:48:54.772088Z","end":"2026-07-21T06:48:55.504757Z","steps":["trace[2126340431] 'agreement among raft nodes before linearized reading' (duration: 732.474071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:55.505056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:54.772081Z","time spent":"732.968688ms","remote":"127.0.0.1:39702","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-21T06:48:55.505088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:55.135586Z","time spent":"369.369806ms","remote":"127.0.0.1:39872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:48:55.505098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:54.280759Z","time spent":"1.224277952s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:48:55.505126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.131265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:48:55.505173Z","caller":"traceutil/trace.go:171","msg":"trace[1400508467] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1272; }","duration":"459.215968ms","start":"2026-07-21T06:48:55.045945Z","end":"2026-07-21T06:48:55.505161Z","steps":["trace[1400508467] 'agreement among raft nodes before linearized reading' (duration: 459.143655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:55.505219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:48:55.045924Z","time spent":"459.28244ms","remote":"127.0.0.1:39822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:48:55.775057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.484418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:48:55.775222Z","caller":"traceutil/trace.go:171","msg":"trace[669385833] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"264.98969ms","start":"2026-07-21T06:48:55.510207Z","end":"2026-07-21T06:48:55.775197Z","steps":["trace[669385833] 'process raft request' (duration: 115.263664ms)","trace[669385833] 'compare' (duration: 149.358914ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:48:55.783274Z","caller":"traceutil/trace.go:171","msg":"trace[894740755] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"272.51664ms","start":"2026-07-21T06:48:55.510737Z","end":"2026-07-21T06:48:55.783253Z","steps":["trace[894740755] 'process raft request' (duration: 272.473499ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:55.783707Z","caller":"traceutil/trace.go:171","msg":"trace[1355968450] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"273.074098ms","start":"2026-07-21T06:48:55.510616Z","end":"2026-07-21T06:48:55.78369Z","steps":["trace[1355968450] 'process raft request' (duration: 272.461359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:48:57.035842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.198352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T06:48:57.03591Z","caller":"traceutil/trace.go:171","msg":"trace[705584687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1276; }","duration":"232.323785ms","start":"2026-07-21T06:48:56.803572Z","end":"2026-07-21T06:48:57.035896Z","steps":["trace[705584687] 'range keys from in-memory index tree' (duration: 232.046976ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:57.311524Z","caller":"traceutil/trace.go:171","msg":"trace[1835979556] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1331; }","duration":"140.808541ms","start":"2026-07-21T06:48:57.170696Z","end":"2026-07-21T06:48:57.311505Z","steps":["trace[1835979556] 'read index received' (duration: 140.701677ms)","trace[1835979556] 'applied index is now lower than readState.Index' (duration: 105.934µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:48:57.311618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.909863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:48:57.311593Z","caller":"traceutil/trace.go:171","msg":"trace[1096685834] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"270.193866ms","start":"2026-07-21T06:48:57.04137Z","end":"2026-07-21T06:48:57.311564Z","steps":["trace[1096685834] 'process raft request' (duration: 269.98879ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:57.311635Z","caller":"traceutil/trace.go:171","msg":"trace[2093771571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1277; }","duration":"140.970176ms","start":"2026-07-21T06:48:57.17066Z","end":"2026-07-21T06:48:57.31163Z","steps":["trace[2093771571] 'agreement among raft nodes before linearized reading' (duration: 140.920124ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:48:59.435842Z","caller":"traceutil/trace.go:171","msg":"trace[474354524] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"118.223339ms","start":"2026-07-21T06:48:59.317595Z","end":"2026-07-21T06:48:59.435818Z","steps":["trace[474354524] 'process raft request' (duration: 118.055344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:49:04.309221Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957996230156784032,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T06:49:04.434725Z","caller":"traceutil/trace.go:171","msg":"trace[1821322114] linearizableReadLoop","detail":"{readStateIndex:1397; appliedIndex:1396; }","duration":"626.204274ms","start":"2026-07-21T06:49:03.808498Z","end":"2026-07-21T06:49:04.434702Z","steps":["trace[1821322114] 'read index received' (duration: 626.033278ms)","trace[1821322114] 'applied index is now lower than readState.Index' (duration: 170.106µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:49:04.435031Z","caller":"traceutil/trace.go:171","msg":"trace[1236888312] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"629.561381ms","start":"2026-07-21T06:49:03.805436Z","end":"2026-07-21T06:49:04.434997Z","steps":["trace[1236888312] 'process raft request' (duration: 629.154858ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:49:04.435131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:49:03.805419Z","time spent":"629.646565ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:49:04.435371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.878596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-21T06:49:04.435405Z","caller":"traceutil/trace.go:171","msg":"trace[431832998] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1340; }","duration":"626.943678ms","start":"2026-07-21T06:49:03.808451Z","end":"2026-07-21T06:49:04.435395Z","steps":["trace[431832998] 'agreement among raft nodes before linearized reading' (duration: 626.832515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:49:04.435433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:49:03.808436Z","time spent":"626.990389ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-21T06:49:04.43566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.324454ms","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-21T06:49:04.435708Z","caller":"traceutil/trace.go:171","msg":"trace[223046251] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1340; }","duration":"624.389016ms","start":"2026-07-21T06:49:03.811306Z","end":"2026-07-21T06:49:04.435695Z","steps":["trace[223046251] 'agreement among raft nodes before linearized reading' (duration: 624.247341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:49:04.435749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:49:03.811297Z","time spent":"624.439988ms","remote":"127.0.0.1:39702","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-21T06:49:04.435866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.926246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:49:04.435915Z","caller":"traceutil/trace.go:171","msg":"trace[429374408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1340; }","duration":"263.972647ms","start":"2026-07-21T06:49:04.17193Z","end":"2026-07-21T06:49:04.435903Z","steps":["trace[429374408] 'agreement among raft nodes before linearized reading' (duration: 263.905285ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:49:04.436149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.788145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:49:04.436235Z","caller":"traceutil/trace.go:171","msg":"trace[275961860] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1340; }","duration":"433.900288ms","start":"2026-07-21T06:49:04.002316Z","end":"2026-07-21T06:49:04.436217Z","steps":["trace[275961860] 'agreement among raft nodes before linearized reading' (duration: 433.702352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:49:04.436282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:49:04.002259Z","time spent":"434.013152ms","remote":"127.0.0.1:39772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:49:04.781432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.40105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:49:04.781598Z","caller":"traceutil/trace.go:171","msg":"trace[626290129] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"341.160394ms","start":"2026-07-21T06:49:04.440417Z","end":"2026-07-21T06:49:04.781577Z","steps":["trace[626290129] 'process raft request' (duration: 92.536137ms)","trace[626290129] 'compare' (duration: 247.398828ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:49:04.782079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:49:04.440394Z","time spent":"341.639379ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:49:04.825046Z","caller":"traceutil/trace.go:171","msg":"trace[188780687] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"381.976218ms","start":"2026-07-21T06:49:04.443044Z","end":"2026-07-21T06:49:04.82502Z","steps":["trace[188780687] 'process raft request' (duration: 381.884545ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:49:04.825082Z","caller":"traceutil/trace.go:171","msg":"trace[2077800701] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"383.758226ms","start":"2026-07-21T06:49:04.441281Z","end":"2026-07-21T06:49:04.825039Z","steps":["trace[2077800701] 'process raft request' (duration: 383.504298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:49:04.825238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:49:04.44126Z","time spent":"383.891539ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:49:04.82522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:49:04.443035Z","time spent":"382.079762ms","remote":"127.0.0.1:39522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":606,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:49:05.079126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.274958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:49:05.079204Z","caller":"traceutil/trace.go:171","msg":"trace[1376015743] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1344; }","duration":"117.407623ms","start":"2026-07-21T06:49:04.961783Z","end":"2026-07-21T06:49:05.079191Z","steps":["trace[1376015743] 'count revisions from in-memory index tree' (duration: 117.156094ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:49:05.970242Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957996230156784044,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T06:49:06.064151Z","caller":"traceutil/trace.go:171","msg":"trace[1463283030] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"603.003203ms","start":"2026-07-21T06:49:05.461116Z","end":"2026-07-21T06:49:06.06412Z","steps":["trace[1463283030] 'process raft request' (duration: 602.838508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:49:06.064319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:49:05.4611Z","time spent":"603.133698ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:49:06.861572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.274234ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:49:06.861909Z","caller":"traceutil/trace.go:171","msg":"trace[1418876488] linearizableReadLoop","detail":"{readStateIndex:1403; appliedIndex:1401; }","duration":"1.392621672s","start":"2026-07-21T06:49:05.469276Z","end":"2026-07-21T06:49:06.861898Z","steps":["trace[1418876488] 'read index received' (duration: 594.699218ms)","trace[1418876488] 'applied index is now lower than readState.Index' (duration: 797.921323ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:49:06.862034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.392752926s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-07-21T06:49:06.862049Z","caller":"traceutil/trace.go:171","msg":"trace[19087403] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1346; }","duration":"1.392783317s","start":"2026-07-21T06:49:05.46926Z","end":"2026-07-21T06:49:06.862043Z","steps":["trace[19087403] 'agreement among raft nodes before linearized reading' (duration: 1.392686984s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:49:06.862072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:49:05.469253Z","time spent":"1.392811148s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":560,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-21T06:49:06.862066Z","caller":"traceutil/trace.go:171","msg":"trace[512624573] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"1.32467002s","start":"2026-07-21T06:49:05.537264Z","end":"2026-07-21T06:49:06.861934Z","steps":["trace[512624573] 'process raft request' (duration: 943.937701ms)","trace[512624573] 'compare' (duration: 380.1496ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:49:06.862708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:49:05.537246Z","time spent":"1.325355632s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:49:06.894298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.732042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-21T06:49:06.894247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.344973298s","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-21T06:49:06.894357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.355371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:49:06.894381Z","caller":"traceutil/trace.go:171","msg":"trace[1120212844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1347; }","duration":"453.855976ms","start":"2026-07-21T06:49:06.440504Z","end":"2026-07-21T06:49:06.89436Z","steps":["trace[1120212844] 'agreement among raft nodes before linearized reading' (duration: 453.617098ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:49:06.894376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.615371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-21T06:49:06.894416Z","caller":"traceutil/trace.go:171","msg":"trace[795379279] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1347; }","duration":"555.469384ms","start":"2026-07-21T06:49:06.338932Z","end":"2026-07-21T06:49:06.894401Z","steps":["trace[795379279] 'agreement among raft nodes before linearized reading' (duration: 555.367981ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:49:06.894408Z","caller":"traceutil/trace.go:171","msg":"trace[1870069917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1347; }","duration":"1.345187476s","start":"2026-07-21T06:49:05.5492Z","end":"2026-07-21T06:49:06.894388Z","steps":["trace[1870069917] 'agreement among raft nodes before linearized reading' (duration: 1.344866376s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:49:06.894429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:49:06.44048Z","time spent":"453.934739ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T06:49:06.894446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:49:06.338911Z","time spent":"555.528626ms","remote":"127.0.0.1:39724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:49:06.894463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:49:05.549182Z","time spent":"1.345268548s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T06:49:06.894512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.099257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:49:06.894542Z","caller":"traceutil/trace.go:171","msg":"trace[14107716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1347; }","duration":"725.164489ms","start":"2026-07-21T06:49:06.169369Z","end":"2026-07-21T06:49:06.894534Z","steps":["trace[14107716] 'agreement among raft nodes before linearized reading' (duration: 725.116167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:49:06.894571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:49:06.16934Z","time spent":"725.224901ms","remote":"127.0.0.1:39414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T06:49:06.894664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.245019194s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-21T06:49:06.894666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.224091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-07-21T06:49:06.894684Z","caller":"traceutil/trace.go:171","msg":"trace[349675868] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1347; }","duration":"1.245069255s","start":"2026-07-21T06:49:05.649607Z","end":"2026-07-21T06:49:06.894677Z","steps":["trace[349675868] 'agreement among raft nodes before linearized reading' (duration: 1.245015413s)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:49:06.894703Z","caller":"traceutil/trace.go:171","msg":"trace[431210669] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1347; }","duration":"868.305934ms","start":"2026-07-21T06:49:06.026382Z","end":"2026-07-21T06:49:06.894688Z","steps":["trace[431210669] 'agreement among raft nodes before linearized reading' (duration: 868.21016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:49:06.894716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:49:05.649591Z","time spent":"1.245116107s","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-21T06:49:06.894737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:49:06.026368Z","time spent":"868.360125ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":551,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-21T06:49:06.894748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.89162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-21T06:49:06.89484Z","caller":"traceutil/trace.go:171","msg":"trace[1291560594] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1347; }","duration":"109.009884ms","start":"2026-07-21T06:49:06.785816Z","end":"2026-07-21T06:49:06.894825Z","steps":["trace[1291560594] 'agreement among raft nodes before linearized reading' (duration: 108.855119ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:49:06.894449Z","caller":"traceutil/trace.go:171","msg":"trace[1304578365] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1347; }","duration":"836.749985ms","start":"2026-07-21T06:49:06.05768Z","end":"2026-07-21T06:49:06.89443Z","steps":["trace[1304578365] 'agreement among raft nodes before linearized reading' (duration: 836.478877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:49:06.894899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:49:06.057658Z","time spent":"837.22933ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-21T06:49:06.894944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.272397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:49:06.894763Z","caller":"traceutil/trace.go:171","msg":"trace[574814626] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"184.227609ms","start":"2026-07-21T06:49:06.710522Z","end":"2026-07-21T06:49:06.894749Z","steps":["trace[574814626] 'process raft request' (duration: 183.304589ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:49:06.894976Z","caller":"traceutil/trace.go:171","msg":"trace[1986694209] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1347; }","duration":"896.342329ms","start":"2026-07-21T06:49:05.998624Z","end":"2026-07-21T06:49:06.894967Z","steps":["trace[1986694209] 'agreement among raft nodes before linearized reading' (duration: 896.289218ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:49:06.895007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:49:05.998604Z","time spent":"896.395832ms","remote":"127.0.0.1:39890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:49:06.895013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.953097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:49:06.895043Z","caller":"traceutil/trace.go:171","msg":"trace[1936279708] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1347; }","duration":"218.023169ms","start":"2026-07-21T06:49:06.67701Z","end":"2026-07-21T06:49:06.895033Z","steps":["trace[1936279708] 'agreement among raft nodes before linearized reading' (duration: 217.975977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:53:32.328021Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10957996230156787506,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T06:53:32.347155Z","caller":"traceutil/trace.go:171","msg":"trace[688099002] transaction","detail":"{read_only:false; response_revision:2721; number_of_response:1; }","duration":"576.666691ms","start":"2026-07-21T06:53:31.770461Z","end":"2026-07-21T06:53:32.347128Z","steps":["trace[688099002] 'process raft request' (duration: 576.524436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:53:32.347472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:53:31.770441Z","time spent":"576.850446ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:53:32.349245Z","caller":"traceutil/trace.go:171","msg":"trace[1934523050] linearizableReadLoop","detail":"{readStateIndex:2833; appliedIndex:2831; }","duration":"521.58479ms","start":"2026-07-21T06:53:31.827637Z","end":"2026-07-21T06:53:32.349222Z","steps":["trace[1934523050] 'read index received' (duration: 518.900344ms)","trace[1934523050] 'applied index is now lower than readState.Index' (duration: 2.683706ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:53:32.349355Z","caller":"traceutil/trace.go:171","msg":"trace[1620016514] transaction","detail":"{read_only:false; response_revision:2722; number_of_response:1; }","duration":"178.765284ms","start":"2026-07-21T06:53:32.170579Z","end":"2026-07-21T06:53:32.349345Z","steps":["trace[1620016514] 'process raft request' (duration: 178.510996ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:53:32.349593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.107595ms","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-21T06:53:32.349655Z","caller":"traceutil/trace.go:171","msg":"trace[1398307452] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2722; }","duration":"193.220018ms","start":"2026-07-21T06:53:32.156417Z","end":"2026-07-21T06:53:32.349637Z","steps":["trace[1398307452] 'agreement among raft nodes before linearized reading' (duration: 193.038363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:53:32.349717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.087827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.150\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-21T06:53:32.349751Z","caller":"traceutil/trace.go:171","msg":"trace[1548401954] range","detail":"{range_begin:/registry/masterleases/199.19.213.150; range_end:; response_count:1; response_revision:2722; }","duration":"522.160119ms","start":"2026-07-21T06:53:31.827579Z","end":"2026-07-21T06:53:32.34974Z","steps":["trace[1548401954] 'agreement among raft nodes before linearized reading' (duration: 522.073346ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:53:32.349785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:53:31.827558Z","time spent":"522.219171ms","remote":"127.0.0.1:39468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.150\" "} {"level":"warn","ts":"2026-07-21T06:53:32.349774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.214784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-21T06:53:32.350087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.315432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:53:32.350121Z","caller":"traceutil/trace.go:171","msg":"trace[1559291081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2722; }","duration":"179.387114ms","start":"2026-07-21T06:53:32.170726Z","end":"2026-07-21T06:53:32.350113Z","steps":["trace[1559291081] 'agreement among raft nodes before linearized reading' (duration: 179.336552ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:53:32.349853Z","caller":"traceutil/trace.go:171","msg":"trace[955035083] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2722; }","duration":"191.320958ms","start":"2026-07-21T06:53:32.158514Z","end":"2026-07-21T06:53:32.349835Z","steps":["trace[955035083] 'agreement among raft nodes before linearized reading' (duration: 191.0878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:53:32.350166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.377461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-21T06:53:32.350262Z","caller":"traceutil/trace.go:171","msg":"trace[298951108] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2722; }","duration":"192.521586ms","start":"2026-07-21T06:53:32.157722Z","end":"2026-07-21T06:53:32.350243Z","steps":["trace[298951108] 'agreement among raft nodes before linearized reading' (duration: 192.265348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:53:32.350535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.726323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-21T06:53:32.350614Z","caller":"traceutil/trace.go:171","msg":"trace[839804068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2722; }","duration":"192.825726ms","start":"2026-07-21T06:53:32.157775Z","end":"2026-07-21T06:53:32.350601Z","steps":["trace[839804068] 'agreement among raft nodes before linearized reading' (duration: 192.050872ms)"],"step_count":1}