{"level":"warn","ts":"2026-07-21T06:38:59.793807Z","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:38:59.793913Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.237: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.237:2380","--initial-cluster=instance=https://199.19.213.237:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.237:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.237: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:38:59.794012Z","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:38:59.794031Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.237:2380"]} {"level":"info","ts":"2026-07-21T06:38:59.794067Z","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:38:59.795143Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.237:2379"]} {"level":"info","ts":"2026-07-21T06:38:59.795239Z","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.237:2380"],"listen-peer-urls":["https://199.19.213.237:2380"],"advertise-client-urls":["https://199.19.213.237:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.237:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.237: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:38:59.801827Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.336838ms"} {"level":"info","ts":"2026-07-21T06:38:59.810423Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c51d7a9ea5537d6e","cluster-id":"bea7a5ea8f053dc3"} {"level":"info","ts":"2026-07-21T06:38:59.811153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e switched to configuration voters=()"} {"level":"info","ts":"2026-07-21T06:38:59.81119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became follower at term 0"} {"level":"info","ts":"2026-07-21T06:38:59.811205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c51d7a9ea5537d6e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-21T06:38:59.81122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became follower at term 1"} {"level":"info","ts":"2026-07-21T06:38:59.811258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e switched to configuration voters=(14203643621593546094)"} {"level":"warn","ts":"2026-07-21T06:38:59.820329Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-21T06:38:59.829677Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-21T06:38:59.831577Z","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:38:59.834805Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c51d7a9ea5537d6e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-21T06:38:59.835011Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c51d7a9ea5537d6e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-21T06:38:59.83509Z","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:38:59.835132Z","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:38:59.835146Z","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:38:59.836482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e switched to configuration voters=(14203643621593546094)"} {"level":"info","ts":"2026-07-21T06:38:59.836787Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bea7a5ea8f053dc3","local-member-id":"c51d7a9ea5537d6e","added-peer-id":"c51d7a9ea5537d6e","added-peer-peer-urls":["https://199.19.213.237:2380"]} {"level":"info","ts":"2026-07-21T06:38:59.8458Z","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:38:59.846069Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c51d7a9ea5537d6e","initial-advertise-peer-urls":["https://199.19.213.237:2380"],"listen-peer-urls":["https://199.19.213.237:2380"],"advertise-client-urls":["https://199.19.213.237:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.237:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-21T06:38:59.8461Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-21T06:38:59.846156Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.237:2380"} {"level":"info","ts":"2026-07-21T06:38:59.846187Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.237:2380"} {"level":"info","ts":"2026-07-21T06:39:00.411928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e is starting a new election at term 1"} {"level":"info","ts":"2026-07-21T06:39:00.411973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became pre-candidate at term 1"} {"level":"info","ts":"2026-07-21T06:39:00.411994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e received MsgPreVoteResp from c51d7a9ea5537d6e at term 1"} {"level":"info","ts":"2026-07-21T06:39:00.412004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became candidate at term 2"} {"level":"info","ts":"2026-07-21T06:39:00.412009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e received MsgVoteResp from c51d7a9ea5537d6e at term 2"} {"level":"info","ts":"2026-07-21T06:39:00.412017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became leader at term 2"} {"level":"info","ts":"2026-07-21T06:39:00.412027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c51d7a9ea5537d6e elected leader c51d7a9ea5537d6e at term 2"} {"level":"info","ts":"2026-07-21T06:39:00.414182Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:39:00.417398Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bea7a5ea8f053dc3","local-member-id":"c51d7a9ea5537d6e","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:39:00.417448Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:39:00.417459Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:39:00.417475Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c51d7a9ea5537d6e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.237:2379]}","request-path":"/0/members/c51d7a9ea5537d6e/attributes","cluster-id":"bea7a5ea8f053dc3","publish-timeout":"7s"} {"level":"info","ts":"2026-07-21T06:39:00.417552Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T06:39:00.417545Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T06:39:00.418398Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-21T06:39:00.418422Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-21T06:39:00.419996Z","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:39:00.421328Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.237:2379"} {"level":"info","ts":"2026-07-21T06:39:13.646217Z","caller":"traceutil/trace.go:171","msg":"trace[771213544] linearizableReadLoop","detail":"{readStateIndex:373; appliedIndex:372; }","duration":"375.569108ms","start":"2026-07-21T06:39:13.270624Z","end":"2026-07-21T06:39:13.646193Z","steps":["trace[771213544] 'read index received' (duration: 375.34676ms)","trace[771213544] 'applied index is now lower than readState.Index' (duration: 221.208µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:39:13.646281Z","caller":"traceutil/trace.go:171","msg":"trace[1446541055] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"656.075052ms","start":"2026-07-21T06:39:12.990176Z","end":"2026-07-21T06:39:13.646251Z","steps":["trace[1446541055] 'process raft request' (duration: 655.878295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:13.64643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.779485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-21T06:39:13.646543Z","caller":"traceutil/trace.go:171","msg":"trace[316057024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:361; }","duration":"375.944211ms","start":"2026-07-21T06:39:13.270587Z","end":"2026-07-21T06:39:13.646532Z","steps":["trace[316057024] 'agreement among raft nodes before linearized reading' (duration: 375.778955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:13.646579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:13.27057Z","time spent":"376.000823ms","remote":"127.0.0.1:59234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T06:39:13.646809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:12.99016Z","time spent":"656.187346ms","remote":"127.0.0.1:59234","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:39:14.673637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.40458ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-07-21T06:39:14.673897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:13.647551Z","time spent":"1.026339894s","remote":"127.0.0.1:59000","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:39:14.674046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.95057ms","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:39:14.674098Z","caller":"traceutil/trace.go:171","msg":"trace[799448623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:362; }","duration":"673.035513ms","start":"2026-07-21T06:39:14.00105Z","end":"2026-07-21T06:39:14.674085Z","steps":["trace[799448623] 'agreement among raft nodes before linearized reading' (duration: 672.915299ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:14.673927Z","caller":"traceutil/trace.go:171","msg":"trace[99694205] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:373; }","duration":"672.824464ms","start":"2026-07-21T06:39:14.001081Z","end":"2026-07-21T06:39:14.673906Z","steps":["trace[99694205] 'read index received' (duration: 210.935887ms)","trace[99694205] 'applied index is now lower than readState.Index' (duration: 461.887417ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:39:14.674165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:14.001031Z","time spent":"673.120085ms","remote":"127.0.0.1:59234","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":"info","ts":"2026-07-21T06:39:14.674191Z","caller":"traceutil/trace.go:171","msg":"trace[476620147] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"1.023895109s","start":"2026-07-21T06:39:13.650264Z","end":"2026-07-21T06:39:14.674159Z","steps":["trace[476620147] 'process raft request' (duration: 1.023497814s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:14.674316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.273092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-21T06:39:14.674362Z","caller":"traceutil/trace.go:171","msg":"trace[308304885] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:362; }","duration":"393.347944ms","start":"2026-07-21T06:39:14.281002Z","end":"2026-07-21T06:39:14.67435Z","steps":["trace[308304885] 'agreement among raft nodes before linearized reading' (duration: 393.23269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:14.674427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:14.280982Z","time spent":"393.435288ms","remote":"127.0.0.1:59234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T06:39:14.674445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.52456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:39:14.674498Z","caller":"traceutil/trace.go:171","msg":"trace[765781199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:362; }","duration":"263.600382ms","start":"2026-07-21T06:39:14.410882Z","end":"2026-07-21T06:39:14.674482Z","steps":["trace[765781199] 'agreement among raft nodes before linearized reading' (duration: 263.499099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:14.675304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:13.650249Z","time spent":"1.024017312s","remote":"127.0.0.1:59234","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:39:14.945485Z","caller":"traceutil/trace.go:171","msg":"trace[1730733862] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"269.990712ms","start":"2026-07-21T06:39:14.675474Z","end":"2026-07-21T06:39:14.945465Z","steps":["trace[1730733862] 'process raft request' (duration: 269.046998ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:14.951865Z","caller":"traceutil/trace.go:171","msg":"trace[71430656] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"272.365807ms","start":"2026-07-21T06:39:14.679483Z","end":"2026-07-21T06:39:14.951849Z","steps":["trace[71430656] 'process raft request' (duration: 272.16355ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:14.951878Z","caller":"traceutil/trace.go:171","msg":"trace[738627105] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"272.329796ms","start":"2026-07-21T06:39:14.679529Z","end":"2026-07-21T06:39:14.951859Z","steps":["trace[738627105] 'process raft request' (duration: 272.234642ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:14.952083Z","caller":"traceutil/trace.go:171","msg":"trace[120663614] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"270.355375ms","start":"2026-07-21T06:39:14.6817Z","end":"2026-07-21T06:39:14.952055Z","steps":["trace[120663614] 'process raft request' (duration: 270.112296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:25.494149Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9038336888883046035,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T06:39:25.61963Z","caller":"traceutil/trace.go:171","msg":"trace[1087376904] linearizableReadLoop","detail":"{readStateIndex:406; appliedIndex:405; }","duration":"625.668066ms","start":"2026-07-21T06:39:24.993941Z","end":"2026-07-21T06:39:25.619609Z","steps":["trace[1087376904] 'read index received' (duration: 625.490228ms)","trace[1087376904] 'applied index is now lower than readState.Index' (duration: 177.118µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:39:25.619734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.77294ms","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:39:25.619765Z","caller":"traceutil/trace.go:171","msg":"trace[512035865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:391; }","duration":"625.832343ms","start":"2026-07-21T06:39:24.993923Z","end":"2026-07-21T06:39:25.619755Z","steps":["trace[512035865] 'agreement among raft nodes before linearized reading' (duration: 625.7667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:25.619797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:24.993916Z","time spent":"625.873964ms","remote":"127.0.0.1:59234","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":"info","ts":"2026-07-21T06:39:25.619878Z","caller":"traceutil/trace.go:171","msg":"trace[558026862] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"626.525382ms","start":"2026-07-21T06:39:24.993314Z","end":"2026-07-21T06:39:25.619839Z","steps":["trace[558026862] 'process raft request' (duration: 626.169826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:25.620038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:24.993299Z","time spent":"626.657338ms","remote":"127.0.0.1:59234","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:39:26.131455Z","caller":"traceutil/trace.go:171","msg":"trace[1221998336] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"507.149336ms","start":"2026-07-21T06:39:25.624269Z","end":"2026-07-21T06:39:26.131418Z","steps":["trace[1221998336] 'process raft request' (duration: 483.753058ms)","trace[1221998336] 'compare' (duration: 23.144577ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:39:26.131573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:25.624239Z","time spent":"507.269761ms","remote":"127.0.0.1:59234","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:39:26.131671Z","caller":"traceutil/trace.go:171","msg":"trace[1254665092] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"135.659253ms","start":"2026-07-21T06:39:25.995985Z","end":"2026-07-21T06:39:26.131645Z","steps":["trace[1254665092] 'process raft request' (duration: 135.425633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:26.131882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.13177ms","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:39:26.131918Z","caller":"traceutil/trace.go:171","msg":"trace[156676134] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:393; }","duration":"345.203292ms","start":"2026-07-21T06:39:25.786705Z","end":"2026-07-21T06:39:26.131909Z","steps":["trace[156676134] 'agreement among raft nodes before linearized reading' (duration: 345.109078ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:26.131797Z","caller":"traceutil/trace.go:171","msg":"trace[326634698] linearizableReadLoop","detail":"{readStateIndex:407; appliedIndex:406; }","duration":"345.030525ms","start":"2026-07-21T06:39:25.786756Z","end":"2026-07-21T06:39:26.131786Z","steps":["trace[326634698] 'read index received' (duration: 321.26374ms)","trace[326634698] 'applied index is now lower than readState.Index' (duration: 23.765425ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:39:26.132113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.59571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"warn","ts":"2026-07-21T06:39:26.13195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:25.786687Z","time spent":"345.256465ms","remote":"127.0.0.1:59234","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:39:26.132147Z","caller":"traceutil/trace.go:171","msg":"trace[1784708236] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:393; }","duration":"205.664143ms","start":"2026-07-21T06:39:25.926473Z","end":"2026-07-21T06:39:26.132137Z","steps":["trace[1784708236] 'agreement among raft nodes before linearized reading' (duration: 205.59275ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:41.00722Z","caller":"traceutil/trace.go:171","msg":"trace[1893413289] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"217.874572ms","start":"2026-07-21T06:39:40.789323Z","end":"2026-07-21T06:39:41.007198Z","steps":["trace[1893413289] 'process raft request' (duration: 217.735525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:41.492919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.499147ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:39:41.492999Z","caller":"traceutil/trace.go:171","msg":"trace[1938712446] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:511; }","duration":"115.589772ms","start":"2026-07-21T06:39:41.377397Z","end":"2026-07-21T06:39:41.492987Z","steps":["trace[1938712446] 'range keys from in-memory index tree' (duration: 115.490537ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:41.493166Z","caller":"traceutil/trace.go:171","msg":"trace[768789472] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"128.562346ms","start":"2026-07-21T06:39:41.364598Z","end":"2026-07-21T06:39:41.49316Z","steps":["trace[768789472] 'process raft request' (duration: 127.910623ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:41.500416Z","caller":"traceutil/trace.go:171","msg":"trace[459809839] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"131.445281ms","start":"2026-07-21T06:39:41.368945Z","end":"2026-07-21T06:39:41.500391Z","steps":["trace[459809839] 'process raft request' (duration: 130.891163ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:41.500571Z","caller":"traceutil/trace.go:171","msg":"trace[1988562928] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"123.347273ms","start":"2026-07-21T06:39:41.377213Z","end":"2026-07-21T06:39:41.50056Z","steps":["trace[1988562928] 'process raft request' (duration: 122.844128ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:41.500598Z","caller":"traceutil/trace.go:171","msg":"trace[630542420] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"131.401259ms","start":"2026-07-21T06:39:41.36919Z","end":"2026-07-21T06:39:41.500591Z","steps":["trace[630542420] 'process raft request' (duration: 130.764287ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:41.500617Z","caller":"traceutil/trace.go:171","msg":"trace[611907422] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"123.701631ms","start":"2026-07-21T06:39:41.376911Z","end":"2026-07-21T06:39:41.500613Z","steps":["trace[611907422] 'process raft request' (duration: 123.07521ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:41.500891Z","caller":"traceutil/trace.go:171","msg":"trace[1417211199] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"123.641438ms","start":"2026-07-21T06:39:41.376989Z","end":"2026-07-21T06:39:41.500631Z","steps":["trace[1417211199] 'process raft request' (duration: 123.029777ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:41.505836Z","caller":"traceutil/trace.go:171","msg":"trace[589078063] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"121.882939ms","start":"2026-07-21T06:39:41.383926Z","end":"2026-07-21T06:39:41.505809Z","steps":["trace[589078063] 'process raft request' (duration: 116.161511ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:42.983828Z","caller":"traceutil/trace.go:171","msg":"trace[1630685927] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"234.567325ms","start":"2026-07-21T06:39:42.749223Z","end":"2026-07-21T06:39:42.98379Z","steps":["trace[1630685927] 'process raft request' (duration: 230.008824ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:42.983891Z","caller":"traceutil/trace.go:171","msg":"trace[1095961015] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"231.754213ms","start":"2026-07-21T06:39:42.752103Z","end":"2026-07-21T06:39:42.983857Z","steps":["trace[1095961015] 'process raft request' (duration: 231.566873ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:42.984057Z","caller":"traceutil/trace.go:171","msg":"trace[922634299] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"149.847462ms","start":"2026-07-21T06:39:42.834203Z","end":"2026-07-21T06:39:42.98405Z","steps":["trace[922634299] 'process raft request' (duration: 149.594609ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:42.983547Z","caller":"traceutil/trace.go:171","msg":"trace[1880367050] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"233.730223ms","start":"2026-07-21T06:39:42.749795Z","end":"2026-07-21T06:39:42.983525Z","steps":["trace[1880367050] 'read index received' (duration: 229.448726ms)","trace[1880367050] 'applied index is now lower than readState.Index' (duration: 4.280857ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:39:42.984292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.477191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backends.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:18056"} {"level":"info","ts":"2026-07-21T06:39:42.984334Z","caller":"traceutil/trace.go:171","msg":"trace[906949574] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backends.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:594; }","duration":"234.555274ms","start":"2026-07-21T06:39:42.749764Z","end":"2026-07-21T06:39:42.984319Z","steps":["trace[906949574] 'agreement among raft nodes before linearized reading' (duration: 234.408717ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:44.176062Z","caller":"traceutil/trace.go:171","msg":"trace[2120926141] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"434.714235ms","start":"2026-07-21T06:39:43.741331Z","end":"2026-07-21T06:39:44.176045Z","steps":["trace[2120926141] 'read index received' (duration: 434.564347ms)","trace[2120926141] 'applied index is now lower than readState.Index' (duration: 149.118µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:39:44.17614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:43.596747Z","time spent":"579.390665ms","remote":"127.0.0.1:59000","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:39:44.176307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.961348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:39:44.176327Z","caller":"traceutil/trace.go:171","msg":"trace[600461687] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:604; }","duration":"435.025621ms","start":"2026-07-21T06:39:43.741294Z","end":"2026-07-21T06:39:44.176319Z","steps":["trace[600461687] 'agreement among raft nodes before linearized reading' (duration: 434.978468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:44.176342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:43.741276Z","time spent":"435.061983ms","remote":"127.0.0.1:59002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-21T06:39:44.176493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.465267ms","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:39:44.176507Z","caller":"traceutil/trace.go:171","msg":"trace[1024810756] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:604; }","duration":"429.501908ms","start":"2026-07-21T06:39:43.747001Z","end":"2026-07-21T06:39:44.176503Z","steps":["trace[1024810756] 'agreement among raft nodes before linearized reading' (duration: 429.409864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:44.176519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:43.74699Z","time spent":"429.526809ms","remote":"127.0.0.1:59234","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:39:44.176851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.465871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:39:44.176953Z","caller":"traceutil/trace.go:171","msg":"trace[1641975869] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:604; }","duration":"235.585947ms","start":"2026-07-21T06:39:43.941319Z","end":"2026-07-21T06:39:44.176905Z","steps":["trace[1641975869] 'agreement among raft nodes before linearized reading' (duration: 235.45511ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:44.352029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.946584ms","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:39:44.352073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.675288ms","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:39:44.352107Z","caller":"traceutil/trace.go:171","msg":"trace[467131668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:606; }","duration":"133.062449ms","start":"2026-07-21T06:39:44.219025Z","end":"2026-07-21T06:39:44.352087Z","steps":["trace[467131668] 'range keys from in-memory index tree' (duration: 132.784966ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:44.352144Z","caller":"traceutil/trace.go:171","msg":"trace[402148316] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:606; }","duration":"131.770304ms","start":"2026-07-21T06:39:44.220358Z","end":"2026-07-21T06:39:44.352128Z","steps":["trace[402148316] 'range keys from in-memory index tree' (duration: 131.570603ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:50.525574Z","caller":"traceutil/trace.go:171","msg":"trace[1353461271] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"298.722823ms","start":"2026-07-21T06:39:50.226821Z","end":"2026-07-21T06:39:50.525544Z","steps":["trace[1353461271] 'process raft request' (duration: 298.385815ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:51.226985Z","caller":"traceutil/trace.go:171","msg":"trace[972620674] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"126.283692ms","start":"2026-07-21T06:39:51.10068Z","end":"2026-07-21T06:39:51.226963Z","steps":["trace[972620674] 'process raft request' (duration: 126.144554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:53.041159Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9038336888883046542,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T06:39:53.108396Z","caller":"traceutil/trace.go:171","msg":"trace[2111167013] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:689; }","duration":"567.334474ms","start":"2026-07-21T06:39:52.541022Z","end":"2026-07-21T06:39:53.108356Z","steps":["trace[2111167013] 'read index received' (duration: 567.119382ms)","trace[2111167013] 'applied index is now lower than readState.Index' (duration: 214.222µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:39:53.108636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.593658ms","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:39:53.108649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.859128ms","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:39:53.108687Z","caller":"traceutil/trace.go:171","msg":"trace[19455072] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:666; }","duration":"567.701704ms","start":"2026-07-21T06:39:52.540977Z","end":"2026-07-21T06:39:53.108679Z","steps":["trace[19455072] 'agreement among raft nodes before linearized reading' (duration: 567.494923ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:53.108718Z","caller":"traceutil/trace.go:171","msg":"trace[846724940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:666; }","duration":"530.966694ms","start":"2026-07-21T06:39:52.577727Z","end":"2026-07-21T06:39:53.108694Z","steps":["trace[846724940] 'agreement among raft nodes before linearized reading' (duration: 530.802385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:53.108734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:52.540956Z","time spent":"567.770998ms","remote":"127.0.0.1:59234","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:39:53.10875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:52.577708Z","time spent":"531.033648ms","remote":"127.0.0.1:59234","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:39:53.108757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.779204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-21T06:39:53.108813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.466106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-l8vfj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-21T06:39:53.108869Z","caller":"traceutil/trace.go:171","msg":"trace[433245043] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:666; }","duration":"530.917851ms","start":"2026-07-21T06:39:52.577925Z","end":"2026-07-21T06:39:53.108843Z","steps":["trace[433245043] 'agreement among raft nodes before linearized reading' (duration: 530.71487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:53.108903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:52.577908Z","time spent":"530.986755ms","remote":"127.0.0.1:59234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-21T06:39:53.108894Z","caller":"traceutil/trace.go:171","msg":"trace[970743604] range","detail":"{range_begin:/registry/pods/kube-system/cilium-l8vfj; range_end:; response_count:1; response_revision:666; }","duration":"500.598734ms","start":"2026-07-21T06:39:52.608274Z","end":"2026-07-21T06:39:53.108872Z","steps":["trace[970743604] 'agreement among raft nodes before linearized reading' (duration: 500.34572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:53.108988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:52.608254Z","time spent":"500.724361ms","remote":"127.0.0.1:59150","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-l8vfj\" "} {"level":"info","ts":"2026-07-21T06:39:53.108422Z","caller":"traceutil/trace.go:171","msg":"trace[1160050828] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"575.848499ms","start":"2026-07-21T06:39:52.532545Z","end":"2026-07-21T06:39:53.108394Z","steps":["trace[1160050828] 'process raft request' (duration: 575.685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:53.110191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:52.53253Z","time spent":"576.596341ms","remote":"127.0.0.1:59234","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:39:53.927191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.767805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T06:39:53.927233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.442556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.237\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-21T06:39:53.92726Z","caller":"traceutil/trace.go:171","msg":"trace[1978314851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:672; }","duration":"514.87415ms","start":"2026-07-21T06:39:53.412371Z","end":"2026-07-21T06:39:53.927245Z","steps":["trace[1978314851] 'range keys from in-memory index tree' (duration: 514.649028ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:53.927299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:53.412355Z","time spent":"514.937543ms","remote":"127.0.0.1:58950","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:39:53.927299Z","caller":"traceutil/trace.go:171","msg":"trace[878240789] range","detail":"{range_begin:/registry/masterleases/199.19.213.237; range_end:; response_count:1; response_revision:672; }","duration":"340.544351ms","start":"2026-07-21T06:39:53.586738Z","end":"2026-07-21T06:39:53.927282Z","steps":["trace[878240789] 'range keys from in-memory index tree' (duration: 340.288517ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:53.927339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:53.586723Z","time spent":"340.608105ms","remote":"127.0.0.1:59000","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.237\" "} {"level":"warn","ts":"2026-07-21T06:39:55.624642Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9038336888883046562,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T06:39:56.111391Z","caller":"traceutil/trace.go:171","msg":"trace[2087172917] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"987.041208ms","start":"2026-07-21T06:39:55.124326Z","end":"2026-07-21T06:39:56.111368Z","steps":["trace[2087172917] 'read index received' (duration: 986.817976ms)","trace[2087172917] 'applied index is now lower than readState.Index' (duration: 222.412µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:39:56.111772Z","caller":"traceutil/trace.go:171","msg":"trace[408909563] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"996.134955ms","start":"2026-07-21T06:39:55.115625Z","end":"2026-07-21T06:39:56.11176Z","steps":["trace[408909563] 'process raft request' (duration: 995.616756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:56.111884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:55.115603Z","time spent":"996.208859ms","remote":"127.0.0.1:59234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:39:56.111946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.867514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:39:56.112036Z","caller":"traceutil/trace.go:171","msg":"trace[1706768863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:674; }","duration":"700.006982ms","start":"2026-07-21T06:39:55.412011Z","end":"2026-07-21T06:39:56.112018Z","steps":["trace[1706768863] 'agreement among raft nodes before linearized reading' (duration: 699.813232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:56.112044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.681974ms","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:39:56.112078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:55.411993Z","time spent":"700.077166ms","remote":"127.0.0.1:58950","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:39:56.11205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.611279ms","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:39:56.112117Z","caller":"traceutil/trace.go:171","msg":"trace[1725282689] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:674; }","duration":"987.79874ms","start":"2026-07-21T06:39:55.1243Z","end":"2026-07-21T06:39:56.112099Z","steps":["trace[1725282689] 'agreement among raft nodes before linearized reading' (duration: 987.544006ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:56.11215Z","caller":"traceutil/trace.go:171","msg":"trace[2033640677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:674; }","duration":"980.768208ms","start":"2026-07-21T06:39:55.131357Z","end":"2026-07-21T06:39:56.112126Z","steps":["trace[2033640677] 'agreement among raft nodes before linearized reading' (duration: 980.428289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:56.112161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:55.124288Z","time spent":"987.865314ms","remote":"127.0.0.1:59234","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:39:56.112211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:55.131347Z","time spent":"980.848202ms","remote":"127.0.0.1:59234","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:39:56.112219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.315294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-21T06:39:56.112304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.271799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-21T06:39:56.112303Z","caller":"traceutil/trace.go:171","msg":"trace[43601159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:674; }","duration":"810.43318ms","start":"2026-07-21T06:39:55.30186Z","end":"2026-07-21T06:39:56.112293Z","steps":["trace[43601159] 'agreement among raft nodes before linearized reading' (duration: 810.25928ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:39:56.112334Z","caller":"traceutil/trace.go:171","msg":"trace[1391714601] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:674; }","duration":"978.324432ms","start":"2026-07-21T06:39:55.134Z","end":"2026-07-21T06:39:56.112324Z","steps":["trace[1391714601] 'agreement among raft nodes before linearized reading' (duration: 978.246307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:39:56.112338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:55.301842Z","time spent":"810.487933ms","remote":"127.0.0.1:59234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T06:39:56.112359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:39:55.133987Z","time spent":"978.366324ms","remote":"127.0.0.1:59234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-21T06:40:09.299411Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9038336888883046748,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T06:40:09.327214Z","caller":"traceutil/trace.go:171","msg":"trace[1191365326] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"627.030943ms","start":"2026-07-21T06:40:08.700161Z","end":"2026-07-21T06:40:09.327191Z","steps":["trace[1191365326] 'process raft request' (duration: 626.833251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:09.327356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:40:08.700147Z","time spent":"627.136069ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4854,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:40:09.336025Z","caller":"traceutil/trace.go:171","msg":"trace[922083313] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:798; }","duration":"537.643999ms","start":"2026-07-21T06:40:08.798365Z","end":"2026-07-21T06:40:09.336009Z","steps":["trace[922083313] 'read index received' (duration: 528.602106ms)","trace[922083313] 'applied index is now lower than readState.Index' (duration: 9.040833ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:40:09.33612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.831361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:40:09.336143Z","caller":"traceutil/trace.go:171","msg":"trace[53132519] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:770; }","duration":"537.913125ms","start":"2026-07-21T06:40:08.798224Z","end":"2026-07-21T06:40:09.336137Z","steps":["trace[53132519] 'agreement among raft nodes before linearized reading' (duration: 537.846051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:09.336164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:40:08.798202Z","time spent":"537.955268ms","remote":"127.0.0.1:59316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:40:12.590296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.524735ms","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:40:12.590397Z","caller":"traceutil/trace.go:171","msg":"trace[941596577] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:788; }","duration":"361.672424ms","start":"2026-07-21T06:40:12.228705Z","end":"2026-07-21T06:40:12.590377Z","steps":["trace[941596577] 'range keys from in-memory index tree' (duration: 361.356336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:12.590388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.255996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T06:40:12.590423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.730511ms","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:40:12.590444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:40:12.228689Z","time spent":"361.745719ms","remote":"127.0.0.1:59234","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:40:12.590475Z","caller":"traceutil/trace.go:171","msg":"trace[209563493] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:788; }","duration":"376.810387ms","start":"2026-07-21T06:40:12.213651Z","end":"2026-07-21T06:40:12.590461Z","steps":["trace[209563493] 'range keys from in-memory index tree' (duration: 376.531659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:12.59053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:40:12.213634Z","time spent":"376.882851ms","remote":"127.0.0.1:59234","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:40:12.590582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.779509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-21T06:40:12.590475Z","caller":"traceutil/trace.go:171","msg":"trace[1592108688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:788; }","duration":"179.381814ms","start":"2026-07-21T06:40:12.411071Z","end":"2026-07-21T06:40:12.590453Z","steps":["trace[1592108688] 'range keys from in-memory index tree' (duration: 179.186922ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:40:12.590648Z","caller":"traceutil/trace.go:171","msg":"trace[772967172] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:788; }","duration":"415.901517ms","start":"2026-07-21T06:40:12.174729Z","end":"2026-07-21T06:40:12.590631Z","steps":["trace[772967172] 'range keys from in-memory index tree' (duration: 415.563286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:12.59079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.333407ms","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:40:12.590827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:40:12.174704Z","time spent":"416.10747ms","remote":"127.0.0.1:59234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-21T06:40:12.590848Z","caller":"traceutil/trace.go:171","msg":"trace[1140293223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:788; }","duration":"342.424292ms","start":"2026-07-21T06:40:12.248407Z","end":"2026-07-21T06:40:12.590831Z","steps":["trace[1140293223] 'range keys from in-memory index tree' (duration: 342.134924ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:12.590906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:40:12.248387Z","time spent":"342.507267ms","remote":"127.0.0.1:59234","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:40:12.59098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.293733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:40:12.591039Z","caller":"traceutil/trace.go:171","msg":"trace[571930548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:788; }","duration":"274.380769ms","start":"2026-07-21T06:40:12.316644Z","end":"2026-07-21T06:40:12.591025Z","steps":["trace[571930548] 'range keys from in-memory index tree' (duration: 274.185817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:12.591104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.598467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-21T06:40:12.59116Z","caller":"traceutil/trace.go:171","msg":"trace[1229007504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:788; }","duration":"358.728475ms","start":"2026-07-21T06:40:12.232415Z","end":"2026-07-21T06:40:12.591144Z","steps":["trace[1229007504] 'range keys from in-memory index tree' (duration: 358.441298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:12.591207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:40:12.232399Z","time spent":"358.797109ms","remote":"127.0.0.1:59234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-21T06:40:12.699216Z","caller":"traceutil/trace.go:171","msg":"trace[2142886371] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"104.45977ms","start":"2026-07-21T06:40:12.59473Z","end":"2026-07-21T06:40:12.699189Z","steps":["trace[2142886371] 'process raft request' (duration: 104.30107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:15.411255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000290968s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-21T06:40:15.411348Z","caller":"traceutil/trace.go:171","msg":"trace[464780388] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000403415s","start":"2026-07-21T06:40:13.410907Z","end":"2026-07-21T06:40:15.411311Z","steps":["trace[464780388] 'agreement among raft nodes before linearized reading' (duration: 2.000314319s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:15.411489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:40:13.410889Z","time spent":"2.000549354s","remote":"127.0.0.1:58950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/21 06:40:15 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-21T06:40:16.273029Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.277509474s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T06:40:16.273751Z","caller":"traceutil/trace.go:171","msg":"trace[1340731664] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"3.678707356s","start":"2026-07-21T06:40:12.595017Z","end":"2026-07-21T06:40:16.273725Z","steps":["trace[1340731664] 'process raft request' (duration: 3.678475842s)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:40:16.273828Z","caller":"traceutil/trace.go:171","msg":"trace[1882674214] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"3.678786821s","start":"2026-07-21T06:40:12.59503Z","end":"2026-07-21T06:40:16.273817Z","steps":["trace[1882674214] 'process raft request' (duration: 3.67861264s)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:40:16.27382Z","caller":"traceutil/trace.go:171","msg":"trace[1628905216] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"3.678008923s","start":"2026-07-21T06:40:12.595785Z","end":"2026-07-21T06:40:16.273794Z","steps":["trace[1628905216] 'process raft request' (duration: 3.677913547s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:16.273879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:40:12.594981Z","time spent":"3.678830883s","remote":"127.0.0.1:59234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:40:16.273927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:40:12.595003Z","time spent":"3.678859225s","remote":"127.0.0.1:59234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:40:16.273941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:40:12.595765Z","time spent":"3.678113299s","remote":"127.0.0.1:59234","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:40:16.273751Z","caller":"traceutil/trace.go:171","msg":"trace[304280417] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:818; }","duration":"3.672132295s","start":"2026-07-21T06:40:12.601599Z","end":"2026-07-21T06:40:16.273732Z","steps":["trace[304280417] 'read index received' (duration: 97.424152ms)","trace[304280417] 'applied index is now lower than readState.Index' (duration: 3.574707503s)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:40:16.274097Z","caller":"traceutil/trace.go:171","msg":"trace[979897587] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"3.678570638s","start":"2026-07-21T06:40:12.595514Z","end":"2026-07-21T06:40:16.274085Z","steps":["trace[979897587] 'process raft request' (duration: 3.678159923s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:16.27413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:40:12.595503Z","time spent":"3.67860971s","remote":"127.0.0.1:59234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:40:16.274173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.672534151s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:40:16.274269Z","caller":"traceutil/trace.go:171","msg":"trace[307233565] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:793; }","duration":"3.672684099s","start":"2026-07-21T06:40:12.601567Z","end":"2026-07-21T06:40:16.274251Z","steps":["trace[307233565] 'agreement among raft nodes before linearized reading' (duration: 3.67236779s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:16.274331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:40:12.601558Z","time spent":"3.672765555s","remote":"127.0.0.1:59338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":52,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-07-21T06:40:16.353972Z","caller":"traceutil/trace.go:171","msg":"trace[2100348329] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"3.584939468s","start":"2026-07-21T06:40:12.768996Z","end":"2026-07-21T06:40:16.353935Z","steps":["trace[2100348329] 'process raft request' (duration: 3.558702924s)","trace[2100348329] 'compare' (duration: 25.83346ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:40:16.354108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.766425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.237\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-21T06:40:16.354142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:40:12.76897Z","time spent":"3.585086148s","remote":"127.0.0.1:59206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:40:16.354454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.650839065s","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:40:16.354491Z","caller":"traceutil/trace.go:171","msg":"trace[2109480165] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:795; }","duration":"1.650910719s","start":"2026-07-21T06:40:14.703571Z","end":"2026-07-21T06:40:16.354482Z","steps":["trace[2109480165] 'agreement among raft nodes before linearized reading' (duration: 1.650781491s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:16.354514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:40:14.703548Z","time spent":"1.650960563s","remote":"127.0.0.1:59234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-21T06:40:16.354466Z","caller":"traceutil/trace.go:171","msg":"trace[1381625857] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"2.654810695s","start":"2026-07-21T06:40:13.699579Z","end":"2026-07-21T06:40:16.35439Z","steps":["trace[1381625857] 'process raft request' (duration: 2.654142753s)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:40:16.354199Z","caller":"traceutil/trace.go:171","msg":"trace[1989798988] range","detail":"{range_begin:/registry/masterleases/199.19.213.237; range_end:; response_count:1; response_revision:795; }","duration":"2.766555069s","start":"2026-07-21T06:40:13.587623Z","end":"2026-07-21T06:40:16.354178Z","steps":["trace[1989798988] 'agreement among raft nodes before linearized reading' (duration: 2.766307033s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:16.354904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:40:13.587605Z","time spent":"2.767271133s","remote":"127.0.0.1:59000","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.237\" "} {"level":"warn","ts":"2026-07-21T06:40:16.354599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.886423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T06:40:16.35501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:40:13.699552Z","time spent":"2.655203539s","remote":"127.0.0.1:59234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:40:16.355046Z","caller":"traceutil/trace.go:171","msg":"trace[1045329322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"943.360132ms","start":"2026-07-21T06:40:15.411668Z","end":"2026-07-21T06:40:16.355028Z","steps":["trace[1045329322] 'agreement among raft nodes before linearized reading' (duration: 942.908374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:16.355186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:40:15.411652Z","time spent":"943.441647ms","remote":"127.0.0.1:58958","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:40:18.427175Z","caller":"traceutil/trace.go:171","msg":"trace[1533725869] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"145.068743ms","start":"2026-07-21T06:40:18.282084Z","end":"2026-07-21T06:40:18.427153Z","steps":["trace[1533725869] 'process raft request' (duration: 144.935885ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:40:18.447186Z","caller":"traceutil/trace.go:171","msg":"trace[1177582011] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"164.811215ms","start":"2026-07-21T06:40:18.282327Z","end":"2026-07-21T06:40:18.447138Z","steps":["trace[1177582011] 'process raft request' (duration: 164.503196ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:40:18.447513Z","caller":"traceutil/trace.go:171","msg":"trace[875682269] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"164.337345ms","start":"2026-07-21T06:40:18.283159Z","end":"2026-07-21T06:40:18.447497Z","steps":["trace[875682269] 'process raft request' (duration: 164.136363ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:40:18.447578Z","caller":"traceutil/trace.go:171","msg":"trace[2098033315] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"164.462083ms","start":"2026-07-21T06:40:18.28309Z","end":"2026-07-21T06:40:18.447552Z","steps":["trace[2098033315] 'process raft request' (duration: 163.974102ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:40:20.078252Z","caller":"traceutil/trace.go:171","msg":"trace[929219573] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"153.706525ms","start":"2026-07-21T06:40:19.924522Z","end":"2026-07-21T06:40:20.078228Z","steps":["trace[929219573] 'process raft request' (duration: 153.583538ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:40:22.158856Z","caller":"traceutil/trace.go:171","msg":"trace[1338048897] linearizableReadLoop","detail":"{readStateIndex:884; appliedIndex:883; }","duration":"168.554162ms","start":"2026-07-21T06:40:21.990281Z","end":"2026-07-21T06:40:22.158836Z","steps":["trace[1338048897] 'read index received' (duration: 168.403152ms)","trace[1338048897] 'applied index is now lower than readState.Index' (duration: 150.18µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:40:22.158978Z","caller":"traceutil/trace.go:171","msg":"trace[2046411531] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"408.192925ms","start":"2026-07-21T06:40:21.750765Z","end":"2026-07-21T06:40:22.158958Z","steps":["trace[2046411531] 'process raft request' (duration: 407.925698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:22.15911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.818239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/eg\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T06:40:22.159156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:40:21.750746Z","time spent":"408.2885ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5662,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:40:22.15917Z","caller":"traceutil/trace.go:171","msg":"trace[846259682] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/eg; range_end:; response_count:0; response_revision:852; }","duration":"168.910105ms","start":"2026-07-21T06:40:21.990243Z","end":"2026-07-21T06:40:22.159153Z","steps":["trace[846259682] 'agreement among raft nodes before linearized reading' (duration: 168.684201ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:40:22.349784Z","caller":"traceutil/trace.go:171","msg":"trace[1983318023] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"184.361646ms","start":"2026-07-21T06:40:22.165403Z","end":"2026-07-21T06:40:22.349765Z","steps":["trace[1983318023] 'process raft request' (duration: 180.433477ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:40:22.358612Z","caller":"traceutil/trace.go:171","msg":"trace[513922638] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"163.134172ms","start":"2026-07-21T06:40:22.195454Z","end":"2026-07-21T06:40:22.358588Z","steps":["trace[513922638] 'process raft request' (duration: 162.995433ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:40:24.313155Z","caller":"traceutil/trace.go:171","msg":"trace[1434646473] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"173.778603ms","start":"2026-07-21T06:40:24.139329Z","end":"2026-07-21T06:40:24.313107Z","steps":["trace[1434646473] 'process raft request' (duration: 134.882643ms)","trace[1434646473] 'compare' (duration: 38.456042ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:40:24.48063Z","caller":"traceutil/trace.go:171","msg":"trace[338288539] linearizableReadLoop","detail":"{readStateIndex:900; appliedIndex:898; }","duration":"162.198265ms","start":"2026-07-21T06:40:24.318409Z","end":"2026-07-21T06:40:24.480607Z","steps":["trace[338288539] 'read index received' (duration: 142.123555ms)","trace[338288539] 'applied index is now lower than readState.Index' (duration: 20.07371ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:40:24.481006Z","caller":"traceutil/trace.go:171","msg":"trace[1148659001] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"333.864193ms","start":"2026-07-21T06:40:24.147106Z","end":"2026-07-21T06:40:24.48097Z","steps":["trace[1148659001] 'process raft request' (duration: 333.441086ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:40:24.481077Z","caller":"traceutil/trace.go:171","msg":"trace[382119592] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"341.447236ms","start":"2026-07-21T06:40:24.139592Z","end":"2026-07-21T06:40:24.481039Z","steps":["trace[382119592] 'process raft request' (duration: 320.972041ms)","trace[382119592] 'compare' (duration: 19.821484ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:40:24.481152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:40:24.147091Z","time spent":"333.985691ms","remote":"127.0.0.1:48476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2316,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:40:24.481233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:40:24.139573Z","time spent":"341.584875ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3856,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:40:24.481252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.829795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-8rdmk\" ","response":"range_response_count:1 size:3871"} {"level":"info","ts":"2026-07-21T06:40:24.48132Z","caller":"traceutil/trace.go:171","msg":"trace[1320592538] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-8rdmk; range_end:; response_count:1; response_revision:867; }","duration":"162.929491ms","start":"2026-07-21T06:40:24.318372Z","end":"2026-07-21T06:40:24.481302Z","steps":["trace[1320592538] 'agreement among raft nodes before linearized reading' (duration: 162.715737ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:40:24.521504Z","caller":"traceutil/trace.go:171","msg":"trace[838112383] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"202.983975ms","start":"2026-07-21T06:40:24.318496Z","end":"2026-07-21T06:40:24.52148Z","steps":["trace[838112383] 'process raft request' (duration: 202.857707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:24.52217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.502151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:40:24.522216Z","caller":"traceutil/trace.go:171","msg":"trace[2105174141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:868; }","duration":"111.608027ms","start":"2026-07-21T06:40:24.410596Z","end":"2026-07-21T06:40:24.522204Z","steps":["trace[2105174141] 'agreement among raft nodes before linearized reading' (duration: 111.506461ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:40:24.620606Z","caller":"traceutil/trace.go:171","msg":"trace[1651848814] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"132.859113ms","start":"2026-07-21T06:40:24.487724Z","end":"2026-07-21T06:40:24.620583Z","steps":["trace[1651848814] 'process raft request' (duration: 131.271632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:24.683252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.040572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"warn","ts":"2026-07-21T06:40:24.683317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.926489ms","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:40:24.68334Z","caller":"traceutil/trace.go:171","msg":"trace[788287229] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:870; }","duration":"135.969472ms","start":"2026-07-21T06:40:24.547363Z","end":"2026-07-21T06:40:24.683333Z","steps":["trace[788287229] 'agreement among raft nodes before linearized reading' (duration: 135.7942ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:40:24.683306Z","caller":"traceutil/trace.go:171","msg":"trace[1935104453] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"158.163117ms","start":"2026-07-21T06:40:24.525103Z","end":"2026-07-21T06:40:24.683267Z","steps":["trace[1935104453] 'process raft request' (duration: 157.88312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:24.683346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.053453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-21T06:40:24.683371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.93883ms","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:40:24.683412Z","caller":"traceutil/trace.go:171","msg":"trace[1710821288] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:870; }","duration":"111.146999ms","start":"2026-07-21T06:40:24.57225Z","end":"2026-07-21T06:40:24.683397Z","steps":["trace[1710821288] 'agreement among raft nodes before linearized reading' (duration: 110.876241ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:40:24.68342Z","caller":"traceutil/trace.go:171","msg":"trace[1300982058] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:870; }","duration":"136.007494ms","start":"2026-07-21T06:40:24.547406Z","end":"2026-07-21T06:40:24.683413Z","steps":["trace[1300982058] 'agreement among raft nodes before linearized reading' (duration: 135.924518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:24.683574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.269627ms","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:40:24.683602Z","caller":"traceutil/trace.go:171","msg":"trace[822346228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:870; }","duration":"111.321329ms","start":"2026-07-21T06:40:24.572274Z","end":"2026-07-21T06:40:24.683595Z","steps":["trace[822346228] 'agreement among raft nodes before linearized reading' (duration: 111.259675ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:40:24.683314Z","caller":"traceutil/trace.go:171","msg":"trace[1409508540] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:870; }","duration":"127.131488ms","start":"2026-07-21T06:40:24.556169Z","end":"2026-07-21T06:40:24.6833Z","steps":["trace[1409508540] 'agreement among raft nodes before linearized reading' (duration: 127.033771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:24.683252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.018081ms","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:40:24.683706Z","caller":"traceutil/trace.go:171","msg":"trace[183678477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:870; }","duration":"111.497671ms","start":"2026-07-21T06:40:24.572202Z","end":"2026-07-21T06:40:24.683699Z","steps":["trace[183678477] 'agreement among raft nodes before linearized reading' (duration: 110.920864ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:40:31.293889Z","caller":"traceutil/trace.go:171","msg":"trace[1284799559] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"393.006562ms","start":"2026-07-21T06:40:30.900867Z","end":"2026-07-21T06:40:31.293873Z","steps":["trace[1284799559] 'process raft request' (duration: 392.864603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:40:31.294201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:40:30.900847Z","time spent":"393.28557ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5472,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:40:31.405256Z","caller":"traceutil/trace.go:171","msg":"trace[242997461] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"106.198307ms","start":"2026-07-21T06:40:31.29903Z","end":"2026-07-21T06:40:31.405229Z","steps":["trace[242997461] 'process raft request' (duration: 72.494118ms)","trace[242997461] 'compare' (duration: 33.589131ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:40:31.408618Z","caller":"traceutil/trace.go:171","msg":"trace[1931572581] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"107.906068ms","start":"2026-07-21T06:40:31.300699Z","end":"2026-07-21T06:40:31.408605Z","steps":["trace[1931572581] 'process raft request' (duration: 107.874116ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:40:31.408816Z","caller":"traceutil/trace.go:171","msg":"trace[1770728819] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"109.572906ms","start":"2026-07-21T06:40:31.299222Z","end":"2026-07-21T06:40:31.408795Z","steps":["trace[1770728819] 'process raft request' (duration: 109.260116ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:19.088435Z","caller":"traceutil/trace.go:171","msg":"trace[1486590169] linearizableReadLoop","detail":"{readStateIndex:1175; appliedIndex:1174; }","duration":"131.12434ms","start":"2026-07-21T06:41:18.957286Z","end":"2026-07-21T06:41:19.08841Z","steps":["trace[1486590169] 'read index received' (duration: 48.068227ms)","trace[1486590169] 'applied index is now lower than readState.Index' (duration: 83.054872ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:41:19.088672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.370648ms","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:41:19.088702Z","caller":"traceutil/trace.go:171","msg":"trace[1022589748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1129; }","duration":"131.445743ms","start":"2026-07-21T06:41:18.957249Z","end":"2026-07-21T06:41:19.088695Z","steps":["trace[1022589748] 'agreement among raft nodes before linearized reading' (duration: 131.314094ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:25.233927Z","caller":"traceutil/trace.go:171","msg":"trace[1492475625] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"105.708735ms","start":"2026-07-21T06:41:25.128186Z","end":"2026-07-21T06:41:25.233895Z","steps":["trace[1492475625] 'process raft request' (duration: 105.527172ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:27.443415Z","caller":"traceutil/trace.go:171","msg":"trace[53825033] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"126.492049ms","start":"2026-07-21T06:41:27.31688Z","end":"2026-07-21T06:41:27.443372Z","steps":["trace[53825033] 'process raft request' (duration: 126.342248ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:27.443634Z","caller":"traceutil/trace.go:171","msg":"trace[1473026944] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"126.303745ms","start":"2026-07-21T06:41:27.317307Z","end":"2026-07-21T06:41:27.443611Z","steps":["trace[1473026944] 'process raft request' (duration: 126.024225ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:41:28.488042Z","caller":"traceutil/trace.go:171","msg":"trace[2081707438] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"266.316095ms","start":"2026-07-21T06:41:28.221667Z","end":"2026-07-21T06:41:28.487983Z","steps":["trace[2081707438] 'process raft request' (duration: 266.133262ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:04.248058Z","caller":"traceutil/trace.go:171","msg":"trace[2102745068] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"130.907105ms","start":"2026-07-21T06:42:04.11713Z","end":"2026-07-21T06:42:04.248037Z","steps":["trace[2102745068] 'process raft request' (duration: 130.761584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:04.534926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.555321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:42:04.535034Z","caller":"traceutil/trace.go:171","msg":"trace[391653474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1311; }","duration":"122.706683ms","start":"2026-07-21T06:42:04.412299Z","end":"2026-07-21T06:42:04.535006Z","steps":["trace[391653474] 'range keys from in-memory index tree' (duration: 122.480766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:14.132561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:13.594318Z","time spent":"538.238002ms","remote":"127.0.0.1:59000","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:42:14.132568Z","caller":"traceutil/trace.go:171","msg":"trace[429096830] linearizableReadLoop","detail":"{readStateIndex:1403; appliedIndex:1402; }","duration":"490.943689ms","start":"2026-07-21T06:42:13.641599Z","end":"2026-07-21T06:42:14.132543Z","steps":["trace[429096830] 'read index received' (duration: 479.425283ms)","trace[429096830] 'applied index is now lower than readState.Index' (duration: 11.516976ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:42:14.132822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.019559ms","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:42:14.132859Z","caller":"traceutil/trace.go:171","msg":"trace[2013591186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1345; }","duration":"482.103495ms","start":"2026-07-21T06:42:13.650741Z","end":"2026-07-21T06:42:14.132845Z","steps":["trace[2013591186] 'agreement among raft nodes before linearized reading' (duration: 481.963054ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:14.132883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:13.650722Z","time spent":"482.15603ms","remote":"127.0.0.1:59234","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:42:14.13292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.494382ms","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":"warn","ts":"2026-07-21T06:42:14.133003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.929282ms","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:42:14.133041Z","caller":"traceutil/trace.go:171","msg":"trace[225341783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1345; }","duration":"481.988927ms","start":"2026-07-21T06:42:13.65104Z","end":"2026-07-21T06:42:14.133029Z","steps":["trace[225341783] 'agreement among raft nodes before linearized reading' (duration: 481.889389ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:14.133021Z","caller":"traceutil/trace.go:171","msg":"trace[1277928472] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1345; }","duration":"351.674186ms","start":"2026-07-21T06:42:13.781329Z","end":"2026-07-21T06:42:14.133003Z","steps":["trace[1277928472] 'agreement among raft nodes before linearized reading' (duration: 351.476321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:14.132941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.332319ms","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:42:14.133072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:13.651027Z","time spent":"482.039841ms","remote":"127.0.0.1:59234","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:42:14.133102Z","caller":"traceutil/trace.go:171","msg":"trace[1794667289] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1345; }","duration":"491.541995ms","start":"2026-07-21T06:42:13.641551Z","end":"2026-07-21T06:42:14.133093Z","steps":["trace[1794667289] 'agreement among raft nodes before linearized reading' (duration: 491.070469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:14.133102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.229715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-21T06:42:14.133131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:13.641529Z","time spent":"491.594008ms","remote":"127.0.0.1:59234","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:42:14.133108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:13.781305Z","time spent":"351.781394ms","remote":"127.0.0.1:59072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":6,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-07-21T06:42:14.133153Z","caller":"traceutil/trace.go:171","msg":"trace[994869558] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1345; }","duration":"482.314361ms","start":"2026-07-21T06:42:13.650826Z","end":"2026-07-21T06:42:14.13314Z","steps":["trace[994869558] 'agreement among raft nodes before linearized reading' (duration: 482.139698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:14.133198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:13.6508Z","time spent":"482.390147ms","remote":"127.0.0.1:59234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-21T06:42:32.398689Z","caller":"traceutil/trace.go:171","msg":"trace[1134713228] linearizableReadLoop","detail":"{readStateIndex:1475; appliedIndex:1472; }","duration":"112.383784ms","start":"2026-07-21T06:42:32.286286Z","end":"2026-07-21T06:42:32.39867Z","steps":["trace[1134713228] 'read index received' (duration: 48.613247ms)","trace[1134713228] 'applied index is now lower than readState.Index' (duration: 63.769437ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:42:32.398907Z","caller":"traceutil/trace.go:171","msg":"trace[1659475397] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"114.915203ms","start":"2026-07-21T06:42:32.283958Z","end":"2026-07-21T06:42:32.398874Z","steps":["trace[1659475397] 'process raft request' (duration: 113.726926ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:32.398907Z","caller":"traceutil/trace.go:171","msg":"trace[1340731241] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"114.830995ms","start":"2026-07-21T06:42:32.284051Z","end":"2026-07-21T06:42:32.398882Z","steps":["trace[1340731241] 'process raft request' (duration: 114.577503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:32.399044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.736485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/local-path-storage/\" range_end:\"/registry/resourcequotas/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:42:32.399497Z","caller":"traceutil/trace.go:171","msg":"trace[1974380908] range","detail":"{range_begin:/registry/resourcequotas/local-path-storage/; range_end:/registry/resourcequotas/local-path-storage0; response_count:0; response_revision:1413; }","duration":"113.211511ms","start":"2026-07-21T06:42:32.286262Z","end":"2026-07-21T06:42:32.399474Z","steps":["trace[1974380908] 'agreement among raft nodes before linearized reading' (duration: 112.726274ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:32.512797Z","caller":"traceutil/trace.go:171","msg":"trace[2030945970] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"100.500901ms","start":"2026-07-21T06:42:32.41227Z","end":"2026-07-21T06:42:32.512771Z","steps":["trace[2030945970] 'process raft request' (duration: 92.512286ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:33.857704Z","caller":"traceutil/trace.go:171","msg":"trace[1153344393] linearizableReadLoop","detail":"{readStateIndex:1479; appliedIndex:1478; }","duration":"108.173453ms","start":"2026-07-21T06:42:33.749506Z","end":"2026-07-21T06:42:33.857679Z","steps":["trace[1153344393] 'read index received' (duration: 31.745606ms)","trace[1153344393] 'applied index is now lower than readState.Index' (duration: 76.426557ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:42:33.857922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.224952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/default/kube-root-ca.crt\" ","response":"range_response_count:1 size:1738"} {"level":"info","ts":"2026-07-21T06:42:33.857999Z","caller":"traceutil/trace.go:171","msg":"trace[510393085] range","detail":"{range_begin:/registry/configmaps/default/kube-root-ca.crt; range_end:; response_count:1; response_revision:1416; }","duration":"100.310309ms","start":"2026-07-21T06:42:33.757645Z","end":"2026-07-21T06:42:33.857955Z","steps":["trace[510393085] 'agreement among raft nodes before linearized reading' (duration: 100.173733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:33.857957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.438176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/envoy-gateway-system/envoy-gateway-config\" ","response":"range_response_count:1 size:1550"} {"level":"info","ts":"2026-07-21T06:42:33.85773Z","caller":"traceutil/trace.go:171","msg":"trace[254679256] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"172.511162ms","start":"2026-07-21T06:42:33.68519Z","end":"2026-07-21T06:42:33.857701Z","steps":["trace[254679256] 'process raft request' (duration: 96.047207ms)","trace[254679256] 'compare' (duration: 75.723965ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:42:33.858055Z","caller":"traceutil/trace.go:171","msg":"trace[426513490] range","detail":"{range_begin:/registry/configmaps/envoy-gateway-system/envoy-gateway-config; range_end:; response_count:1; response_revision:1416; }","duration":"108.570112ms","start":"2026-07-21T06:42:33.749469Z","end":"2026-07-21T06:42:33.858039Z","steps":["trace[426513490] 'agreement among raft nodes before linearized reading' (duration: 108.330818ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:36.731203Z","caller":"traceutil/trace.go:171","msg":"trace[716922767] linearizableReadLoop","detail":"{readStateIndex:1487; appliedIndex:1486; }","duration":"314.429884ms","start":"2026-07-21T06:42:36.416754Z","end":"2026-07-21T06:42:36.731184Z","steps":["trace[716922767] 'read index received' (duration: 314.288988ms)","trace[716922767] 'applied index is now lower than readState.Index' (duration: 139.536µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:42:36.731275Z","caller":"traceutil/trace.go:171","msg":"trace[62874694] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"316.627728ms","start":"2026-07-21T06:42:36.414624Z","end":"2026-07-21T06:42:36.731252Z","steps":["trace[62874694] 'process raft request' (duration: 316.427384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:36.731484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.707567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-21T06:42:36.731531Z","caller":"traceutil/trace.go:171","msg":"trace[673602408] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1424; }","duration":"314.785696ms","start":"2026-07-21T06:42:36.416735Z","end":"2026-07-21T06:42:36.731521Z","steps":["trace[673602408] 'agreement among raft nodes before linearized reading' (duration: 314.61304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:36.731569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:36.416729Z","time spent":"314.831144ms","remote":"127.0.0.1:59234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-21T06:42:36.731583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:36.414614Z","time spent":"316.848353ms","remote":"127.0.0.1:59234","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:42:36.775743Z","caller":"traceutil/trace.go:171","msg":"trace[812930306] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"251.015862ms","start":"2026-07-21T06:42:36.5247Z","end":"2026-07-21T06:42:36.775715Z","steps":["trace[812930306] 'process raft request' (duration: 250.72825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:36.775838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.667707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-node-lease/kube-root-ca.crt\" ","response":"range_response_count:1 size:1754"} {"level":"info","ts":"2026-07-21T06:42:36.775875Z","caller":"traceutil/trace.go:171","msg":"trace[700740012] range","detail":"{range_begin:/registry/configmaps/kube-node-lease/kube-root-ca.crt; range_end:; response_count:1; response_revision:1425; }","duration":"167.733626ms","start":"2026-07-21T06:42:36.608127Z","end":"2026-07-21T06:42:36.775861Z","steps":["trace[700740012] 'agreement among raft nodes before linearized reading' (duration: 167.672277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:36.77576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.474644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-public/\" range_end:\"/registry/pods/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:42:36.775921Z","caller":"traceutil/trace.go:171","msg":"trace[1536663004] range","detail":"{range_begin:/registry/pods/kube-public/; range_end:/registry/pods/kube-public0; response_count:0; response_revision:1425; }","duration":"295.686319ms","start":"2026-07-21T06:42:36.480224Z","end":"2026-07-21T06:42:36.77591Z","steps":["trace[1536663004] 'agreement among raft nodes before linearized reading' (duration: 295.441386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:36.775986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.503196ms","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:42:36.776107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.463288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-07-21T06:42:36.776141Z","caller":"traceutil/trace.go:171","msg":"trace[1285903620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1425; }","duration":"358.689682ms","start":"2026-07-21T06:42:36.417414Z","end":"2026-07-21T06:42:36.776104Z","steps":["trace[1285903620] 'agreement among raft nodes before linearized reading' (duration: 358.222993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:36.776238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:36.417408Z","time spent":"358.812459ms","remote":"127.0.0.1:59234","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":"info","ts":"2026-07-21T06:42:36.776237Z","caller":"traceutil/trace.go:171","msg":"trace[777449975] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1425; }","duration":"326.635974ms","start":"2026-07-21T06:42:36.449574Z","end":"2026-07-21T06:42:36.77621Z","steps":["trace[777449975] 'agreement among raft nodes before linearized reading' (duration: 326.281253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:36.776313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:36.44956Z","time spent":"326.738962ms","remote":"127.0.0.1:59112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":1489,"request content":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" "} {"level":"warn","ts":"2026-07-21T06:42:36.776623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.67362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-node-lease/\" range_end:\"/registry/persistentvolumeclaims/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:42:36.777002Z","caller":"traceutil/trace.go:171","msg":"trace[1078565822] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-node-lease/; range_end:/registry/persistentvolumeclaims/kube-node-lease0; response_count:0; response_revision:1425; }","duration":"324.570476ms","start":"2026-07-21T06:42:36.452402Z","end":"2026-07-21T06:42:36.776972Z","steps":["trace[1078565822] 'agreement among raft nodes before linearized reading' (duration: 323.394336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:36.777238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:36.452395Z","time spent":"324.81377ms","remote":"127.0.0.1:59112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/kube-node-lease/\" range_end:\"/registry/persistentvolumeclaims/kube-node-lease0\" limit:500 "} {"level":"warn","ts":"2026-07-21T06:42:37.195598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.551859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:42:37.195797Z","caller":"traceutil/trace.go:171","msg":"trace[61587247] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"413.125245ms","start":"2026-07-21T06:42:36.78265Z","end":"2026-07-21T06:42:37.195776Z","steps":["trace[61587247] 'process raft request' (duration: 413.049557ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:42:37.195897Z","caller":"traceutil/trace.go:171","msg":"trace[269972302] linearizableReadLoop","detail":"{readStateIndex:1489; appliedIndex:1488; }","duration":"415.609153ms","start":"2026-07-21T06:42:36.780265Z","end":"2026-07-21T06:42:37.195874Z","steps":["trace[269972302] 'read index received' (duration: 39.687053ms)","trace[269972302] 'applied index is now lower than readState.Index' (duration: 375.91939ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:42:37.195895Z","caller":"traceutil/trace.go:171","msg":"trace[2086807331] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"460.215931ms","start":"2026-07-21T06:42:36.73566Z","end":"2026-07-21T06:42:37.195875Z","steps":["trace[2086807331] 'process raft request' (duration: 84.282741ms)","trace[2086807331] 'compare' (duration: 375.282826ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:42:37.195901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:36.782637Z","time spent":"413.217213ms","remote":"127.0.0.1:59234","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:42:37.195978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:36.735632Z","time spent":"460.301819ms","remote":"127.0.0.1:59234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:42:37.19604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.774469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-07-21T06:42:37.196062Z","caller":"traceutil/trace.go:171","msg":"trace[138311811] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1427; }","duration":"415.830407ms","start":"2026-07-21T06:42:36.780224Z","end":"2026-07-21T06:42:37.196055Z","steps":["trace[138311811] 'agreement among raft nodes before linearized reading' (duration: 415.735299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:37.196082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:36.780206Z","time spent":"415.870417ms","remote":"127.0.0.1:59112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":1489,"request content":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" "} {"level":"warn","ts":"2026-07-21T06:42:37.196066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.335393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-node-lease/kube-root-ca.crt\" ","response":"range_response_count:1 size:1754"} {"level":"info","ts":"2026-07-21T06:42:37.196144Z","caller":"traceutil/trace.go:171","msg":"trace[790139935] range","detail":"{range_begin:/registry/configmaps/kube-node-lease/kube-root-ca.crt; range_end:; response_count:1; response_revision:1427; }","duration":"336.420731ms","start":"2026-07-21T06:42:36.859707Z","end":"2026-07-21T06:42:37.196127Z","steps":["trace[790139935] 'agreement among raft nodes before linearized reading' (duration: 336.279145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:37.196238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:36.859655Z","time spent":"336.570988ms","remote":"127.0.0.1:59058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1778,"request content":"key:\"/registry/configmaps/kube-node-lease/kube-root-ca.crt\" "} {"level":"warn","ts":"2026-07-21T06:42:37.19607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.841926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-public/\" range_end:\"/registry/persistentvolumeclaims/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:42:37.196996Z","caller":"traceutil/trace.go:171","msg":"trace[747502742] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-public/; range_end:/registry/persistentvolumeclaims/kube-public0; response_count:0; response_revision:1427; }","duration":"334.794613ms","start":"2026-07-21T06:42:36.862179Z","end":"2026-07-21T06:42:37.196974Z","steps":["trace[747502742] 'agreement among raft nodes before linearized reading' (duration: 333.831177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:37.197058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:42:36.862139Z","time spent":"334.897331ms","remote":"127.0.0.1:59112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/kube-public/\" range_end:\"/registry/persistentvolumeclaims/kube-public0\" limit:500 "} {"level":"warn","ts":"2026-07-21T06:42:37.474888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.734716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-node-lease/\" range_end:\"/registry/roles/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:42:37.475057Z","caller":"traceutil/trace.go:171","msg":"trace[319596315] range","detail":"{range_begin:/registry/roles/kube-node-lease/; range_end:/registry/roles/kube-node-lease0; response_count:0; response_revision:1427; }","duration":"158.95004ms","start":"2026-07-21T06:42:37.316082Z","end":"2026-07-21T06:42:37.475032Z","steps":["trace[319596315] 'range keys from in-memory index tree' (duration: 158.661867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:42:37.475587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.05288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-07-21T06:42:37.475744Z","caller":"traceutil/trace.go:171","msg":"trace[356990042] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:1427; }","duration":"107.953008ms","start":"2026-07-21T06:42:37.367759Z","end":"2026-07-21T06:42:37.475712Z","steps":["trace[356990042] 'range keys from in-memory index tree' (duration: 106.931813ms)"],"step_count":1}