{"level":"warn","ts":"2026-07-24T04:40:29.577502Z","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-24T04:40:29.577591Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.152: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.152:2380","--initial-cluster=instance=https://199.19.213.152:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.152:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.152: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-24T04:40:29.577682Z","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-24T04:40:29.577694Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.152:2380"]} {"level":"info","ts":"2026-07-24T04:40:29.577715Z","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-24T04:40:29.578417Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.152:2379"]} {"level":"info","ts":"2026-07-24T04:40:29.57849Z","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.152:2380"],"listen-peer-urls":["https://199.19.213.152:2380"],"advertise-client-urls":["https://199.19.213.152:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.152:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.152: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-24T04:40:29.58768Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.266047ms"} {"level":"info","ts":"2026-07-24T04:40:29.609324Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"68d6d88687e6b830","cluster-id":"8a5d84e8c26d99a1"} {"level":"info","ts":"2026-07-24T04:40:29.609485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 switched to configuration voters=()"} {"level":"info","ts":"2026-07-24T04:40:29.609578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 became follower at term 0"} {"level":"info","ts":"2026-07-24T04:40:29.609658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 68d6d88687e6b830 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-24T04:40:29.609678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 became follower at term 1"} {"level":"info","ts":"2026-07-24T04:40:29.609816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 switched to configuration voters=(7554463497277847600)"} {"level":"warn","ts":"2026-07-24T04:40:29.625307Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-24T04:40:29.627659Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-24T04:40:29.629584Z","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-24T04:40:29.634118Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"68d6d88687e6b830","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-24T04:40:29.634327Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"68d6d88687e6b830","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-24T04:40:29.63452Z","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-24T04:40:29.635216Z","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-24T04:40:29.635385Z","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-24T04:40:29.638664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 switched to configuration voters=(7554463497277847600)"} {"level":"info","ts":"2026-07-24T04:40:29.638857Z","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-24T04:40:29.638975Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8a5d84e8c26d99a1","local-member-id":"68d6d88687e6b830","added-peer-id":"68d6d88687e6b830","added-peer-peer-urls":["https://199.19.213.152:2380"]} {"level":"info","ts":"2026-07-24T04:40:29.639089Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"68d6d88687e6b830","initial-advertise-peer-urls":["https://199.19.213.152:2380"],"listen-peer-urls":["https://199.19.213.152:2380"],"advertise-client-urls":["https://199.19.213.152:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.152:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-24T04:40:29.639143Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-24T04:40:29.63912Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.152:2380"} {"level":"info","ts":"2026-07-24T04:40:29.639818Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.152:2380"} {"level":"info","ts":"2026-07-24T04:40:29.710268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 is starting a new election at term 1"} {"level":"info","ts":"2026-07-24T04:40:29.710339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-24T04:40:29.710375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 received MsgPreVoteResp from 68d6d88687e6b830 at term 1"} {"level":"info","ts":"2026-07-24T04:40:29.710395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 became candidate at term 2"} {"level":"info","ts":"2026-07-24T04:40:29.710408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 received MsgVoteResp from 68d6d88687e6b830 at term 2"} {"level":"info","ts":"2026-07-24T04:40:29.710433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 became leader at term 2"} {"level":"info","ts":"2026-07-24T04:40:29.710443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 68d6d88687e6b830 elected leader 68d6d88687e6b830 at term 2"} {"level":"info","ts":"2026-07-24T04:40:29.741223Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:40:29.797409Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"68d6d88687e6b830","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.152:2379]}","request-path":"/0/members/68d6d88687e6b830/attributes","cluster-id":"8a5d84e8c26d99a1","publish-timeout":"7s"} {"level":"info","ts":"2026-07-24T04:40:29.797545Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T04:40:29.799155Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T04:40:29.799481Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-24T04:40:29.799596Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-24T04:40:29.800267Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.152:2379"} {"level":"info","ts":"2026-07-24T04:40:29.802156Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8a5d84e8c26d99a1","local-member-id":"68d6d88687e6b830","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:40:29.809205Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-24T04:40:29.815663Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:40:29.815908Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:40:33.068165Z","caller":"traceutil/trace.go:171","msg":"trace[1066577605] transaction","detail":"{read_only:false; response_revision:163; number_of_response:1; }","duration":"102.447515ms","start":"2026-07-24T04:40:32.96568Z","end":"2026-07-24T04:40:33.068127Z","steps":["trace[1066577605] 'process raft request' (duration: 30.896684ms)","trace[1066577605] 'compare' (duration: 71.363337ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:40:35.614022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.28562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-24T04:40:35.61421Z","caller":"traceutil/trace.go:171","msg":"trace[1082834285] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:261; }","duration":"118.654359ms","start":"2026-07-24T04:40:35.495515Z","end":"2026-07-24T04:40:35.614169Z","steps":["trace[1082834285] 'range keys from in-memory index tree' (duration: 118.112996ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:35.870785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.816274ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:40:35.870897Z","caller":"traceutil/trace.go:171","msg":"trace[530898037] linearizableReadLoop","detail":"{readStateIndex:274; appliedIndex:273; }","duration":"127.319795ms","start":"2026-07-24T04:40:35.743559Z","end":"2026-07-24T04:40:35.870879Z","steps":["trace[530898037] 'read index received' (duration: 7.789714ms)","trace[530898037] 'applied index is now lower than readState.Index' (duration: 119.528901ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:40:35.870986Z","caller":"traceutil/trace.go:171","msg":"trace[110495120] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"174.803839ms","start":"2026-07-24T04:40:35.696171Z","end":"2026-07-24T04:40:35.870975Z","steps":["trace[110495120] 'process raft request' (duration: 55.254468ms)","trace[110495120] 'compare' (duration: 118.68555ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:40:35.871111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.536969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:40:35.871147Z","caller":"traceutil/trace.go:171","msg":"trace[580495246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:267; }","duration":"127.651193ms","start":"2026-07-24T04:40:35.743485Z","end":"2026-07-24T04:40:35.871136Z","steps":["trace[580495246] 'agreement among raft nodes before linearized reading' (duration: 127.54876ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:40:36.101772Z","caller":"traceutil/trace.go:171","msg":"trace[815365305] linearizableReadLoop","detail":"{readStateIndex:277; appliedIndex:276; }","duration":"157.087757ms","start":"2026-07-24T04:40:35.944645Z","end":"2026-07-24T04:40:36.101733Z","steps":["trace[815365305] 'read index received' (duration: 112.221636ms)","trace[815365305] 'applied index is now lower than readState.Index' (duration: 44.864591ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:40:36.101886Z","caller":"traceutil/trace.go:171","msg":"trace[707924976] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"177.481339ms","start":"2026-07-24T04:40:35.92436Z","end":"2026-07-24T04:40:36.101842Z","steps":["trace[707924976] 'process raft request' (duration: 132.518855ms)","trace[707924976] 'compare' (duration: 44.612605ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:40:36.102031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.351063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:40:36.102083Z","caller":"traceutil/trace.go:171","msg":"trace[1982847903] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:0; response_revision:270; }","duration":"157.492456ms","start":"2026-07-24T04:40:35.944576Z","end":"2026-07-24T04:40:36.102068Z","steps":["trace[1982847903] 'agreement among raft nodes before linearized reading' (duration: 157.326722ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:40:45.3752Z","caller":"traceutil/trace.go:171","msg":"trace[334872666] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"164.913226ms","start":"2026-07-24T04:40:45.210235Z","end":"2026-07-24T04:40:45.375148Z","steps":["trace[334872666] 'process raft request' (duration: 164.748182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:49.360503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.787779ms","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-24T04:40:49.360564Z","caller":"traceutil/trace.go:171","msg":"trace[958942824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:375; }","duration":"759.865101ms","start":"2026-07-24T04:40:48.600686Z","end":"2026-07-24T04:40:49.360551Z","steps":["trace[958942824] 'range keys from in-memory index tree' (duration: 759.671847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:49.360496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.351518ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:40:49.360596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:48.600572Z","time spent":"760.017604ms","remote":"127.0.0.1:52550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-24T04:40:49.360788Z","caller":"traceutil/trace.go:171","msg":"trace[349172003] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:375; }","duration":"911.653634ms","start":"2026-07-24T04:40:48.449105Z","end":"2026-07-24T04:40:49.360758Z","steps":["trace[349172003] 'range keys from in-memory index tree' (duration: 911.262366ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:40:49.36348Z","caller":"traceutil/trace.go:171","msg":"trace[777145018] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:385; }","duration":"197.040616ms","start":"2026-07-24T04:40:49.166412Z","end":"2026-07-24T04:40:49.363453Z","steps":["trace[777145018] 'read index received' (duration: 191.499771ms)","trace[777145018] 'applied index is now lower than readState.Index' (duration: 5.539315ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:40:49.363613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.2045ms","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-24T04:40:49.363661Z","caller":"traceutil/trace.go:171","msg":"trace[1959113051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:375; }","duration":"197.290042ms","start":"2026-07-24T04:40:49.166361Z","end":"2026-07-24T04:40:49.363651Z","steps":["trace[1959113051] 'agreement among raft nodes before linearized reading' (duration: 197.188699ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:40:49.537956Z","caller":"traceutil/trace.go:171","msg":"trace[1713090142] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"167.844584ms","start":"2026-07-24T04:40:49.370077Z","end":"2026-07-24T04:40:49.537922Z","steps":["trace[1713090142] 'process raft request' (duration: 167.690081ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:40:49.537836Z","caller":"traceutil/trace.go:171","msg":"trace[878176210] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"169.352576ms","start":"2026-07-24T04:40:49.368445Z","end":"2026-07-24T04:40:49.537797Z","steps":["trace[878176210] 'process raft request' (duration: 145.425822ms)","trace[878176210] 'compare' (duration: 23.74787ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:40:49.671206Z","caller":"traceutil/trace.go:171","msg":"trace[976766906] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"119.939415ms","start":"2026-07-24T04:40:49.551236Z","end":"2026-07-24T04:40:49.671175Z","steps":["trace[976766906] 'process raft request' (duration: 92.424268ms)","trace[976766906] 'compare' (duration: 27.367654ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:40:54.274687Z","caller":"traceutil/trace.go:171","msg":"trace[251383415] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"264.142736ms","start":"2026-07-24T04:40:54.010517Z","end":"2026-07-24T04:40:54.27466Z","steps":["trace[251383415] 'process raft request' (duration: 263.933172ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:40:54.7399Z","caller":"traceutil/trace.go:171","msg":"trace[1909570800] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"409.56947ms","start":"2026-07-24T04:40:54.330301Z","end":"2026-07-24T04:40:54.73987Z","steps":["trace[1909570800] 'process raft request' (duration: 382.518178ms)","trace[1909570800] 'compare' (duration: 26.90343ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:40:54.740241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:54.330277Z","time spent":"409.700862ms","remote":"127.0.0.1:52550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:40:58.420871Z","caller":"traceutil/trace.go:171","msg":"trace[1792590905] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"128.762608ms","start":"2026-07-24T04:40:58.29209Z","end":"2026-07-24T04:40:58.420852Z","steps":["trace[1792590905] 'process raft request' (duration: 128.653456ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:40:59.561738Z","caller":"traceutil/trace.go:171","msg":"trace[120804382] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"111.530102ms","start":"2026-07-24T04:40:59.450182Z","end":"2026-07-24T04:40:59.561712Z","steps":["trace[120804382] 'process raft request' (duration: 111.334188ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:00.650794Z","caller":"traceutil/trace.go:171","msg":"trace[1973757363] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"222.919964ms","start":"2026-07-24T04:41:00.427827Z","end":"2026-07-24T04:41:00.650747Z","steps":["trace[1973757363] 'process raft request' (duration: 222.68324ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:14.874328Z","caller":"traceutil/trace.go:171","msg":"trace[462000161] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"764.928541ms","start":"2026-07-24T04:41:14.10937Z","end":"2026-07-24T04:41:14.874299Z","steps":["trace[462000161] 'process raft request' (duration: 764.700447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:14.87454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:14.109342Z","time spent":"765.064762ms","remote":"127.0.0.1:52316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T04:41:14.882169Z","caller":"traceutil/trace.go:171","msg":"trace[906890302] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"764.520343ms","start":"2026-07-24T04:41:14.117636Z","end":"2026-07-24T04:41:14.882156Z","steps":["trace[906890302] 'process raft request' (duration: 764.217928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:14.882227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:14.11763Z","time spent":"764.569994ms","remote":"127.0.0.1:52316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T04:41:14.882295Z","caller":"traceutil/trace.go:171","msg":"trace[1809496874] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:516; }","duration":"138.534144ms","start":"2026-07-24T04:41:14.74374Z","end":"2026-07-24T04:41:14.882274Z","steps":["trace[1809496874] 'read index received' (duration: 130.398269ms)","trace[1809496874] 'applied index is now lower than readState.Index' (duration: 8.134745ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:41:14.882356Z","caller":"traceutil/trace.go:171","msg":"trace[2086155927] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"769.14103ms","start":"2026-07-24T04:41:14.11321Z","end":"2026-07-24T04:41:14.882351Z","steps":["trace[2086155927] 'process raft request' (duration: 768.53942ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:14.882391Z","caller":"traceutil/trace.go:171","msg":"trace[1953813846] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"762.680082ms","start":"2026-07-24T04:41:14.1197Z","end":"2026-07-24T04:41:14.88238Z","steps":["trace[1953813846] 'process raft request' (duration: 762.192005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:14.882425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:14.113192Z","time spent":"769.18604ms","remote":"127.0.0.1:52316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T04:41:14.882451Z","caller":"traceutil/trace.go:171","msg":"trace[924248807] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"737.397372ms","start":"2026-07-24T04:41:14.145014Z","end":"2026-07-24T04:41:14.882411Z","steps":["trace[924248807] 'process raft request' (duration: 737.069407ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:14.88248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:14.119694Z","time spent":"762.731343ms","remote":"127.0.0.1:52316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T04:41:14.882494Z","caller":"traceutil/trace.go:171","msg":"trace[626226096] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"580.720027ms","start":"2026-07-24T04:41:14.301767Z","end":"2026-07-24T04:41:14.882487Z","steps":["trace[626226096] 'process raft request' (duration: 580.437281ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:14.882525Z","caller":"traceutil/trace.go:171","msg":"trace[1140082326] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"758.462103ms","start":"2026-07-24T04:41:14.12404Z","end":"2026-07-24T04:41:14.882503Z","steps":["trace[1140082326] 'process raft request' (duration: 757.927324ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:14.88255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:14.144996Z","time spent":"737.511634ms","remote":"127.0.0.1:52316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T04:41:14.882566Z","caller":"traceutil/trace.go:171","msg":"trace[642537186] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"709.502039ms","start":"2026-07-24T04:41:14.173056Z","end":"2026-07-24T04:41:14.882558Z","steps":["trace[642537186] 'process raft request' (duration: 709.079521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:14.8826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:14.173042Z","time spent":"709.54593ms","remote":"127.0.0.1:52316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:41:14.882594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:14.124031Z","time spent":"758.532004ms","remote":"127.0.0.1:52316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T04:41:14.882721Z","caller":"traceutil/trace.go:171","msg":"trace[452577593] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"758.695986ms","start":"2026-07-24T04:41:14.123999Z","end":"2026-07-24T04:41:14.882695Z","steps":["trace[452577593] 'process raft request' (duration: 757.930903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:14.882811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:14.123948Z","time spent":"758.824258ms","remote":"127.0.0.1:52316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:41:14.882825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.089094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:41:14.882865Z","caller":"traceutil/trace.go:171","msg":"trace[2049355246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"139.183905ms","start":"2026-07-24T04:41:14.74367Z","end":"2026-07-24T04:41:14.882854Z","steps":["trace[2049355246] 'agreement among raft nodes before linearized reading' (duration: 139.095143ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:14.882539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:14.301735Z","time spent":"580.787219ms","remote":"127.0.0.1:52316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T04:41:14.882333Z","caller":"traceutil/trace.go:171","msg":"trace[635961927] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"772.564196ms","start":"2026-07-24T04:41:14.109758Z","end":"2026-07-24T04:41:14.882323Z","steps":["trace[635961927] 'process raft request' (duration: 771.880556ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:14.882974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:14.109748Z","time spent":"773.202407ms","remote":"127.0.0.1:52316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T04:41:14.883095Z","caller":"traceutil/trace.go:171","msg":"trace[791166752] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"755.101206ms","start":"2026-07-24T04:41:14.127983Z","end":"2026-07-24T04:41:14.883085Z","steps":["trace[791166752] 'process raft request' (duration: 754.028078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:14.883156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:14.127971Z","time spent":"755.158947ms","remote":"127.0.0.1:52316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T04:41:14.883338Z","caller":"traceutil/trace.go:171","msg":"trace[1830669300] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"766.819832ms","start":"2026-07-24T04:41:14.116499Z","end":"2026-07-24T04:41:14.883319Z","steps":["trace[1830669300] 'process raft request' (duration: 765.295437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:14.883395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:14.116483Z","time spent":"766.887803ms","remote":"127.0.0.1:52316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:41:14.884026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.583892ms","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-24T04:41:14.884068Z","caller":"traceutil/trace.go:171","msg":"trace[597880865] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:512; }","duration":"112.664384ms","start":"2026-07-24T04:41:14.771395Z","end":"2026-07-24T04:41:14.884059Z","steps":["trace[597880865] 'agreement among raft nodes before linearized reading' (duration: 112.511492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:15.327592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.349764ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:41:15.327727Z","caller":"traceutil/trace.go:171","msg":"trace[1225926658] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"219.703765ms","start":"2026-07-24T04:41:15.108005Z","end":"2026-07-24T04:41:15.327708Z","steps":["trace[1225926658] 'process raft request' (duration: 93.104126ms)","trace[1225926658] 'compare' (duration: 126.176672ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:41:15.344118Z","caller":"traceutil/trace.go:171","msg":"trace[834693867] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"189.984164ms","start":"2026-07-24T04:41:15.154108Z","end":"2026-07-24T04:41:15.344092Z","steps":["trace[834693867] 'process raft request' (duration: 189.919943ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:15.344164Z","caller":"traceutil/trace.go:171","msg":"trace[1559771039] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"194.131703ms","start":"2026-07-24T04:41:15.149986Z","end":"2026-07-24T04:41:15.344117Z","steps":["trace[1559771039] 'process raft request' (duration: 193.818918ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:15.957789Z","caller":"traceutil/trace.go:171","msg":"trace[1441149182] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"277.652891ms","start":"2026-07-24T04:41:15.680079Z","end":"2026-07-24T04:41:15.957732Z","steps":["trace[1441149182] 'process raft request' (duration: 277.410937ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:16.008578Z","caller":"traceutil/trace.go:171","msg":"trace[1982492094] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"305.871237ms","start":"2026-07-24T04:41:15.702661Z","end":"2026-07-24T04:41:16.008532Z","steps":["trace[1982492094] 'process raft request' (duration: 304.980382ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:16.008615Z","caller":"traceutil/trace.go:171","msg":"trace[784862756] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:581; }","duration":"264.509264ms","start":"2026-07-24T04:41:15.744074Z","end":"2026-07-24T04:41:16.008583Z","steps":["trace[784862756] 'read index received' (duration: 214.170264ms)","trace[784862756] 'applied index is now lower than readState.Index' (duration: 50.33788ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:41:16.009413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:15.702638Z","time spent":"306.025229ms","remote":"127.0.0.1:52316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:41:16.009445Z","caller":"traceutil/trace.go:171","msg":"trace[51900258] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"138.586215ms","start":"2026-07-24T04:41:15.870821Z","end":"2026-07-24T04:41:16.009407Z","steps":["trace[51900258] 'process raft request' (duration: 137.709942ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:16.009985Z","caller":"traceutil/trace.go:171","msg":"trace[1046508750] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"235.392883ms","start":"2026-07-24T04:41:15.773391Z","end":"2026-07-24T04:41:16.008784Z","steps":["trace[1046508750] 'process raft request' (duration: 234.915225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:16.010925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.715879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:41:16.011141Z","caller":"traceutil/trace.go:171","msg":"trace[2128411972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"267.067706ms","start":"2026-07-24T04:41:15.744039Z","end":"2026-07-24T04:41:16.011106Z","steps":["trace[2128411972] 'agreement among raft nodes before linearized reading' (duration: 264.959581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:16.184052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.126356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:41:16.18419Z","caller":"traceutil/trace.go:171","msg":"trace[274959748] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:584; }","duration":"174.935404ms","start":"2026-07-24T04:41:16.009232Z","end":"2026-07-24T04:41:16.184168Z","steps":["trace[274959748] 'read index received' (duration: 65.514173ms)","trace[274959748] 'applied index is now lower than readState.Index' (duration: 109.419801ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:41:16.184294Z","caller":"traceutil/trace.go:171","msg":"trace[54341245] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"215.280078ms","start":"2026-07-24T04:41:15.968973Z","end":"2026-07-24T04:41:16.184253Z","steps":["trace[54341245] 'process raft request' (duration: 105.848257ms)","trace[54341245] 'compare' (duration: 108.947593ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:41:16.184338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.863171ms","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-24T04:41:16.184382Z","caller":"traceutil/trace.go:171","msg":"trace[1586357970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:564; }","duration":"354.928033ms","start":"2026-07-24T04:41:15.82944Z","end":"2026-07-24T04:41:16.184368Z","steps":["trace[1586357970] 'agreement among raft nodes before linearized reading' (duration: 354.837411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:16.184425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:15.829431Z","time spent":"354.983864ms","remote":"127.0.0.1:52550","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-24T04:41:16.184591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.197418ms","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-24T04:41:16.18463Z","caller":"traceutil/trace.go:171","msg":"trace[1625077424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:564; }","duration":"378.276089ms","start":"2026-07-24T04:41:15.806341Z","end":"2026-07-24T04:41:16.184617Z","steps":["trace[1625077424] 'agreement among raft nodes before linearized reading' (duration: 378.165996ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:16.18466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:15.806324Z","time spent":"378.328169ms","remote":"127.0.0.1:52550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-24T04:41:16.191238Z","caller":"traceutil/trace.go:171","msg":"trace[809292811] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"165.271646ms","start":"2026-07-24T04:41:16.02594Z","end":"2026-07-24T04:41:16.191212Z","steps":["trace[809292811] 'process raft request' (duration: 165.094403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:16.198495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.049496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:41:16.198587Z","caller":"traceutil/trace.go:171","msg":"trace[1409980009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"183.179699ms","start":"2026-07-24T04:41:16.015381Z","end":"2026-07-24T04:41:16.198561Z","steps":["trace[1409980009] 'agreement among raft nodes before linearized reading' (duration: 182.981545ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:18.325151Z","caller":"traceutil/trace.go:171","msg":"trace[1650271369] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"110.028173ms","start":"2026-07-24T04:41:18.215099Z","end":"2026-07-24T04:41:18.325127Z","steps":["trace[1650271369] 'process raft request' (duration: 109.649177ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:19.881173Z","caller":"traceutil/trace.go:171","msg":"trace[2112336917] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"301.065087ms","start":"2026-07-24T04:41:19.580094Z","end":"2026-07-24T04:41:19.881159Z","steps":["trace[2112336917] 'process raft request' (duration: 300.809953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:19.881299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:19.580062Z","time spent":"301.176259ms","remote":"127.0.0.1:52632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:41:19.881393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.745639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:41:19.881094Z","caller":"traceutil/trace.go:171","msg":"trace[1826669349] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"136.441053ms","start":"2026-07-24T04:41:19.744627Z","end":"2026-07-24T04:41:19.881068Z","steps":["trace[1826669349] 'read index received' (duration: 136.203788ms)","trace[1826669349] 'applied index is now lower than readState.Index' (duration: 235.955µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:41:19.881482Z","caller":"traceutil/trace.go:171","msg":"trace[518639527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"136.87615ms","start":"2026-07-24T04:41:19.744581Z","end":"2026-07-24T04:41:19.881457Z","steps":["trace[518639527] 'agreement among raft nodes before linearized reading' (duration: 136.660857ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:20.511797Z","caller":"traceutil/trace.go:171","msg":"trace[367793034] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"182.781982ms","start":"2026-07-24T04:41:20.328989Z","end":"2026-07-24T04:41:20.511771Z","steps":["trace[367793034] 'read index received' (duration: 181.658864ms)","trace[367793034] 'applied index is now lower than readState.Index' (duration: 1.122178ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:41:20.511886Z","caller":"traceutil/trace.go:171","msg":"trace[1069271111] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"224.367013ms","start":"2026-07-24T04:41:20.287492Z","end":"2026-07-24T04:41:20.511859Z","steps":["trace[1069271111] 'process raft request' (duration: 223.5081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:20.511981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.974285ms","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-24T04:41:20.512096Z","caller":"traceutil/trace.go:171","msg":"trace[1509737821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"183.126127ms","start":"2026-07-24T04:41:20.328957Z","end":"2026-07-24T04:41:20.512084Z","steps":["trace[1509737821] 'agreement among raft nodes before linearized reading' (duration: 182.909734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:21.471786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.541941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:41:21.471906Z","caller":"traceutil/trace.go:171","msg":"trace[1032979960] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"346.970039ms","start":"2026-07-24T04:41:21.124917Z","end":"2026-07-24T04:41:21.471887Z","steps":["trace[1032979960] 'process raft request' (duration: 113.249105ms)","trace[1032979960] 'compare' (duration: 233.327918ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:41:21.471978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:21.124892Z","time spent":"347.05307ms","remote":"127.0.0.1:52644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:41:22.197491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.328216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:41:22.19758Z","caller":"traceutil/trace.go:171","msg":"trace[1632100417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"452.483009ms","start":"2026-07-24T04:41:21.745074Z","end":"2026-07-24T04:41:22.197557Z","steps":["trace[1632100417] 'range keys from in-memory index tree' (duration: 452.219465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:22.19762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:21.745045Z","time spent":"452.567241ms","remote":"127.0.0.1:52268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T04:41:22.69933Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13272283553099722523,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-24T04:41:22.780649Z","caller":"traceutil/trace.go:171","msg":"trace[277771743] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"581.449447ms","start":"2026-07-24T04:41:22.199169Z","end":"2026-07-24T04:41:22.780618Z","steps":["trace[277771743] 'read index received' (duration: 580.440161ms)","trace[277771743] 'applied index is now lower than readState.Index' (duration: 1.007606ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:41:22.780847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.66341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:41:22.780738Z","caller":"traceutil/trace.go:171","msg":"trace[855226079] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"911.058649ms","start":"2026-07-24T04:41:21.869617Z","end":"2026-07-24T04:41:22.780676Z","steps":["trace[855226079] 'process raft request' (duration: 910.232296ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:22.780958Z","caller":"traceutil/trace.go:171","msg":"trace[756506651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"581.848343ms","start":"2026-07-24T04:41:22.199086Z","end":"2026-07-24T04:41:22.780935Z","steps":["trace[756506651] 'agreement among raft nodes before linearized reading' (duration: 581.64528ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:22.781231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:22.199043Z","time spent":"582.152578ms","remote":"127.0.0.1:52256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T04:41:22.781301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:21.869582Z","time spent":"911.488036ms","remote":"127.0.0.1:52348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036269,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:41:22.781528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.518517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-24T04:41:22.781577Z","caller":"traceutil/trace.go:171","msg":"trace[1355779602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:603; }","duration":"478.586628ms","start":"2026-07-24T04:41:22.30297Z","end":"2026-07-24T04:41:22.781557Z","steps":["trace[1355779602] 'agreement among raft nodes before linearized reading' (duration: 478.393665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:22.78161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:22.302954Z","time spent":"478.648959ms","remote":"127.0.0.1:52550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-24T04:41:22.781743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.733264ms","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-24T04:41:22.781764Z","caller":"traceutil/trace.go:171","msg":"trace[1877436350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:603; }","duration":"238.780056ms","start":"2026-07-24T04:41:22.54298Z","end":"2026-07-24T04:41:22.78176Z","steps":["trace[1877436350] 'agreement among raft nodes before linearized reading' (duration: 238.723015ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:23.159617Z","caller":"traceutil/trace.go:171","msg":"trace[282094605] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"138.378319ms","start":"2026-07-24T04:41:23.021211Z","end":"2026-07-24T04:41:23.15959Z","steps":["trace[282094605] 'read index received' (duration: 138.086105ms)","trace[282094605] 'applied index is now lower than readState.Index' (duration: 290.724µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:41:23.159849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.617863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-24T04:41:23.159887Z","caller":"traceutil/trace.go:171","msg":"trace[660349666] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:610; }","duration":"138.719704ms","start":"2026-07-24T04:41:23.021157Z","end":"2026-07-24T04:41:23.159877Z","steps":["trace[660349666] 'agreement among raft nodes before linearized reading' (duration: 138.537012ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:23.159949Z","caller":"traceutil/trace.go:171","msg":"trace[1272794331] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"143.572269ms","start":"2026-07-24T04:41:23.016343Z","end":"2026-07-24T04:41:23.159915Z","steps":["trace[1272794331] 'process raft request' (duration: 143.043891ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:23.638244Z","caller":"traceutil/trace.go:171","msg":"trace[1882720983] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"473.437053ms","start":"2026-07-24T04:41:23.164773Z","end":"2026-07-24T04:41:23.63821Z","steps":["trace[1882720983] 'process raft request' (duration: 410.603137ms)","trace[1882720983] 'compare' (duration: 62.700574ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:41:23.638543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:23.164746Z","time spent":"473.591036ms","remote":"127.0.0.1:52550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:41:24.72291Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.140432801s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-24T04:41:25.189312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.787041ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:41:25.189509Z","caller":"traceutil/trace.go:171","msg":"trace[2034546514] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"1.942170329s","start":"2026-07-24T04:41:23.247302Z","end":"2026-07-24T04:41:25.189472Z","steps":["trace[2034546514] 'process raft request' (duration: 1.476095873s)","trace[2034546514] 'compare' (duration: 465.295703ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:41:25.189744Z","caller":"traceutil/trace.go:171","msg":"trace[144675534] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:632; }","duration":"1.906128199s","start":"2026-07-24T04:41:23.28359Z","end":"2026-07-24T04:41:25.189719Z","steps":["trace[144675534] 'read index received' (duration: 291.841262ms)","trace[144675534] 'applied index is now lower than readState.Index' (duration: 1.614284787s)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:41:25.189893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:23.247279Z","time spent":"1.942314021s","remote":"127.0.0.1:52348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:41:25.190032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.802112814s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.152\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-24T04:41:25.190048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.906457094s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rd86t\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-24T04:41:25.190103Z","caller":"traceutil/trace.go:171","msg":"trace[1578407100] range","detail":"{range_begin:/registry/masterleases/199.19.213.152; range_end:; response_count:1; response_revision:612; }","duration":"1.802182865s","start":"2026-07-24T04:41:23.387899Z","end":"2026-07-24T04:41:25.190082Z","steps":["trace[1578407100] 'agreement among raft nodes before linearized reading' (duration: 1.802052643s)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:25.190116Z","caller":"traceutil/trace.go:171","msg":"trace[1014809458] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rd86t; range_end:; response_count:1; response_revision:612; }","duration":"1.906587406s","start":"2026-07-24T04:41:23.283509Z","end":"2026-07-24T04:41:25.190097Z","steps":["trace[1014809458] 'agreement among raft nodes before linearized reading' (duration: 1.906363953s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:25.190152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:23.387819Z","time spent":"1.802321516s","remote":"127.0.0.1:52294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.152\" "} {"level":"warn","ts":"2026-07-24T04:41:25.190183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:23.283494Z","time spent":"1.906677687s","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-rd86t\" "} {"level":"warn","ts":"2026-07-24T04:41:25.190354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.012294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-24T04:41:25.190374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.446707315s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:41:25.190411Z","caller":"traceutil/trace.go:171","msg":"trace[1144035293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:612; }","duration":"394.101306ms","start":"2026-07-24T04:41:24.796293Z","end":"2026-07-24T04:41:25.190394Z","steps":["trace[1144035293] 'agreement among raft nodes before linearized reading' (duration: 394.004155ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:25.190462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:24.796274Z","time spent":"394.175037ms","remote":"127.0.0.1:52550","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-24T04:41:25.190555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.374488ms","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-24T04:41:25.190592Z","caller":"traceutil/trace.go:171","msg":"trace[1973800363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:612; }","duration":"390.428779ms","start":"2026-07-24T04:41:24.800152Z","end":"2026-07-24T04:41:25.190581Z","steps":["trace[1973800363] 'agreement among raft nodes before linearized reading' (duration: 390.362498ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:25.190416Z","caller":"traceutil/trace.go:171","msg":"trace[1178126758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"1.446780675s","start":"2026-07-24T04:41:23.743622Z","end":"2026-07-24T04:41:25.190403Z","steps":["trace[1178126758] 'agreement among raft nodes before linearized reading' (duration: 1.446716554s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:25.190622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:24.800141Z","time spent":"390.474049ms","remote":"127.0.0.1:52550","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-24T04:41:25.190649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:23.743604Z","time spent":"1.44703185s","remote":"127.0.0.1:52256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T04:41:26.547189Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.23077339s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-24T04:41:27.20241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999216411s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-24T04:41:27.202492Z","caller":"traceutil/trace.go:171","msg":"trace[719726148] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999343972s","start":"2026-07-24T04:41:25.203129Z","end":"2026-07-24T04:41:27.202473Z","steps":["trace[719726148] 'agreement among raft nodes before linearized reading' (duration: 1.99923838s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:27.20254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:25.203121Z","time spent":"1.999409743s","remote":"127.0.0.1:52268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/24 04:41:27 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-24T04:41:27.545691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.022086ms","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-24T04:41:27.545826Z","caller":"traceutil/trace.go:171","msg":"trace[56347570] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:612; }","duration":"500.167228ms","start":"2026-07-24T04:41:27.045643Z","end":"2026-07-24T04:41:27.54581Z","steps":["trace[56347570] 'range keys from in-memory index tree' (duration: 500.005706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:27.546082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.229774114s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-24T04:41:27.546436Z","caller":"traceutil/trace.go:171","msg":"trace[1775646238] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.268988118s","start":"2026-07-24T04:41:26.277433Z","end":"2026-07-24T04:41:27.546421Z","steps":["trace[1775646238] 'process raft request' (duration: 1.268961777s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:27.546508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:26.277408Z","time spent":"1.269052918s","remote":"127.0.0.1:52550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:41:27.546637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:25.191605Z","time spent":"2.355031432s","remote":"127.0.0.1:52294","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-24T04:41:27.546788Z","caller":"traceutil/trace.go:171","msg":"trace[1580235930] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"2.350175248s","start":"2026-07-24T04:41:25.196605Z","end":"2026-07-24T04:41:27.546781Z","steps":["trace[1580235930] 'process raft request' (duration: 2.349553638s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:27.546831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:25.196574Z","time spent":"2.350237039s","remote":"127.0.0.1:52550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:41:27.546952Z","caller":"traceutil/trace.go:171","msg":"trace[2014394734] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"2.337950091s","start":"2026-07-24T04:41:25.208984Z","end":"2026-07-24T04:41:27.546934Z","steps":["trace[2014394734] 'process raft request' (duration: 2.337353772s)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:27.546973Z","caller":"traceutil/trace.go:171","msg":"trace[1895870154] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"2.35029948s","start":"2026-07-24T04:41:25.196666Z","end":"2026-07-24T04:41:27.546966Z","steps":["trace[1895870154] 'process raft request' (duration: 2.349584148s)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:27.546975Z","caller":"traceutil/trace.go:171","msg":"trace[846258926] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"2.297981699s","start":"2026-07-24T04:41:25.248972Z","end":"2026-07-24T04:41:27.546953Z","steps":["trace[846258926] 'process raft request' (duration: 2.29739904s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:27.547008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:25.196646Z","time spent":"2.350343551s","remote":"127.0.0.1:52550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:41:27.547033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:25.208944Z","time spent":"2.338046022s","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:41:27.547086Z","caller":"traceutil/trace.go:171","msg":"trace[1703252187] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"2.35030801s","start":"2026-07-24T04:41:25.196771Z","end":"2026-07-24T04:41:27.547079Z","steps":["trace[1703252187] 'process raft request' (duration: 2.349539108s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:27.547081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:25.248947Z","time spent":"2.2980767s","remote":"127.0.0.1:52812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:41:27.547117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:25.196759Z","time spent":"2.350341171s","remote":"127.0.0.1:52550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:41:27.547234Z","caller":"traceutil/trace.go:171","msg":"trace[1128477108] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:634; }","duration":"2.344047484s","start":"2026-07-24T04:41:25.203161Z","end":"2026-07-24T04:41:27.547208Z","steps":["trace[1128477108] 'read index received' (duration: 113.234984ms)","trace[1128477108] 'applied index is now lower than readState.Index' (duration: 2.23081119s)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:41:27.547355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.503259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:41:27.547378Z","caller":"traceutil/trace.go:171","msg":"trace[1713054867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"344.561669ms","start":"2026-07-24T04:41:27.202808Z","end":"2026-07-24T04:41:27.54737Z","steps":["trace[1713054867] 'agreement among raft nodes before linearized reading' (duration: 344.525419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:27.547397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:27.202791Z","time spent":"344.60275ms","remote":"127.0.0.1:52256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T04:41:27.547411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.905084155s","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-24T04:41:27.547441Z","caller":"traceutil/trace.go:171","msg":"trace[875756045] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"1.905152325s","start":"2026-07-24T04:41:25.642279Z","end":"2026-07-24T04:41:27.547431Z","steps":["trace[875756045] 'agreement among raft nodes before linearized reading' (duration: 1.905026744s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:27.547479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:25.642259Z","time spent":"1.905213217s","remote":"127.0.0.1:52550","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-24T04:41:28.136029Z","caller":"traceutil/trace.go:171","msg":"trace[855775749] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"112.384524ms","start":"2026-07-24T04:41:28.023629Z","end":"2026-07-24T04:41:28.136014Z","steps":["trace[855775749] 'process raft request' (duration: 112.292172ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:29.709491Z","caller":"traceutil/trace.go:171","msg":"trace[1002085908] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:686; }","duration":"157.310571ms","start":"2026-07-24T04:41:29.552148Z","end":"2026-07-24T04:41:29.709459Z","steps":["trace[1002085908] 'read index received' (duration: 157.076408ms)","trace[1002085908] 'applied index is now lower than readState.Index' (duration: 232.833µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:41:29.70957Z","caller":"traceutil/trace.go:171","msg":"trace[1857707397] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"245.34436ms","start":"2026-07-24T04:41:29.464205Z","end":"2026-07-24T04:41:29.709549Z","steps":["trace[1857707397] 'process raft request' (duration: 245.092237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:29.709772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.580875ms","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-24T04:41:29.709823Z","caller":"traceutil/trace.go:171","msg":"trace[52806836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:662; }","duration":"157.696707ms","start":"2026-07-24T04:41:29.552115Z","end":"2026-07-24T04:41:29.709811Z","steps":["trace[52806836] 'agreement among raft nodes before linearized reading' (duration: 157.491713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:29.709966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.714937ms","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-24T04:41:29.710015Z","caller":"traceutil/trace.go:171","msg":"trace[1564771123] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:662; }","duration":"157.794079ms","start":"2026-07-24T04:41:29.552199Z","end":"2026-07-24T04:41:29.709993Z","steps":["trace[1564771123] 'agreement among raft nodes before linearized reading' (duration: 157.636526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:29.710073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.808791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-24T04:41:29.710792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.015152ms","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-24T04:41:29.710792Z","caller":"traceutil/trace.go:171","msg":"trace[1274940354] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:662; }","duration":"140.579172ms","start":"2026-07-24T04:41:29.57019Z","end":"2026-07-24T04:41:29.710769Z","steps":["trace[1274940354] 'agreement among raft nodes before linearized reading' (duration: 139.650169ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:29.71085Z","caller":"traceutil/trace.go:171","msg":"trace[64426102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:662; }","duration":"158.101683ms","start":"2026-07-24T04:41:29.552732Z","end":"2026-07-24T04:41:29.710833Z","steps":["trace[64426102] 'agreement among raft nodes before linearized reading' (duration: 157.987811ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:32.641111Z","caller":"traceutil/trace.go:171","msg":"trace[1277436661] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"152.977819ms","start":"2026-07-24T04:41:32.488108Z","end":"2026-07-24T04:41:32.641086Z","steps":["trace[1277436661] 'process raft request' (duration: 152.804937ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:45.012175Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13272283553099722966,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T04:41:45.481023Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.054343695s","expected-duration":"1s"} {"level":"info","ts":"2026-07-24T04:41:45.48137Z","caller":"traceutil/trace.go:171","msg":"trace[468442632] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"969.50487ms","start":"2026-07-24T04:41:44.511842Z","end":"2026-07-24T04:41:45.481347Z","steps":["trace[468442632] 'read index received' (duration: 969.291928ms)","trace[468442632] 'applied index is now lower than readState.Index' (duration: 211.772µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:41:45.481482Z","caller":"traceutil/trace.go:171","msg":"trace[770838490] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"1.054912112s","start":"2026-07-24T04:41:44.426554Z","end":"2026-07-24T04:41:45.481466Z","steps":["trace[770838490] 'process raft request' (duration: 1.054648078s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:45.481561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.581231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:41:45.481593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:44.426529Z","time spent":"1.054990222s","remote":"127.0.0.1:52518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:41:45.481661Z","caller":"traceutil/trace.go:171","msg":"trace[1491803488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:788; }","duration":"735.717373ms","start":"2026-07-24T04:41:44.745918Z","end":"2026-07-24T04:41:45.481636Z","steps":["trace[1491803488] 'agreement among raft nodes before linearized reading' (duration: 735.583131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:45.481655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.463097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:41:45.48171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:44.745901Z","time spent":"735.800954ms","remote":"127.0.0.1:52268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-24T04:41:45.481718Z","caller":"traceutil/trace.go:171","msg":"trace[1380418130] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:788; }","duration":"477.578649ms","start":"2026-07-24T04:41:45.004122Z","end":"2026-07-24T04:41:45.4817Z","steps":["trace[1380418130] 'agreement among raft nodes before linearized reading' (duration: 477.462837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:45.481753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:45.004103Z","time spent":"477.640929ms","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:41:45.481862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.003266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:41:45.482015Z","caller":"traceutil/trace.go:171","msg":"trace[1984407535] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:788; }","duration":"970.201199ms","start":"2026-07-24T04:41:44.511757Z","end":"2026-07-24T04:41:45.481958Z","steps":["trace[1984407535] 'agreement among raft nodes before linearized reading' (duration: 969.869055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:45.482099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:44.511709Z","time spent":"970.35112ms","remote":"127.0.0.1:52398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:41:45.482596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.681162ms","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-24T04:41:45.482639Z","caller":"traceutil/trace.go:171","msg":"trace[1506423567] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:788; }","duration":"188.763143ms","start":"2026-07-24T04:41:45.293863Z","end":"2026-07-24T04:41:45.482627Z","steps":["trace[1506423567] 'agreement among raft nodes before linearized reading' (duration: 187.715889ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:50.161451Z","caller":"traceutil/trace.go:171","msg":"trace[1513180667] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"133.57082ms","start":"2026-07-24T04:41:50.027848Z","end":"2026-07-24T04:41:50.161419Z","steps":["trace[1513180667] 'process raft request' (duration: 96.540328ms)","trace[1513180667] 'compare' (duration: 36.884271ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:41:59.717594Z","caller":"traceutil/trace.go:171","msg":"trace[1981538289] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"103.757258ms","start":"2026-07-24T04:41:59.613813Z","end":"2026-07-24T04:41:59.71757Z","steps":["trace[1981538289] 'process raft request' (duration: 103.614366ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:00.148537Z","caller":"traceutil/trace.go:171","msg":"trace[883758360] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"168.062016ms","start":"2026-07-24T04:41:59.980426Z","end":"2026-07-24T04:42:00.148488Z","steps":["trace[883758360] 'process raft request' (duration: 107.746151ms)","trace[883758360] 'compare' (duration: 59.84213ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:42:00.148571Z","caller":"traceutil/trace.go:171","msg":"trace[1262122768] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"166.344125ms","start":"2026-07-24T04:41:59.982201Z","end":"2026-07-24T04:42:00.148545Z","steps":["trace[1262122768] 'process raft request' (duration: 166.146761ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:00.14917Z","caller":"traceutil/trace.go:171","msg":"trace[2028340201] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"161.293022ms","start":"2026-07-24T04:41:59.98785Z","end":"2026-07-24T04:42:00.149143Z","steps":["trace[2028340201] 'process raft request' (duration: 160.617203ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:00.150833Z","caller":"traceutil/trace.go:171","msg":"trace[1874172382] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"162.650949ms","start":"2026-07-24T04:41:59.98816Z","end":"2026-07-24T04:42:00.150811Z","steps":["trace[1874172382] 'process raft request' (duration: 160.917698ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:00.350599Z","caller":"traceutil/trace.go:171","msg":"trace[1849025698] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:986; }","duration":"160.788486ms","start":"2026-07-24T04:42:00.189785Z","end":"2026-07-24T04:42:00.350574Z","steps":["trace[1849025698] 'read index received' (duration: 92.525563ms)","trace[1849025698] 'applied index is now lower than readState.Index' (duration: 68.261753ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:42:00.350676Z","caller":"traceutil/trace.go:171","msg":"trace[115065310] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"195.451753ms","start":"2026-07-24T04:42:00.155189Z","end":"2026-07-24T04:42:00.350641Z","steps":["trace[115065310] 'process raft request' (duration: 127.207241ms)","trace[115065310] 'compare' (duration: 67.864828ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:42:00.350794Z","caller":"traceutil/trace.go:171","msg":"trace[379661709] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"193.165265ms","start":"2026-07-24T04:42:00.157601Z","end":"2026-07-24T04:42:00.350766Z","steps":["trace[379661709] 'process raft request' (duration: 192.867661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:00.35082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.022518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-vbrtm\" ","response":"range_response_count:1 size:4884"} {"level":"info","ts":"2026-07-24T04:42:00.350863Z","caller":"traceutil/trace.go:171","msg":"trace[1289177719] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-vbrtm; range_end:; response_count:1; response_revision:955; }","duration":"161.112659ms","start":"2026-07-24T04:42:00.189738Z","end":"2026-07-24T04:42:00.350851Z","steps":["trace[1289177719] 'agreement among raft nodes before linearized reading' (duration: 160.946627ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:00.818797Z","caller":"traceutil/trace.go:171","msg":"trace[362818371] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:991; }","duration":"252.483536ms","start":"2026-07-24T04:42:00.56628Z","end":"2026-07-24T04:42:00.818763Z","steps":["trace[362818371] 'read index received' (duration: 236.092634ms)","trace[362818371] 'applied index is now lower than readState.Index' (duration: 16.388982ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:42:00.818829Z","caller":"traceutil/trace.go:171","msg":"trace[81552167] transaction","detail":"{read_only:false; number_of_response:1; response_revision:959; }","duration":"329.378646ms","start":"2026-07-24T04:42:00.489418Z","end":"2026-07-24T04:42:00.818797Z","steps":["trace[81552167] 'process raft request' (duration: 313.018085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:00.818931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.632838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:42:00.819022Z","caller":"traceutil/trace.go:171","msg":"trace[1449239025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:959; }","duration":"252.76519ms","start":"2026-07-24T04:42:00.566234Z","end":"2026-07-24T04:42:00.818999Z","steps":["trace[1449239025] 'agreement among raft nodes before linearized reading' (duration: 252.626628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:00.819103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:00.489399Z","time spent":"329.520218ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":55,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-07-24T04:42:06.514301Z","caller":"traceutil/trace.go:171","msg":"trace[1768191502] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"113.069335ms","start":"2026-07-24T04:42:06.401207Z","end":"2026-07-24T04:42:06.514277Z","steps":["trace[1768191502] 'process raft request' (duration: 112.930724ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:42.820747Z","caller":"traceutil/trace.go:171","msg":"trace[1797873319] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"111.57839ms","start":"2026-07-24T04:42:42.709137Z","end":"2026-07-24T04:42:42.820715Z","steps":["trace[1797873319] 'process raft request' (duration: 111.415998ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:51.491472Z","caller":"traceutil/trace.go:171","msg":"trace[1873356490] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"109.014054ms","start":"2026-07-24T04:42:51.382437Z","end":"2026-07-24T04:42:51.491451Z","steps":["trace[1873356490] 'process raft request' (duration: 108.868172ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:52.287452Z","caller":"traceutil/trace.go:171","msg":"trace[1361714558] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"139.716909ms","start":"2026-07-24T04:42:52.147709Z","end":"2026-07-24T04:42:52.287426Z","steps":["trace[1361714558] 'process raft request' (duration: 139.553378ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:53.793431Z","caller":"traceutil/trace.go:171","msg":"trace[1846077099] linearizableReadLoop","detail":"{readStateIndex:1188; appliedIndex:1187; }","duration":"260.426921ms","start":"2026-07-24T04:42:53.532964Z","end":"2026-07-24T04:42:53.793391Z","steps":["trace[1846077099] 'read index received' (duration: 188.008432ms)","trace[1846077099] 'applied index is now lower than readState.Index' (duration: 72.416889ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:42:53.793574Z","caller":"traceutil/trace.go:171","msg":"trace[1370261518] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"296.411185ms","start":"2026-07-24T04:42:53.497106Z","end":"2026-07-24T04:42:53.793517Z","steps":["trace[1370261518] 'process raft request' (duration: 223.830044ms)","trace[1370261518] 'compare' (duration: 72.217057ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:42:53.793617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.635423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-24T04:42:53.793676Z","caller":"traceutil/trace.go:171","msg":"trace[2138556145] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1143; }","duration":"260.734175ms","start":"2026-07-24T04:42:53.532928Z","end":"2026-07-24T04:42:53.793662Z","steps":["trace[2138556145] 'agreement among raft nodes before linearized reading' (duration: 260.597754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:08.823803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.098156ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-24T04:43:09.242135Z","caller":"traceutil/trace.go:171","msg":"trace[511392037] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"154.320263ms","start":"2026-07-24T04:43:09.087793Z","end":"2026-07-24T04:43:09.242113Z","steps":["trace[511392037] 'process raft request' (duration: 154.206722ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:11.088599Z","caller":"traceutil/trace.go:171","msg":"trace[695259852] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"179.567533ms","start":"2026-07-24T04:43:10.908996Z","end":"2026-07-24T04:43:11.088564Z","steps":["trace[695259852] 'process raft request' (duration: 178.465082ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:13.015401Z","caller":"traceutil/trace.go:171","msg":"trace[1185779580] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"117.725861ms","start":"2026-07-24T04:43:12.897647Z","end":"2026-07-24T04:43:13.015373Z","steps":["trace[1185779580] 'process raft request' (duration: 117.553319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:13.521471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.442613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.152\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-24T04:43:13.521545Z","caller":"traceutil/trace.go:171","msg":"trace[1349886018] range","detail":"{range_begin:/registry/masterleases/199.19.213.152; range_end:; response_count:1; response_revision:1207; }","duration":"127.568474ms","start":"2026-07-24T04:43:13.393958Z","end":"2026-07-24T04:43:13.521527Z","steps":["trace[1349886018] 'range keys from in-memory index tree' (duration: 127.285101ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:14.104081Z","caller":"traceutil/trace.go:171","msg":"trace[1287253850] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"172.870227ms","start":"2026-07-24T04:43:13.931186Z","end":"2026-07-24T04:43:14.104056Z","steps":["trace[1287253850] 'process raft request' (duration: 172.731005ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:26.636454Z","caller":"traceutil/trace.go:171","msg":"trace[77357330] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"199.414746ms","start":"2026-07-24T04:43:26.437014Z","end":"2026-07-24T04:43:26.636429Z","steps":["trace[77357330] 'process raft request' (duration: 199.287175ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:34.927882Z","caller":"traceutil/trace.go:171","msg":"trace[1516391472] linearizableReadLoop","detail":"{readStateIndex:1362; appliedIndex:1361; }","duration":"107.102908ms","start":"2026-07-24T04:43:34.820763Z","end":"2026-07-24T04:43:34.927866Z","steps":["trace[1516391472] 'read index received' (duration: 106.965967ms)","trace[1516391472] 'applied index is now lower than readState.Index' (duration: 136.161µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:43:34.927998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.219889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T04:43:34.927975Z","caller":"traceutil/trace.go:171","msg":"trace[1128409812] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"178.253895ms","start":"2026-07-24T04:43:34.749672Z","end":"2026-07-24T04:43:34.927925Z","steps":["trace[1128409812] 'process raft request' (duration: 178.098234ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:34.928039Z","caller":"traceutil/trace.go:171","msg":"trace[1076561073] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1308; }","duration":"107.29694ms","start":"2026-07-24T04:43:34.820733Z","end":"2026-07-24T04:43:34.92803Z","steps":["trace[1076561073] 'agreement among raft nodes before linearized reading' (duration: 107.22393ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:43.204183Z","caller":"traceutil/trace.go:171","msg":"trace[136025405] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"139.40448ms","start":"2026-07-24T04:43:43.064727Z","end":"2026-07-24T04:43:43.204131Z","steps":["trace[136025405] 'process raft request' (duration: 54.202703ms)","trace[136025405] 'compare' (duration: 85.033645ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:43:43.58448Z","caller":"traceutil/trace.go:171","msg":"trace[812528052] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1400; }","duration":"118.246018ms","start":"2026-07-24T04:43:43.46621Z","end":"2026-07-24T04:43:43.584456Z","steps":["trace[812528052] 'read index received' (duration: 83.058348ms)","trace[812528052] 'applied index is now lower than readState.Index' (duration: 35.18627ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:43:43.5846Z","caller":"traceutil/trace.go:171","msg":"trace[1990258745] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"118.815093ms","start":"2026-07-24T04:43:43.465753Z","end":"2026-07-24T04:43:43.584568Z","steps":["trace[1990258745] 'process raft request' (duration: 83.539792ms)","trace[1990258745] 'compare' (duration: 35.045419ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:43:43.5847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.4756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-24T04:43:43.584738Z","caller":"traceutil/trace.go:171","msg":"trace[1768727920] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1345; }","duration":"118.567971ms","start":"2026-07-24T04:43:43.46616Z","end":"2026-07-24T04:43:43.584728Z","steps":["trace[1768727920] 'agreement among raft nodes before linearized reading' (duration: 118.391969ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:43.746019Z","caller":"traceutil/trace.go:171","msg":"trace[339287211] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"105.835594ms","start":"2026-07-24T04:43:43.640159Z","end":"2026-07-24T04:43:43.745994Z","steps":["trace[339287211] 'process raft request' (duration: 105.683033ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:44.387624Z","caller":"traceutil/trace.go:171","msg":"trace[519271184] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"121.421766ms","start":"2026-07-24T04:43:44.266177Z","end":"2026-07-24T04:43:44.387598Z","steps":["trace[519271184] 'process raft request' (duration: 121.247063ms)"],"step_count":1}