{"level":"warn","ts":"2026-07-09T16:43:28.741155Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T16:43:28.74124Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.191: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.191:2380","--initial-cluster=instance=https://199.19.213.191:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.191:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.191:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-09T16:43:28.74135Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T16:43:28.741365Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.191:2380"]} {"level":"info","ts":"2026-07-09T16:43:28.741399Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-09T16:43:28.742126Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.191:2379"]} {"level":"info","ts":"2026-07-09T16:43:28.742239Z","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.191:2380"],"listen-peer-urls":["https://199.19.213.191:2380"],"advertise-client-urls":["https://199.19.213.191:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.191:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.191:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-09T16:43:28.746581Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.061334ms"} {"level":"info","ts":"2026-07-09T16:43:28.752372Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e892d50a3a4fc0f4","cluster-id":"21eccdda20ff4e92"} {"level":"info","ts":"2026-07-09T16:43:28.752503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 switched to configuration voters=()"} {"level":"info","ts":"2026-07-09T16:43:28.752799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 became follower at term 0"} {"level":"info","ts":"2026-07-09T16:43:28.752889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e892d50a3a4fc0f4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-09T16:43:28.753013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 became follower at term 1"} {"level":"info","ts":"2026-07-09T16:43:28.753175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 switched to configuration voters=(16758691403303731444)"} {"level":"warn","ts":"2026-07-09T16:43:28.759889Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-09T16:43:28.761547Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-09T16:43:28.762966Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-09T16:43:28.764866Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e892d50a3a4fc0f4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-09T16:43:28.765138Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e892d50a3a4fc0f4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-09T16:43:28.765203Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T16:43:28.765544Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T16:43:28.765594Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T16:43:28.767278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 switched to configuration voters=(16758691403303731444)"} {"level":"info","ts":"2026-07-09T16:43:28.768612Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"21eccdda20ff4e92","local-member-id":"e892d50a3a4fc0f4","added-peer-id":"e892d50a3a4fc0f4","added-peer-peer-urls":["https://199.19.213.191:2380"]} {"level":"info","ts":"2026-07-09T16:43:28.771412Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-09T16:43:28.771612Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.191:2380"} {"level":"info","ts":"2026-07-09T16:43:28.771703Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.191:2380"} {"level":"info","ts":"2026-07-09T16:43:28.772024Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e892d50a3a4fc0f4","initial-advertise-peer-urls":["https://199.19.213.191:2380"],"listen-peer-urls":["https://199.19.213.191:2380"],"advertise-client-urls":["https://199.19.213.191:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.191:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-09T16:43:28.77209Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-09T16:43:29.654249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 is starting a new election at term 1"} {"level":"info","ts":"2026-07-09T16:43:29.654303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-09T16:43:29.654338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 received MsgPreVoteResp from e892d50a3a4fc0f4 at term 1"} {"level":"info","ts":"2026-07-09T16:43:29.654355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 became candidate at term 2"} {"level":"info","ts":"2026-07-09T16:43:29.654364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 received MsgVoteResp from e892d50a3a4fc0f4 at term 2"} {"level":"info","ts":"2026-07-09T16:43:29.654379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 became leader at term 2"} {"level":"info","ts":"2026-07-09T16:43:29.654398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e892d50a3a4fc0f4 elected leader e892d50a3a4fc0f4 at term 2"} {"level":"info","ts":"2026-07-09T16:43:29.655381Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:43:29.656375Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T16:43:29.656393Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T16:43:29.656331Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e892d50a3a4fc0f4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.191:2379]}","request-path":"/0/members/e892d50a3a4fc0f4/attributes","cluster-id":"21eccdda20ff4e92","publish-timeout":"7s"} {"level":"info","ts":"2026-07-09T16:43:29.65679Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-09T16:43:29.656806Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-09T16:43:29.658714Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.191:2379"} {"level":"info","ts":"2026-07-09T16:43:29.661364Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"21eccdda20ff4e92","local-member-id":"e892d50a3a4fc0f4","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:43:29.661544Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:43:29.661587Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:43:29.662793Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-09T16:43:32.357561Z","caller":"traceutil/trace.go:171","msg":"trace[2000029327] transaction","detail":"{read_only:false; response_revision:138; number_of_response:1; }","duration":"110.988609ms","start":"2026-07-09T16:43:32.246507Z","end":"2026-07-09T16:43:32.357496Z","steps":["trace[2000029327] 'process raft request' (duration: 103.633521ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:40.073651Z","caller":"traceutil/trace.go:171","msg":"trace[768416834] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"146.009241ms","start":"2026-07-09T16:43:39.927618Z","end":"2026-07-09T16:43:40.073627Z","steps":["trace[768416834] 'process raft request' (duration: 85.009674ms)","trace[768416834] 'compare' (duration: 60.660867ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:43:40.073899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.313488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-09T16:43:40.073981Z","caller":"traceutil/trace.go:171","msg":"trace[459725434] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:370; }","duration":"139.440431ms","start":"2026-07-09T16:43:39.934528Z","end":"2026-07-09T16:43:40.073968Z","steps":["trace[459725434] 'agreement among raft nodes before linearized reading' (duration: 139.315588ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:40.073804Z","caller":"traceutil/trace.go:171","msg":"trace[1234437119] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:383; }","duration":"138.734113ms","start":"2026-07-09T16:43:39.93469Z","end":"2026-07-09T16:43:40.073424Z","steps":["trace[1234437119] 'read index received' (duration: 77.85628ms)","trace[1234437119] 'applied index is now lower than readState.Index' (duration: 60.876793ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:43:40.509858Z","caller":"traceutil/trace.go:171","msg":"trace[349755119] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:384; }","duration":"426.072652ms","start":"2026-07-09T16:43:40.083742Z","end":"2026-07-09T16:43:40.509815Z","steps":["trace[349755119] 'read index received' (duration: 420.759775ms)","trace[349755119] 'applied index is now lower than readState.Index' (duration: 5.311777ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:43:40.510064Z","caller":"traceutil/trace.go:171","msg":"trace[63614583] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"426.77101ms","start":"2026-07-09T16:43:40.083278Z","end":"2026-07-09T16:43:40.510049Z","steps":["trace[63614583] 'process raft request' (duration: 421.32052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:40.510291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.339962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-07-09T16:43:40.510386Z","caller":"traceutil/trace.go:171","msg":"trace[67976966] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:371; }","duration":"219.486046ms","start":"2026-07-09T16:43:40.290879Z","end":"2026-07-09T16:43:40.510365Z","steps":["trace[67976966] 'agreement among raft nodes before linearized reading' (duration: 219.2476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:40.510509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.87511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T16:43:40.510628Z","caller":"traceutil/trace.go:171","msg":"trace[1319814451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:371; }","duration":"188.999344ms","start":"2026-07-09T16:43:40.321574Z","end":"2026-07-09T16:43:40.510573Z","steps":["trace[1319814451] 'agreement among raft nodes before linearized reading' (duration: 188.847609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:40.510523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.751939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-nldwt\" ","response":"range_response_count:1 size:4466"} {"level":"info","ts":"2026-07-09T16:43:40.510742Z","caller":"traceutil/trace.go:171","msg":"trace[603054508] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-nldwt; range_end:; response_count:1; response_revision:371; }","duration":"426.987686ms","start":"2026-07-09T16:43:40.083728Z","end":"2026-07-09T16:43:40.510715Z","steps":["trace[603054508] 'agreement among raft nodes before linearized reading' (duration: 426.689417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:40.510827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:40.083722Z","time spent":"427.078069ms","remote":"127.0.0.1:34746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":4489,"request content":"key:\"/registry/pods/kube-system/kube-proxy-nldwt\" "} {"level":"warn","ts":"2026-07-09T16:43:40.51151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:40.083248Z","time spent":"426.852862ms","remote":"127.0.0.1:34746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:43:40.840738Z","caller":"traceutil/trace.go:171","msg":"trace[630310414] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"325.390336ms","start":"2026-07-09T16:43:40.515311Z","end":"2026-07-09T16:43:40.840701Z","steps":["trace[630310414] 'process raft request' (duration: 300.314232ms)","trace[630310414] 'compare' (duration: 24.789687ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:43:40.84088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:40.515259Z","time spent":"325.55819ms","remote":"127.0.0.1:34656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:43:42.106057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.588109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:43:42.106272Z","caller":"traceutil/trace.go:171","msg":"trace[1408739053] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"1.59053017s","start":"2026-07-09T16:43:40.515717Z","end":"2026-07-09T16:43:42.106247Z","steps":["trace[1408739053] 'process raft request' (duration: 617.570381ms)","trace[1408739053] 'compare' (duration: 972.403995ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:43:42.106361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:40.515679Z","time spent":"1.590637812s","remote":"127.0.0.1:34656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:43:42.106362Z","caller":"traceutil/trace.go:171","msg":"trace[2068849747] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"1.589959174s","start":"2026-07-09T16:43:40.516365Z","end":"2026-07-09T16:43:42.106324Z","steps":["trace[2068849747] 'process raft request' (duration: 1.589785809s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:42.106421Z","caller":"traceutil/trace.go:171","msg":"trace[643490115] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:385; }","duration":"1.499762098s","start":"2026-07-09T16:43:40.606647Z","end":"2026-07-09T16:43:42.106409Z","steps":["trace[643490115] 'read index received' (duration: 208.981996ms)","trace[643490115] 'applied index is now lower than readState.Index' (duration: 1.290779212s)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:43:42.106598Z","caller":"traceutil/trace.go:171","msg":"trace[150544582] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"1.585689675s","start":"2026-07-09T16:43:40.520895Z","end":"2026-07-09T16:43:42.106585Z","steps":["trace[150544582] 'process raft request' (duration: 1.58547368s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:42.106646Z","caller":"traceutil/trace.go:171","msg":"trace[485414950] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"1.588718523s","start":"2026-07-09T16:43:40.517917Z","end":"2026-07-09T16:43:42.106635Z","steps":["trace[485414950] 'process raft request' (duration: 1.588342573s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:42.106739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.500098427s","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-09T16:43:42.106775Z","caller":"traceutil/trace.go:171","msg":"trace[1453756395] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:377; }","duration":"1.500177919s","start":"2026-07-09T16:43:40.606588Z","end":"2026-07-09T16:43:42.106766Z","steps":["trace[1453756395] 'agreement among raft nodes before linearized reading' (duration: 1.500115417s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:42.106854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:40.606528Z","time spent":"1.500318002s","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T16:43:42.106879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:40.517909Z","time spent":"1.588765104s","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:43:42.106602Z","caller":"traceutil/trace.go:171","msg":"trace[1167765759] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"1.587999615s","start":"2026-07-09T16:43:40.518564Z","end":"2026-07-09T16:43:42.106564Z","steps":["trace[1167765759] 'process raft request' (duration: 1.587742628s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:42.108857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:40.518558Z","time spent":"1.590234552s","remote":"127.0.0.1:34746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:43:42.108891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:40.516351Z","time spent":"1.590068186s","remote":"127.0.0.1:34746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:43:42.106847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:40.520888Z","time spent":"1.585742617s","remote":"127.0.0.1:34746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:43:42.479694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.668285ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:43:42.479947Z","caller":"traceutil/trace.go:171","msg":"trace[842756956] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:391; }","duration":"373.461872ms","start":"2026-07-09T16:43:42.106453Z","end":"2026-07-09T16:43:42.479915Z","steps":["trace[842756956] 'read index received' (duration: 126.769685ms)","trace[842756956] 'applied index is now lower than readState.Index' (duration: 246.687897ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:43:42.480033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.975187ms","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-09T16:43:42.480141Z","caller":"traceutil/trace.go:171","msg":"trace[1451107233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:380; }","duration":"721.193002ms","start":"2026-07-09T16:43:41.758921Z","end":"2026-07-09T16:43:42.480114Z","steps":["trace[1451107233] 'agreement among raft nodes before linearized reading' (duration: 721.030268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:42.4802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:41.758903Z","time spent":"721.283215ms","remote":"127.0.0.1:34820","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-09T16:43:42.480275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.185813775s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-09T16:43:42.480328Z","caller":"traceutil/trace.go:171","msg":"trace[874880405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:380; }","duration":"1.185900668s","start":"2026-07-09T16:43:41.294414Z","end":"2026-07-09T16:43:42.480315Z","steps":["trace[874880405] 'agreement among raft nodes before linearized reading' (duration: 1.185811326s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:42.4803Z","caller":"traceutil/trace.go:171","msg":"trace[708933352] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"369.759146ms","start":"2026-07-09T16:43:42.110524Z","end":"2026-07-09T16:43:42.480283Z","steps":["trace[708933352] 'process raft request' (duration: 369.271913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:42.480393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:41.294397Z","time spent":"1.185978759s","remote":"127.0.0.1:34820","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-09T16:43:42.479969Z","caller":"traceutil/trace.go:171","msg":"trace[319154549] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"1.250166598s","start":"2026-07-09T16:43:41.229779Z","end":"2026-07-09T16:43:42.479946Z","steps":["trace[319154549] 'process raft request' (duration: 1.003170485s)","trace[319154549] 'compare' (duration: 246.562162ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:43:42.480648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:41.229739Z","time spent":"1.250827145s","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:43:42.480716Z","caller":"traceutil/trace.go:171","msg":"trace[638792852] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"367.668632ms","start":"2026-07-09T16:43:42.113035Z","end":"2026-07-09T16:43:42.480704Z","steps":["trace[638792852] 'process raft request' (duration: 366.809519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:42.481752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:42.113024Z","time spent":"368.660386ms","remote":"127.0.0.1:34656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:43:42.480762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:42.110505Z","time spent":"370.013912ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:43:42.480831Z","caller":"traceutil/trace.go:171","msg":"trace[641486760] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"367.772125ms","start":"2026-07-09T16:43:42.113046Z","end":"2026-07-09T16:43:42.480819Z","steps":["trace[641486760] 'process raft request' (duration: 366.831161ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:42.480865Z","caller":"traceutil/trace.go:171","msg":"trace[84209481] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"366.794839ms","start":"2026-07-09T16:43:42.11406Z","end":"2026-07-09T16:43:42.480855Z","steps":["trace[84209481] 'process raft request' (duration: 366.626475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:42.480962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.932778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-07-09T16:43:42.480988Z","caller":"traceutil/trace.go:171","msg":"trace[1470150906] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"366.43504ms","start":"2026-07-09T16:43:42.114523Z","end":"2026-07-09T16:43:42.480958Z","steps":["trace[1470150906] 'process raft request' (duration: 366.270596ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:42.482989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:42.113038Z","time spent":"369.861658ms","remote":"127.0.0.1:34656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:43:42.483029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:42.114513Z","time spent":"368.465823ms","remote":"127.0.0.1:34746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:43:42.483088Z","caller":"traceutil/trace.go:171","msg":"trace[710219948] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:383; }","duration":"370.065144ms","start":"2026-07-09T16:43:42.112971Z","end":"2026-07-09T16:43:42.483036Z","steps":["trace[710219948] 'agreement among raft nodes before linearized reading' (duration: 367.897138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:42.483108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:42.114048Z","time spent":"368.958095ms","remote":"127.0.0.1:35058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:43:42.483228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:42.112961Z","time spent":"370.212887ms","remote":"127.0.0.1:34746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6988,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-07-09T16:43:42.890968Z","caller":"traceutil/trace.go:171","msg":"trace[1847678887] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"403.511744ms","start":"2026-07-09T16:43:42.487431Z","end":"2026-07-09T16:43:42.890943Z","steps":["trace[1847678887] 'process raft request' (duration: 402.710273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:42.891201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:42.48741Z","time spent":"403.702558ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:43:42.893857Z","caller":"traceutil/trace.go:171","msg":"trace[46545788] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"406.069879ms","start":"2026-07-09T16:43:42.487767Z","end":"2026-07-09T16:43:42.893837Z","steps":["trace[46545788] 'process raft request' (duration: 405.834123ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:42.893862Z","caller":"traceutil/trace.go:171","msg":"trace[609594241] linearizableReadLoop","detail":"{readStateIndex:401; appliedIndex:397; }","duration":"172.484309ms","start":"2026-07-09T16:43:42.721358Z","end":"2026-07-09T16:43:42.893843Z","steps":["trace[609594241] 'read index received' (duration: 168.793055ms)","trace[609594241] 'applied index is now lower than readState.Index' (duration: 3.690584ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:43:42.893971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:42.487754Z","time spent":"406.150582ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:43:42.894292Z","caller":"traceutil/trace.go:171","msg":"trace[2046227672] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"404.067418ms","start":"2026-07-09T16:43:42.490206Z","end":"2026-07-09T16:43:42.894273Z","steps":["trace[2046227672] 'process raft request' (duration: 403.548595ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:43:42.894344Z","caller":"traceutil/trace.go:171","msg":"trace[2099619034] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"402.795255ms","start":"2026-07-09T16:43:42.49153Z","end":"2026-07-09T16:43:42.894325Z","steps":["trace[2099619034] 'process raft request' (duration: 402.272511ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:43:42.89449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:42.490189Z","time spent":"404.16071ms","remote":"127.0.0.1:35058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:43:42.894495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:43:42.491523Z","time spent":"402.878627ms","remote":"127.0.0.1:34746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:43:42.894714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.363682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.191\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-09T16:43:42.896611Z","caller":"traceutil/trace.go:171","msg":"trace[844589551] range","detail":"{range_begin:/registry/masterleases/199.19.213.191; range_end:; response_count:1; response_revision:387; }","duration":"175.288811ms","start":"2026-07-09T16:43:42.721309Z","end":"2026-07-09T16:43:42.896598Z","steps":["trace[844589551] 'agreement among raft nodes before linearized reading' (duration: 173.345502ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:07.213692Z","caller":"traceutil/trace.go:171","msg":"trace[1653671557] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"183.391172ms","start":"2026-07-09T16:44:07.030265Z","end":"2026-07-09T16:44:07.213656Z","steps":["trace[1653671557] 'process raft request' (duration: 181.149194ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:08.224037Z","caller":"traceutil/trace.go:171","msg":"trace[724314123] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"753.853261ms","start":"2026-07-09T16:44:07.470149Z","end":"2026-07-09T16:44:08.224002Z","steps":["trace[724314123] 'process raft request' (duration: 753.598324ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:08.224205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:07.470104Z","time spent":"754.025415ms","remote":"127.0.0.1:34628","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-09T16:44:08.232115Z","caller":"traceutil/trace.go:171","msg":"trace[1537842356] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"751.044269ms","start":"2026-07-09T16:44:07.481051Z","end":"2026-07-09T16:44:08.232095Z","steps":["trace[1537842356] 'process raft request' (duration: 750.519266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:08.232214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:07.481029Z","time spent":"751.139191ms","remote":"127.0.0.1:34628","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-09T16:44:08.232231Z","caller":"traceutil/trace.go:171","msg":"trace[1202607773] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"732.84665ms","start":"2026-07-09T16:44:07.49937Z","end":"2026-07-09T16:44:08.232217Z","steps":["trace[1202607773] 'process raft request' (duration: 732.278086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:08.232301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:07.499344Z","time spent":"732.924603ms","remote":"127.0.0.1:34628","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-09T16:44:08.232433Z","caller":"traceutil/trace.go:171","msg":"trace[862638841] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"761.955559ms","start":"2026-07-09T16:44:07.470469Z","end":"2026-07-09T16:44:08.232424Z","steps":["trace[862638841] 'process raft request' (duration: 760.934524ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:08.232474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:07.470457Z","time spent":"762.00021ms","remote":"127.0.0.1:34628","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-09T16:44:08.232588Z","caller":"traceutil/trace.go:171","msg":"trace[26567673] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"762.070782ms","start":"2026-07-09T16:44:07.470508Z","end":"2026-07-09T16:44:08.232579Z","steps":["trace[26567673] 'process raft request' (duration: 760.967904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:08.23263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:07.470504Z","time spent":"762.109783ms","remote":"127.0.0.1:34628","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-09T16:44:08.232727Z","caller":"traceutil/trace.go:171","msg":"trace[775394977] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"755.016721ms","start":"2026-07-09T16:44:07.477702Z","end":"2026-07-09T16:44:08.232719Z","steps":["trace[775394977] 'process raft request' (duration: 753.80387ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:08.232738Z","caller":"traceutil/trace.go:171","msg":"trace[235901564] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"750.32316ms","start":"2026-07-09T16:44:07.482403Z","end":"2026-07-09T16:44:08.232726Z","steps":["trace[235901564] 'process raft request' (duration: 749.190321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:08.232773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:07.477676Z","time spent":"755.077062ms","remote":"127.0.0.1:34628","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-09T16:44:08.232841Z","caller":"traceutil/trace.go:171","msg":"trace[720286675] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"748.143785ms","start":"2026-07-09T16:44:07.484691Z","end":"2026-07-09T16:44:08.232834Z","steps":["trace[720286675] 'process raft request' (duration: 746.928554ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:08.232844Z","caller":"traceutil/trace.go:171","msg":"trace[294556793] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"754.933189ms","start":"2026-07-09T16:44:07.47789Z","end":"2026-07-09T16:44:08.232823Z","steps":["trace[294556793] 'process raft request' (duration: 753.646566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:08.23287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:07.484682Z","time spent":"748.175655ms","remote":"127.0.0.1:34628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:44:08.232853Z","caller":"traceutil/trace.go:171","msg":"trace[710482206] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"714.892499ms","start":"2026-07-09T16:44:07.517948Z","end":"2026-07-09T16:44:08.23284Z","steps":["trace[710482206] 'process raft request' (duration: 713.830002ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:08.232943Z","caller":"traceutil/trace.go:171","msg":"trace[1753380660] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"594.607217ms","start":"2026-07-09T16:44:07.638328Z","end":"2026-07-09T16:44:08.232935Z","steps":["trace[1753380660] 'process raft request' (duration: 593.499149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:08.232988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:07.638295Z","time spent":"594.672998ms","remote":"127.0.0.1:34628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:44:08.233037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:07.517923Z","time spent":"715.021993ms","remote":"127.0.0.1:34628","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-09T16:44:08.232903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:07.477884Z","time spent":"754.98977ms","remote":"127.0.0.1:34628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:44:08.232915Z","caller":"traceutil/trace.go:171","msg":"trace[370697946] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"733.20531ms","start":"2026-07-09T16:44:07.499705Z","end":"2026-07-09T16:44:08.23291Z","steps":["trace[370697946] 'process raft request' (duration: 732.01726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:08.233212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:07.499687Z","time spent":"733.502808ms","remote":"127.0.0.1:34628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:44:08.232797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:07.482396Z","time spent":"750.372691ms","remote":"127.0.0.1:34628","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-09T16:44:08.501428Z","caller":"traceutil/trace.go:171","msg":"trace[703031506] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"116.614408ms","start":"2026-07-09T16:44:08.384778Z","end":"2026-07-09T16:44:08.501392Z","steps":["trace[703031506] 'process raft request' (duration: 116.548826ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:08.501704Z","caller":"traceutil/trace.go:171","msg":"trace[1392861781] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"151.104745ms","start":"2026-07-09T16:44:08.350593Z","end":"2026-07-09T16:44:08.501698Z","steps":["trace[1392861781] 'process raft request' (duration: 150.568962ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:08.50177Z","caller":"traceutil/trace.go:171","msg":"trace[740124722] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"151.071583ms","start":"2026-07-09T16:44:08.350693Z","end":"2026-07-09T16:44:08.501765Z","steps":["trace[740124722] 'process raft request' (duration: 150.56468ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:14.557087Z","caller":"traceutil/trace.go:171","msg":"trace[401489519] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"329.520393ms","start":"2026-07-09T16:44:14.227546Z","end":"2026-07-09T16:44:14.557067Z","steps":["trace[401489519] 'read index received' (duration: 328.983919ms)","trace[401489519] 'applied index is now lower than readState.Index' (duration: 535.304µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:44:14.557147Z","caller":"traceutil/trace.go:171","msg":"trace[909818688] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"707.087099ms","start":"2026-07-09T16:44:13.850031Z","end":"2026-07-09T16:44:14.557118Z","steps":["trace[909818688] 'process raft request' (duration: 706.75521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:14.557258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.705607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:44:14.557302Z","caller":"traceutil/trace.go:171","msg":"trace[1619541620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"329.80332ms","start":"2026-07-09T16:44:14.227485Z","end":"2026-07-09T16:44:14.557288Z","steps":["trace[1619541620] 'agreement among raft nodes before linearized reading' (duration: 329.708128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:14.557296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:13.849998Z","time spent":"707.239733ms","remote":"127.0.0.1:34662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:44:14.557331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:14.227464Z","time spent":"329.861021ms","remote":"127.0.0.1:34572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:44:14.557717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.062351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T16:44:14.557746Z","caller":"traceutil/trace.go:171","msg":"trace[111516513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:615; }","duration":"252.129093ms","start":"2026-07-09T16:44:14.305609Z","end":"2026-07-09T16:44:14.557739Z","steps":["trace[111516513] 'agreement among raft nodes before linearized reading' (duration: 251.969359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:15.601692Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903913080167495279,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T16:44:15.767603Z","caller":"traceutil/trace.go:171","msg":"trace[93581276] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"735.459488ms","start":"2026-07-09T16:44:15.03212Z","end":"2026-07-09T16:44:15.76758Z","steps":["trace[93581276] 'process raft request' (duration: 732.961004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:15.767741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:15.032094Z","time spent":"735.575611ms","remote":"127.0.0.1:34662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:44:15.818169Z","caller":"traceutil/trace.go:171","msg":"trace[1543980349] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:645; }","duration":"717.505147ms","start":"2026-07-09T16:44:15.100626Z","end":"2026-07-09T16:44:15.818131Z","steps":["trace[1543980349] 'read index received' (duration: 663.657532ms)","trace[1543980349] 'applied index is now lower than readState.Index' (duration: 53.846935ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:44:15.818206Z","caller":"traceutil/trace.go:171","msg":"trace[1300293824] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"359.684477ms","start":"2026-07-09T16:44:15.458491Z","end":"2026-07-09T16:44:15.818176Z","steps":["trace[1300293824] 'process raft request' (duration: 359.508402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:15.818309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.676552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-09T16:44:15.818315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:15.458466Z","time spent":"359.7778ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:44:15.818328Z","caller":"traceutil/trace.go:171","msg":"trace[1936297448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"717.761394ms","start":"2026-07-09T16:44:15.100561Z","end":"2026-07-09T16:44:15.818322Z","steps":["trace[1936297448] 'agreement among raft nodes before linearized reading' (duration: 717.673721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:15.818344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:15.100543Z","time spent":"717.797945ms","remote":"127.0.0.1:34820","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-09T16:44:15.818425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.39661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:44:15.818463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.640907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-09T16:44:15.818479Z","caller":"traceutil/trace.go:171","msg":"trace[1692223036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:622; }","duration":"571.694078ms","start":"2026-07-09T16:44:15.246781Z","end":"2026-07-09T16:44:15.818475Z","steps":["trace[1692223036] 'agreement among raft nodes before linearized reading' (duration: 571.650527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:15.818479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.346963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8plfj\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-07-09T16:44:15.818492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:15.24676Z","time spent":"571.729529ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-09T16:44:15.818495Z","caller":"traceutil/trace.go:171","msg":"trace[1598730079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"590.542063ms","start":"2026-07-09T16:44:15.227933Z","end":"2026-07-09T16:44:15.818475Z","steps":["trace[1598730079] 'agreement among raft nodes before linearized reading' (duration: 590.381309ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:15.818504Z","caller":"traceutil/trace.go:171","msg":"trace[561914800] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8plfj; range_end:; response_count:1; response_revision:622; }","duration":"246.398055ms","start":"2026-07-09T16:44:15.572098Z","end":"2026-07-09T16:44:15.818496Z","steps":["trace[561914800] 'agreement among raft nodes before linearized reading' (duration: 246.325933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:15.818534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:15.227914Z","time spent":"590.613305ms","remote":"127.0.0.1:34572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:44:17.074723Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903913080167495290,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:44:17.575786Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903913080167495290,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:44:17.731415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.48574708s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:44:17.73151Z","caller":"traceutil/trace.go:171","msg":"trace[481248904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"1.485884814s","start":"2026-07-09T16:44:16.245601Z","end":"2026-07-09T16:44:17.731486Z","steps":["trace[481248904] 'range keys from in-memory index tree' (duration: 1.485677018s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:17.731558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.245585Z","time spent":"1.485962735s","remote":"127.0.0.1:34572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:44:17.79966Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.498807766s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T16:44:17.801062Z","caller":"traceutil/trace.go:171","msg":"trace[2062326337] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"1.226771652s","start":"2026-07-09T16:44:16.574261Z","end":"2026-07-09T16:44:17.801033Z","steps":["trace[2062326337] 'read index received' (duration: 1.225857028s)","trace[2062326337] 'applied index is now lower than readState.Index' (duration: 912.664µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:44:17.80116Z","caller":"traceutil/trace.go:171","msg":"trace[1286849967] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"1.502123721s","start":"2026-07-09T16:44:16.299Z","end":"2026-07-09T16:44:17.801124Z","steps":["trace[1286849967] 'process raft request' (duration: 1.501420783s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:17.801314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109818054s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-09T16:44:17.801324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.298974Z","time spent":"1.502261934s","remote":"127.0.0.1:34662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:44:17.80136Z","caller":"traceutil/trace.go:171","msg":"trace[194199673] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:627; }","duration":"1.109908377s","start":"2026-07-09T16:44:16.691439Z","end":"2026-07-09T16:44:17.801348Z","steps":["trace[194199673] 'agreement among raft nodes before linearized reading' (duration: 1.109769723s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:17.801337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.205444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:44:17.801392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.691424Z","time spent":"1.109961037s","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T16:44:17.801396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.22709635s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T16:44:17.801455Z","caller":"traceutil/trace.go:171","msg":"trace[813200469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"576.372209ms","start":"2026-07-09T16:44:17.225058Z","end":"2026-07-09T16:44:17.80143Z","steps":["trace[813200469] 'agreement among raft nodes before linearized reading' (duration: 576.186794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:17.801515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:17.225035Z","time spent":"576.470862ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T16:44:17.801532Z","caller":"traceutil/trace.go:171","msg":"trace[565224377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"1.227270764s","start":"2026-07-09T16:44:16.57422Z","end":"2026-07-09T16:44:17.801491Z","steps":["trace[565224377] 'agreement among raft nodes before linearized reading' (duration: 1.226982907s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:17.801711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:16.574201Z","time spent":"1.22746836s","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T16:44:19.012247Z","caller":"traceutil/trace.go:171","msg":"trace[237994102] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"144.787732ms","start":"2026-07-09T16:44:18.867435Z","end":"2026-07-09T16:44:19.012223Z","steps":["trace[237994102] 'process raft request' (duration: 143.556871ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:22.933856Z","caller":"traceutil/trace.go:171","msg":"trace[1037336538] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"139.504917ms","start":"2026-07-09T16:44:22.794259Z","end":"2026-07-09T16:44:22.933764Z","steps":["trace[1037336538] 'process raft request' (duration: 138.669645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:35.165138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.461179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:44:35.165254Z","caller":"traceutil/trace.go:171","msg":"trace[264856317] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:801; }","duration":"961.596882ms","start":"2026-07-09T16:44:34.20364Z","end":"2026-07-09T16:44:35.165236Z","steps":["trace[264856317] 'count revisions from in-memory index tree' (duration: 961.375837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:35.16517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.123846ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:44:35.165297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:34.203552Z","time spent":"961.733356ms","remote":"127.0.0.1:34798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":1,"response size":30,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-07-09T16:44:35.165318Z","caller":"traceutil/trace.go:171","msg":"trace[1380376697] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:801; }","duration":"272.29328ms","start":"2026-07-09T16:44:34.893005Z","end":"2026-07-09T16:44:35.165299Z","steps":["trace[1380376697] 'range keys from in-memory index tree' (duration: 272.102295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:35.165602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.225099547s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-09T16:44:35.165634Z","caller":"traceutil/trace.go:171","msg":"trace[468788933] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:801; }","duration":"1.225167919s","start":"2026-07-09T16:44:33.940456Z","end":"2026-07-09T16:44:35.165624Z","steps":["trace[468788933] 'range keys from in-memory index tree' (duration: 1.224956045s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:35.165663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:33.940437Z","time spent":"1.225219501s","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T16:44:35.165842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.224791881s","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-09T16:44:35.16589Z","caller":"traceutil/trace.go:171","msg":"trace[1366579776] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:801; }","duration":"1.224864572s","start":"2026-07-09T16:44:33.941011Z","end":"2026-07-09T16:44:35.165875Z","steps":["trace[1366579776] 'range keys from in-memory index tree' (duration: 1.224676457s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:35.165935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:33.940993Z","time spent":"1.224928174s","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T16:44:35.165972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.216856016s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-09T16:44:35.165977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.193184147s","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-09T16:44:35.166012Z","caller":"traceutil/trace.go:171","msg":"trace[343327567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:801; }","duration":"1.216921358s","start":"2026-07-09T16:44:33.949076Z","end":"2026-07-09T16:44:35.165998Z","steps":["trace[343327567] 'range keys from in-memory index tree' (duration: 1.216724943s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:44:35.166025Z","caller":"traceutil/trace.go:171","msg":"trace[129168557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:801; }","duration":"1.193266069s","start":"2026-07-09T16:44:33.972746Z","end":"2026-07-09T16:44:35.166012Z","steps":["trace[129168557] 'range keys from in-memory index tree' (duration: 1.193034124s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:35.166064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:33.972728Z","time spent":"1.19332731s","remote":"127.0.0.1:34820","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-09T16:44:35.166064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.319852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:44:35.166099Z","caller":"traceutil/trace.go:171","msg":"trace[1187389246] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:801; }","duration":"563.399405ms","start":"2026-07-09T16:44:34.60269Z","end":"2026-07-09T16:44:35.166089Z","steps":["trace[1187389246] 'count revisions from in-memory index tree' (duration: 563.22234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:35.166124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:34.602667Z","time spent":"563.450646ms","remote":"127.0.0.1:34776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:44:35.166139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:33.949063Z","time spent":"1.216992709s","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T16:44:35.177974Z","caller":"traceutil/trace.go:171","msg":"trace[2124130089] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"483.162242ms","start":"2026-07-09T16:44:34.694799Z","end":"2026-07-09T16:44:35.177962Z","steps":["trace[2124130089] 'process raft request' (duration: 483.016458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:44:35.178495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:44:34.69477Z","time spent":"483.664355ms","remote":"127.0.0.1:34798","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-09T16:44:50.661718Z","caller":"traceutil/trace.go:171","msg":"trace[319584979] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"174.939007ms","start":"2026-07-09T16:44:50.486407Z","end":"2026-07-09T16:44:50.661346Z","steps":["trace[319584979] 'process raft request' (duration: 149.298748ms)","trace[319584979] 'compare' (duration: 25.522986ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:44:50.859964Z","caller":"traceutil/trace.go:171","msg":"trace[1162311362] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"102.382642ms","start":"2026-07-09T16:44:50.757546Z","end":"2026-07-09T16:44:50.859929Z","steps":["trace[1162311362] 'process raft request' (duration: 102.229479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:11.923899Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903913080167496128,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:45:12.424264Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903913080167496128,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:45:12.845019Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.456669485s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T16:45:12.845453Z","caller":"traceutil/trace.go:171","msg":"trace[1249566661] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"1.45724862s","start":"2026-07-09T16:45:11.388178Z","end":"2026-07-09T16:45:12.845426Z","steps":["trace[1249566661] 'process raft request' (duration: 1.457076995s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:12.845598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:11.388143Z","time spent":"1.457377623s","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:45:12.846141Z","caller":"traceutil/trace.go:171","msg":"trace[170109197] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1058; }","duration":"1.423139273s","start":"2026-07-09T16:45:11.422976Z","end":"2026-07-09T16:45:12.846115Z","steps":["trace[170109197] 'read index received' (duration: 1.42220635s)","trace[170109197] 'applied index is now lower than readState.Index' (duration: 931.963µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:12.846231Z","caller":"traceutil/trace.go:171","msg":"trace[1534712229] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"696.692918ms","start":"2026-07-09T16:45:12.149491Z","end":"2026-07-09T16:45:12.846184Z","steps":["trace[1534712229] 'process raft request' (duration: 696.474913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:12.846337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:12.149469Z","time spent":"696.812151ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:45:12.846361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.418805681s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-09T16:45:12.846415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.520596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:45:12.846429Z","caller":"traceutil/trace.go:171","msg":"trace[825689687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1021; }","duration":"1.418925414s","start":"2026-07-09T16:45:11.427488Z","end":"2026-07-09T16:45:12.846414Z","steps":["trace[825689687] 'agreement among raft nodes before linearized reading' (duration: 1.41876321s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:12.84645Z","caller":"traceutil/trace.go:171","msg":"trace[1646868144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1021; }","duration":"618.580478ms","start":"2026-07-09T16:45:12.227856Z","end":"2026-07-09T16:45:12.846437Z","steps":["trace[1646868144] 'agreement among raft nodes before linearized reading' (duration: 618.537827ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:12.846463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:11.427457Z","time spent":"1.418998566s","remote":"127.0.0.1:34820","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-09T16:45:12.846487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:12.227837Z","time spent":"618.641099ms","remote":"127.0.0.1:34572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:45:12.84658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263717034s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-09T16:45:12.846606Z","caller":"traceutil/trace.go:171","msg":"trace[319179213] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1021; }","duration":"1.263769836s","start":"2026-07-09T16:45:11.582829Z","end":"2026-07-09T16:45:12.846599Z","steps":["trace[319179213] 'agreement among raft nodes before linearized reading' (duration: 1.263706704s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:12.846626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:11.582814Z","time spent":"1.263806346s","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T16:45:12.846359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.423371449s","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-09T16:45:12.846668Z","caller":"traceutil/trace.go:171","msg":"trace[283108583] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1021; }","duration":"1.423735338s","start":"2026-07-09T16:45:11.422915Z","end":"2026-07-09T16:45:12.84665Z","steps":["trace[283108583] 'agreement among raft nodes before linearized reading' (duration: 1.423323238s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:12.846727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:11.422891Z","time spent":"1.423817701s","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T16:45:12.846794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.954214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.191\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-09T16:45:12.846805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.603276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:45:12.84683Z","caller":"traceutil/trace.go:171","msg":"trace[1558616768] range","detail":"{range_begin:/registry/masterleases/199.19.213.191; range_end:; response_count:1; response_revision:1021; }","duration":"116.038125ms","start":"2026-07-09T16:45:12.730782Z","end":"2026-07-09T16:45:12.84682Z","steps":["trace[1558616768] 'agreement among raft nodes before linearized reading' (duration: 115.931523ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:12.846838Z","caller":"traceutil/trace.go:171","msg":"trace[805101586] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1021; }","duration":"197.669948ms","start":"2026-07-09T16:45:12.649159Z","end":"2026-07-09T16:45:12.846829Z","steps":["trace[805101586] 'agreement among raft nodes before linearized reading' (duration: 197.594656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:12.84694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.367952005s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T16:45:12.84702Z","caller":"traceutil/trace.go:171","msg":"trace[1143543210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1021; }","duration":"1.368087228s","start":"2026-07-09T16:45:11.478914Z","end":"2026-07-09T16:45:12.847002Z","steps":["trace[1143543210] 'agreement among raft nodes before linearized reading' (duration: 1.367886002s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:12.847065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:11.478883Z","time spent":"1.36816968s","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T16:45:19.018219Z","caller":"traceutil/trace.go:171","msg":"trace[238146874] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"125.727746ms","start":"2026-07-09T16:45:18.892465Z","end":"2026-07-09T16:45:19.018192Z","steps":["trace[238146874] 'process raft request' (duration: 118.999052ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:19.018778Z","caller":"traceutil/trace.go:171","msg":"trace[1732476134] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"126.167459ms","start":"2026-07-09T16:45:18.892578Z","end":"2026-07-09T16:45:19.018746Z","steps":["trace[1732476134] 'process raft request' (duration: 125.925462ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:19.018798Z","caller":"traceutil/trace.go:171","msg":"trace[40644332] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"125.804889ms","start":"2026-07-09T16:45:18.892967Z","end":"2026-07-09T16:45:19.018772Z","steps":["trace[40644332] 'process raft request' (duration: 125.748428ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:19.018832Z","caller":"traceutil/trace.go:171","msg":"trace[1577377853] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"126.205ms","start":"2026-07-09T16:45:18.892581Z","end":"2026-07-09T16:45:19.018786Z","steps":["trace[1577377853] 'process raft request' (duration: 126.061366ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:25.162634Z","caller":"traceutil/trace.go:171","msg":"trace[2025750440] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"251.896287ms","start":"2026-07-09T16:45:24.910708Z","end":"2026-07-09T16:45:25.162605Z","steps":["trace[2025750440] 'process raft request' (duration: 185.505892ms)","trace[2025750440] 'compare' (duration: 66.242892ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:25.162856Z","caller":"traceutil/trace.go:171","msg":"trace[691168949] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"175.954853ms","start":"2026-07-09T16:45:24.986884Z","end":"2026-07-09T16:45:25.162839Z","steps":["trace[691168949] 'process raft request' (duration: 175.684676ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:25.162844Z","caller":"traceutil/trace.go:171","msg":"trace[1182661717] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1102; }","duration":"119.160427ms","start":"2026-07-09T16:45:25.043641Z","end":"2026-07-09T16:45:25.162801Z","steps":["trace[1182661717] 'read index received' (duration: 52.566067ms)","trace[1182661717] 'applied index is now lower than readState.Index' (duration: 66.58488ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:45:25.163104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.451574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-09T16:45:25.163178Z","caller":"traceutil/trace.go:171","msg":"trace[1074876328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1062; }","duration":"119.579119ms","start":"2026-07-09T16:45:25.043581Z","end":"2026-07-09T16:45:25.16316Z","steps":["trace[1074876328] 'agreement among raft nodes before linearized reading' (duration: 119.369483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:25.163275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.952736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-07-09T16:45:25.163276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.795077ms","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-09T16:45:25.163318Z","caller":"traceutil/trace.go:171","msg":"trace[1800003013] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1062; }","duration":"118.023277ms","start":"2026-07-09T16:45:25.045284Z","end":"2026-07-09T16:45:25.163307Z","steps":["trace[1800003013] 'agreement among raft nodes before linearized reading' (duration: 117.902774ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:25.163335Z","caller":"traceutil/trace.go:171","msg":"trace[1506144922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1062; }","duration":"118.8978ms","start":"2026-07-09T16:45:25.04442Z","end":"2026-07-09T16:45:25.163318Z","steps":["trace[1506144922] 'agreement among raft nodes before linearized reading' (duration: 118.661984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:25.163328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.256061ms","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-09T16:45:25.163416Z","caller":"traceutil/trace.go:171","msg":"trace[554882337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1062; }","duration":"116.346824ms","start":"2026-07-09T16:45:25.047046Z","end":"2026-07-09T16:45:25.163393Z","steps":["trace[554882337] 'agreement among raft nodes before linearized reading' (duration: 116.131268ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:25.370627Z","caller":"traceutil/trace.go:171","msg":"trace[520799768] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"202.554704ms","start":"2026-07-09T16:45:25.168038Z","end":"2026-07-09T16:45:25.370593Z","steps":["trace[520799768] 'process raft request' (duration: 185.46277ms)","trace[520799768] 'compare' (duration: 16.710235ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:25.370816Z","caller":"traceutil/trace.go:171","msg":"trace[2131419374] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"200.852431ms","start":"2026-07-09T16:45:25.169952Z","end":"2026-07-09T16:45:25.370804Z","steps":["trace[2131419374] 'process raft request' (duration: 200.479831ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:25.37082Z","caller":"traceutil/trace.go:171","msg":"trace[1213579212] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"200.48749ms","start":"2026-07-09T16:45:25.17013Z","end":"2026-07-09T16:45:25.370617Z","steps":["trace[1213579212] 'process raft request' (duration: 200.420948ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:27.149819Z","caller":"traceutil/trace.go:171","msg":"trace[1359511270] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"161.270611ms","start":"2026-07-09T16:45:26.988513Z","end":"2026-07-09T16:45:27.149784Z","steps":["trace[1359511270] 'process raft request' (duration: 161.046506ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:27.480471Z","caller":"traceutil/trace.go:171","msg":"trace[1233210082] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"102.852574ms","start":"2026-07-09T16:45:27.377582Z","end":"2026-07-09T16:45:27.480434Z","steps":["trace[1233210082] 'process raft request' (duration: 31.453989ms)","trace[1233210082] 'compare' (duration: 71.076197ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:27.480514Z","caller":"traceutil/trace.go:171","msg":"trace[2041223215] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"101.811397ms","start":"2026-07-09T16:45:27.378677Z","end":"2026-07-09T16:45:27.480488Z","steps":["trace[2041223215] 'process raft request' (duration: 101.768505ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:27.480644Z","caller":"traceutil/trace.go:171","msg":"trace[222102300] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"102.890624ms","start":"2026-07-09T16:45:27.377718Z","end":"2026-07-09T16:45:27.480608Z","steps":["trace[222102300] 'process raft request' (duration: 102.638867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:37.722075Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903913080167496333,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T16:45:37.964805Z","caller":"traceutil/trace.go:171","msg":"trace[1757764878] linearizableReadLoop","detail":"{readStateIndex:1144; appliedIndex:1143; }","duration":"742.777097ms","start":"2026-07-09T16:45:37.222Z","end":"2026-07-09T16:45:37.964777Z","steps":["trace[1757764878] 'read index received' (duration: 742.658143ms)","trace[1757764878] 'applied index is now lower than readState.Index' (duration: 117.804µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:37.964893Z","caller":"traceutil/trace.go:171","msg":"trace[281897023] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"784.571783ms","start":"2026-07-09T16:45:37.180269Z","end":"2026-07-09T16:45:37.964841Z","steps":["trace[281897023] 'process raft request' (duration: 784.330287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:37.96505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.037293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-09T16:45:37.96508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:37.180247Z","time spent":"784.720606ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:45:37.965098Z","caller":"traceutil/trace.go:171","msg":"trace[1115696218] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1100; }","duration":"743.140275ms","start":"2026-07-09T16:45:37.221944Z","end":"2026-07-09T16:45:37.965085Z","steps":["trace[1115696218] 'agreement among raft nodes before linearized reading' (duration: 742.969221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:37.965158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:37.221922Z","time spent":"743.225208ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T16:45:38.46531Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903913080167496337,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T16:45:38.613328Z","caller":"traceutil/trace.go:171","msg":"trace[1913699164] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"842.450577ms","start":"2026-07-09T16:45:37.770853Z","end":"2026-07-09T16:45:38.613304Z","steps":["trace[1913699164] 'process raft request' (duration: 842.239991ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:38.613533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:37.77083Z","time spent":"842.565859ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:45:38.746368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.988457ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-09T16:45:38.746828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.22342753s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-09T16:45:38.746832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.220833573s","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-09T16:45:38.746865Z","caller":"traceutil/trace.go:171","msg":"trace[1012093490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1102; }","duration":"1.223548274s","start":"2026-07-09T16:45:37.523307Z","end":"2026-07-09T16:45:38.746855Z","steps":["trace[1012093490] 'agreement among raft nodes before linearized reading' (duration: 1.22340193s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:38.746874Z","caller":"traceutil/trace.go:171","msg":"trace[1868334611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1102; }","duration":"1.220901645s","start":"2026-07-09T16:45:37.525962Z","end":"2026-07-09T16:45:38.746864Z","steps":["trace[1868334611] 'agreement among raft nodes before linearized reading' (duration: 1.220783932s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:38.74689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:37.523248Z","time spent":"1.223635985s","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T16:45:38.746875Z","caller":"traceutil/trace.go:171","msg":"trace[170051189] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"776.463722ms","start":"2026-07-09T16:45:37.970379Z","end":"2026-07-09T16:45:38.746843Z","steps":["trace[170051189] 'process raft request' (duration: 776.087323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:38.746906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:37.525952Z","time spent":"1.220945976s","remote":"127.0.0.1:34820","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-09T16:45:38.746951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.21922341s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-09T16:45:38.747001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:37.970358Z","time spent":"776.569784ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:45:38.747049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.372248518s","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-09T16:45:38.747043Z","caller":"traceutil/trace.go:171","msg":"trace[1271433379] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1102; }","duration":"1.219355394s","start":"2026-07-09T16:45:37.52767Z","end":"2026-07-09T16:45:38.747025Z","steps":["trace[1271433379] 'agreement among raft nodes before linearized reading' (duration: 1.219032996s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:38.747078Z","caller":"traceutil/trace.go:171","msg":"trace[1388421766] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1102; }","duration":"1.37231355s","start":"2026-07-09T16:45:37.374755Z","end":"2026-07-09T16:45:38.747069Z","steps":["trace[1388421766] 'agreement among raft nodes before linearized reading' (duration: 1.372228748s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:38.7471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:37.527617Z","time spent":"1.219472637s","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T16:45:38.747106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:37.374734Z","time spent":"1.372366861s","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T16:45:38.747276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.518975371s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:45:38.747335Z","caller":"traceutil/trace.go:171","msg":"trace[439876401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1102; }","duration":"1.519033653s","start":"2026-07-09T16:45:37.228268Z","end":"2026-07-09T16:45:38.747302Z","steps":["trace[439876401] 'agreement among raft nodes before linearized reading' (duration: 1.518978552s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:38.747373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:37.228257Z","time spent":"1.519107835s","remote":"127.0.0.1:34572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T16:45:38.746678Z","caller":"traceutil/trace.go:171","msg":"trace[868644429] linearizableReadLoop","detail":"{readStateIndex:1147; appliedIndex:1144; }","duration":"781.77301ms","start":"2026-07-09T16:45:37.964881Z","end":"2026-07-09T16:45:38.746654Z","steps":["trace[868644429] 'read index received' (duration: 648.180448ms)","trace[868644429] 'applied index is now lower than readState.Index' (duration: 133.591292ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:45:38.747732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.253792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:45:38.747838Z","caller":"traceutil/trace.go:171","msg":"trace[365062405] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1102; }","duration":"383.417636ms","start":"2026-07-09T16:45:38.364396Z","end":"2026-07-09T16:45:38.747814Z","steps":["trace[365062405] 'agreement among raft nodes before linearized reading' (duration: 383.19383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:38.747901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:38.364373Z","time spent":"383.513519ms","remote":"127.0.0.1:34904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:45:39.012518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.586473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:45:39.012925Z","caller":"traceutil/trace.go:171","msg":"trace[1040362100] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1147; }","duration":"256.756004ms","start":"2026-07-09T16:45:38.756153Z","end":"2026-07-09T16:45:39.012909Z","steps":["trace[1040362100] 'read index received' (duration: 38.683746ms)","trace[1040362100] 'applied index is now lower than readState.Index' (duration: 218.071298ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:39.013013Z","caller":"traceutil/trace.go:171","msg":"trace[1991905286] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"260.06482ms","start":"2026-07-09T16:45:38.752939Z","end":"2026-07-09T16:45:39.013003Z","steps":["trace[1991905286] 'process raft request' (duration: 259.933087ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:39.013027Z","caller":"traceutil/trace.go:171","msg":"trace[1701543701] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"260.288386ms","start":"2026-07-09T16:45:38.752705Z","end":"2026-07-09T16:45:39.012993Z","steps":["trace[1701543701] 'process raft request' (duration: 260.102131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:39.013178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.0262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:45:39.013215Z","caller":"traceutil/trace.go:171","msg":"trace[209585307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1106; }","duration":"257.092542ms","start":"2026-07-09T16:45:38.756111Z","end":"2026-07-09T16:45:39.013204Z","steps":["trace[209585307] 'agreement among raft nodes before linearized reading' (duration: 257.01488ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:39.013254Z","caller":"traceutil/trace.go:171","msg":"trace[342675938] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"260.609263ms","start":"2026-07-09T16:45:38.752631Z","end":"2026-07-09T16:45:39.013241Z","steps":["trace[342675938] 'process raft request' (duration: 106.22439ms)","trace[342675938] 'compare' (duration: 153.49564ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:40.131757Z","caller":"traceutil/trace.go:171","msg":"trace[1443523918] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"161.636831ms","start":"2026-07-09T16:45:39.970091Z","end":"2026-07-09T16:45:40.131728Z","steps":["trace[1443523918] 'process raft request' (duration: 161.459127ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:41.09284Z","caller":"traceutil/trace.go:171","msg":"trace[1005724188] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"293.890509ms","start":"2026-07-09T16:45:40.798922Z","end":"2026-07-09T16:45:41.092812Z","steps":["trace[1005724188] 'read index received' (duration: 293.749985ms)","trace[1005724188] 'applied index is now lower than readState.Index' (duration: 139.534µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:45:41.09314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.572664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:45:41.093199Z","caller":"traceutil/trace.go:171","msg":"trace[252560126] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1108; }","duration":"192.699209ms","start":"2026-07-09T16:45:40.900487Z","end":"2026-07-09T16:45:41.093186Z","steps":["trace[252560126] 'agreement among raft nodes before linearized reading' (duration: 192.548864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:41.09319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.245008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T16:45:41.093271Z","caller":"traceutil/trace.go:171","msg":"trace[1453497420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1108; }","duration":"294.373012ms","start":"2026-07-09T16:45:40.798878Z","end":"2026-07-09T16:45:41.093251Z","steps":["trace[1453497420] 'agreement among raft nodes before linearized reading' (duration: 294.095584ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:41.095122Z","caller":"traceutil/trace.go:171","msg":"trace[1800720665] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"338.665623ms","start":"2026-07-09T16:45:40.756264Z","end":"2026-07-09T16:45:41.09493Z","steps":["trace[1800720665] 'process raft request' (duration: 336.358723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:41.095258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:40.756241Z","time spent":"338.94785ms","remote":"127.0.0.1:34820","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-09T16:45:41.244033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.904966ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:45:41.244234Z","caller":"traceutil/trace.go:171","msg":"trace[89486716] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"140.739968ms","start":"2026-07-09T16:45:41.103464Z","end":"2026-07-09T16:45:41.244204Z","steps":["trace[89486716] 'process raft request' (duration: 35.450412ms)","trace[89486716] 'compare' (duration: 104.700641ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:41.24442Z","caller":"traceutil/trace.go:171","msg":"trace[1479326368] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"140.741137ms","start":"2026-07-09T16:45:41.103638Z","end":"2026-07-09T16:45:41.244379Z","steps":["trace[1479326368] 'process raft request' (duration: 140.47579ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:41.244487Z","caller":"traceutil/trace.go:171","msg":"trace[467268759] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"140.562194ms","start":"2026-07-09T16:45:41.103913Z","end":"2026-07-09T16:45:41.244475Z","steps":["trace[467268759] 'process raft request' (duration: 140.45516ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:41.244518Z","caller":"traceutil/trace.go:171","msg":"trace[1179657302] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"139.978928ms","start":"2026-07-09T16:45:41.104509Z","end":"2026-07-09T16:45:41.244488Z","steps":["trace[1179657302] 'process raft request' (duration: 139.648518ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:42.790689Z","caller":"traceutil/trace.go:171","msg":"trace[331004330] linearizableReadLoop","detail":"{readStateIndex:1158; appliedIndex:1157; }","duration":"154.148397ms","start":"2026-07-09T16:45:42.636503Z","end":"2026-07-09T16:45:42.790652Z","steps":["trace[331004330] 'read index received' (duration: 153.921221ms)","trace[331004330] 'applied index is now lower than readState.Index' (duration: 226.236µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:45:42.790841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.310022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:45:42.790822Z","caller":"traceutil/trace.go:171","msg":"trace[1494156784] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"652.464838ms","start":"2026-07-09T16:45:42.138322Z","end":"2026-07-09T16:45:42.790787Z","steps":["trace[1494156784] 'process raft request' (duration: 652.16336ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:42.790882Z","caller":"traceutil/trace.go:171","msg":"trace[1037759040] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1113; }","duration":"154.407464ms","start":"2026-07-09T16:45:42.636459Z","end":"2026-07-09T16:45:42.790867Z","steps":["trace[1037759040] 'agreement among raft nodes before linearized reading' (duration: 154.326981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:42.791002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:42.138298Z","time spent":"652.604363ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:45:43.600728Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903913080167496364,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:45:44.094518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.050139ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:45:44.094586Z","caller":"traceutil/trace.go:171","msg":"trace[1205204194] linearizableReadLoop","detail":"{readStateIndex:1160; appliedIndex:1159; }","duration":"994.802747ms","start":"2026-07-09T16:45:43.099773Z","end":"2026-07-09T16:45:44.094576Z","steps":["trace[1205204194] 'read index received' (duration: 699.554974ms)","trace[1205204194] 'applied index is now lower than readState.Index' (duration: 295.246663ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:44.094641Z","caller":"traceutil/trace.go:171","msg":"trace[383806339] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"1.202910196s","start":"2026-07-09T16:45:42.891725Z","end":"2026-07-09T16:45:44.094635Z","steps":["trace[383806339] 'process raft request' (duration: 907.665662ms)","trace[383806339] 'compare' (duration: 294.827413ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:45:44.094673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:42.891705Z","time spent":"1.202952877s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:45:44.094725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.594495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:45:44.094762Z","caller":"traceutil/trace.go:171","msg":"trace[886621875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1114; }","duration":"866.660626ms","start":"2026-07-09T16:45:43.228092Z","end":"2026-07-09T16:45:44.094753Z","steps":["trace[886621875] 'agreement among raft nodes before linearized reading' (duration: 866.561563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:44.094805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:43.228076Z","time spent":"866.722208ms","remote":"127.0.0.1:34572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:45:44.095055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.279249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-09T16:45:44.095084Z","caller":"traceutil/trace.go:171","msg":"trace[2042517307] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1114; }","duration":"995.3107ms","start":"2026-07-09T16:45:43.099766Z","end":"2026-07-09T16:45:44.095077Z","steps":["trace[2042517307] 'agreement among raft nodes before linearized reading' (duration: 995.214818ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:44.095103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:43.099705Z","time spent":"995.393572ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T16:45:44.095284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.803598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:45:44.095314Z","caller":"traceutil/trace.go:171","msg":"trace[1939487346] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1114; }","duration":"148.87232ms","start":"2026-07-09T16:45:43.946434Z","end":"2026-07-09T16:45:44.095306Z","steps":["trace[1939487346] 'agreement among raft nodes before linearized reading' (duration: 148.821189ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:44.09535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.589084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-09T16:45:44.095374Z","caller":"traceutil/trace.go:171","msg":"trace[478045698] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1114; }","duration":"846.634366ms","start":"2026-07-09T16:45:43.248735Z","end":"2026-07-09T16:45:44.095369Z","steps":["trace[478045698] 'agreement among raft nodes before linearized reading' (duration: 846.570074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:44.095389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:43.248728Z","time spent":"846.658276ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T16:45:44.095457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.379624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T16:45:44.095473Z","caller":"traceutil/trace.go:171","msg":"trace[869927095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1114; }","duration":"845.451195ms","start":"2026-07-09T16:45:43.250018Z","end":"2026-07-09T16:45:44.095469Z","steps":["trace[869927095] 'agreement among raft nodes before linearized reading' (duration: 845.409334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:44.095485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:43.250002Z","time spent":"845.479955ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T16:45:44.095547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.031ms","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-09T16:45:44.095562Z","caller":"traceutil/trace.go:171","msg":"trace[644886647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1114; }","duration":"846.086951ms","start":"2026-07-09T16:45:43.249471Z","end":"2026-07-09T16:45:44.095558Z","steps":["trace[644886647] 'agreement among raft nodes before linearized reading' (duration: 846.04727ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:44.095576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:43.249432Z","time spent":"846.139593ms","remote":"127.0.0.1:34820","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-09T16:45:44.095842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.236901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-09T16:45:44.095904Z","caller":"traceutil/trace.go:171","msg":"trace[1156255334] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1114; }","duration":"847.349654ms","start":"2026-07-09T16:45:43.248541Z","end":"2026-07-09T16:45:44.09589Z","steps":["trace[1156255334] 'agreement among raft nodes before linearized reading' (duration: 846.862821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:44.095938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:45:43.24852Z","time spent":"847.409325ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-09T16:45:44.238455Z","caller":"traceutil/trace.go:171","msg":"trace[1672627129] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"138.170291ms","start":"2026-07-09T16:45:44.100253Z","end":"2026-07-09T16:45:44.238423Z","steps":["trace[1672627129] 'process raft request' (duration: 94.465595ms)","trace[1672627129] 'compare' (duration: 43.002658ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:44.238716Z","caller":"traceutil/trace.go:171","msg":"trace[887201692] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"136.532828ms","start":"2026-07-09T16:45:44.102161Z","end":"2026-07-09T16:45:44.238694Z","steps":["trace[887201692] 'process raft request' (duration: 135.683946ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:44.238731Z","caller":"traceutil/trace.go:171","msg":"trace[1378806512] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"136.481527ms","start":"2026-07-09T16:45:44.102212Z","end":"2026-07-09T16:45:44.238693Z","steps":["trace[1378806512] 'process raft request' (duration: 135.732008ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:44.238896Z","caller":"traceutil/trace.go:171","msg":"trace[824254928] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"136.680372ms","start":"2026-07-09T16:45:44.102187Z","end":"2026-07-09T16:45:44.238867Z","steps":["trace[824254928] 'process raft request' (duration: 135.715638ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:44.238981Z","caller":"traceutil/trace.go:171","msg":"trace[242981996] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"134.823065ms","start":"2026-07-09T16:45:44.104136Z","end":"2026-07-09T16:45:44.23896Z","steps":["trace[242981996] 'process raft request' (duration: 133.839179ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:48.425123Z","caller":"traceutil/trace.go:171","msg":"trace[2064446354] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"112.983938ms","start":"2026-07-09T16:45:48.312115Z","end":"2026-07-09T16:45:48.425099Z","steps":["trace[2064446354] 'process raft request' (duration: 112.847294ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:48.425383Z","caller":"traceutil/trace.go:171","msg":"trace[1651682029] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"113.07088ms","start":"2026-07-09T16:45:48.312292Z","end":"2026-07-09T16:45:48.425363Z","steps":["trace[1651682029] 'process raft request' (duration: 112.718981ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:48.425139Z","caller":"traceutil/trace.go:171","msg":"trace[974888498] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"113.668735ms","start":"2026-07-09T16:45:48.311441Z","end":"2026-07-09T16:45:48.42511Z","steps":["trace[974888498] 'process raft request' (duration: 95.365479ms)","trace[974888498] 'compare' (duration: 18.010729ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:45:50.569883Z","caller":"traceutil/trace.go:171","msg":"trace[223553536] linearizableReadLoop","detail":"{readStateIndex:1183; appliedIndex:1182; }","duration":"140.219365ms","start":"2026-07-09T16:45:50.429633Z","end":"2026-07-09T16:45:50.569852Z","steps":["trace[223553536] 'read index received' (duration: 94.738383ms)","trace[223553536] 'applied index is now lower than readState.Index' (duration: 45.479592ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:45:50.570151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.43429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-09T16:45:50.570171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.197884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T16:45:50.57019Z","caller":"traceutil/trace.go:171","msg":"trace[1391238386] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1136; }","duration":"140.577244ms","start":"2026-07-09T16:45:50.429604Z","end":"2026-07-09T16:45:50.570182Z","steps":["trace[1391238386] 'agreement among raft nodes before linearized reading' (duration: 140.44128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:45:50.570173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.526583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-09T16:45:50.570241Z","caller":"traceutil/trace.go:171","msg":"trace[628788439] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1136; }","duration":"140.631986ms","start":"2026-07-09T16:45:50.429592Z","end":"2026-07-09T16:45:50.570224Z","steps":["trace[628788439] 'agreement among raft nodes before linearized reading' (duration: 140.434051ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:50.57026Z","caller":"traceutil/trace.go:171","msg":"trace[594025013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1136; }","duration":"140.275886ms","start":"2026-07-09T16:45:50.429932Z","end":"2026-07-09T16:45:50.570208Z","steps":["trace[594025013] 'agreement among raft nodes before linearized reading' (duration: 140.138613ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:45:50.570499Z","caller":"traceutil/trace.go:171","msg":"trace[1678783339] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"152.037791ms","start":"2026-07-09T16:45:50.41798Z","end":"2026-07-09T16:45:50.570018Z","steps":["trace[1678783339] 'process raft request' (duration: 106.489798ms)","trace[1678783339] 'compare' (duration: 45.143003ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:00.58102Z","caller":"traceutil/trace.go:171","msg":"trace[728723893] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"117.437852ms","start":"2026-07-09T16:46:00.46356Z","end":"2026-07-09T16:46:00.580997Z","steps":["trace[728723893] 'process raft request' (duration: 117.312789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:01.204744Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903913080167496608,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:46:01.705714Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903913080167496608,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:46:02.206275Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903913080167496608,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:46:02.698465Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.995661813s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T16:46:02.69884Z","caller":"traceutil/trace.go:171","msg":"trace[548702151] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"1.996097874s","start":"2026-07-09T16:46:00.702716Z","end":"2026-07-09T16:46:02.698814Z","steps":["trace[548702151] 'process raft request' (duration: 1.995964151s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:02.69899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:00.702701Z","time spent":"1.996215677s","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:03.228529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000025396s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-09T16:46:03.228649Z","caller":"traceutil/trace.go:171","msg":"trace[780775101] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00020858s","start":"2026-07-09T16:46:01.228418Z","end":"2026-07-09T16:46:03.228626Z","steps":["trace[780775101] 'agreement among raft nodes before linearized reading' (duration: 2.000069966s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:03.228736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:01.2284Z","time spent":"2.000322023s","remote":"127.0.0.1:34572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/09 16:46:03 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-09T16:46:03.760434Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.055051234s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T16:46:03.834791Z","caller":"traceutil/trace.go:171","msg":"trace[524905551] linearizableReadLoop","detail":"{readStateIndex:1274; appliedIndex:1271; }","duration":"3.130097318s","start":"2026-07-09T16:46:00.704667Z","end":"2026-07-09T16:46:03.834764Z","steps":["trace[524905551] 'read index received' (duration: 1.994025s)","trace[524905551] 'applied index is now lower than readState.Index' (duration: 1.136071038s)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:03.835103Z","caller":"traceutil/trace.go:171","msg":"trace[1223879172] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"3.130315875s","start":"2026-07-09T16:46:00.70477Z","end":"2026-07-09T16:46:03.835086Z","steps":["trace[1223879172] 'process raft request' (duration: 3.055858689s)","trace[1223879172] 'compare' (duration: 73.721816ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:03.835213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:00.704749Z","time spent":"3.130386996s","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:46:03.83542Z","caller":"traceutil/trace.go:171","msg":"trace[711477513] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"2.962597705s","start":"2026-07-09T16:46:00.872803Z","end":"2026-07-09T16:46:03.835401Z","steps":["trace[711477513] 'process raft request' (duration: 2.961872015s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:03.835537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:00.872779Z","time spent":"2.962684897s","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:03.835955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098624006s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.191\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-09T16:46:03.835984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.005477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:03.836009Z","caller":"traceutil/trace.go:171","msg":"trace[79929384] range","detail":"{range_begin:/registry/masterleases/199.19.213.191; range_end:; response_count:1; response_revision:1223; }","duration":"1.098710778s","start":"2026-07-09T16:46:02.737285Z","end":"2026-07-09T16:46:03.835996Z","steps":["trace[79929384] 'agreement among raft nodes before linearized reading' (duration: 1.098551774s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:03.836032Z","caller":"traceutil/trace.go:171","msg":"trace[1081763755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1223; }","duration":"607.081099ms","start":"2026-07-09T16:46:03.228933Z","end":"2026-07-09T16:46:03.836014Z","steps":["trace[1081763755] 'agreement among raft nodes before linearized reading' (duration: 607.005317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:03.836071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:03.228915Z","time spent":"607.147132ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:46:03.83603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.25041554s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-09T16:46:03.836084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:02.73727Z","time spent":"1.09879125s","remote":"127.0.0.1:34612","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.191\" "} {"level":"info","ts":"2026-07-09T16:46:03.836135Z","caller":"traceutil/trace.go:171","msg":"trace[553078090] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1223; }","duration":"1.250577095s","start":"2026-07-09T16:46:02.585531Z","end":"2026-07-09T16:46:03.836108Z","steps":["trace[553078090] 'agreement among raft nodes before linearized reading' (duration: 1.250279367s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:03.836168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.213235815s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-09T16:46:03.8362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:02.585512Z","time spent":"1.250677008s","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T16:46:03.836206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.131519086s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-09T16:46:03.836259Z","caller":"traceutil/trace.go:171","msg":"trace[2114202563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1223; }","duration":"1.213385468s","start":"2026-07-09T16:46:02.622851Z","end":"2026-07-09T16:46:03.836236Z","steps":["trace[2114202563] 'agreement among raft nodes before linearized reading' (duration: 1.213132092s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:03.83627Z","caller":"traceutil/trace.go:171","msg":"trace[1278404115] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1223; }","duration":"3.131601947s","start":"2026-07-09T16:46:00.704649Z","end":"2026-07-09T16:46:03.836251Z","steps":["trace[1278404115] 'agreement among raft nodes before linearized reading' (duration: 3.130822087s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:03.836311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:00.704643Z","time spent":"3.131659749s","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T16:46:03.836311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:02.622821Z","time spent":"1.213479211s","remote":"127.0.0.1:34820","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-09T16:46:03.988367Z","caller":"traceutil/trace.go:171","msg":"trace[577510755] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"147.534274ms","start":"2026-07-09T16:46:03.840808Z","end":"2026-07-09T16:46:03.988342Z","steps":["trace[577510755] 'process raft request' (duration: 147.426092ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:03.988518Z","caller":"traceutil/trace.go:171","msg":"trace[1189367011] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"147.715279ms","start":"2026-07-09T16:46:03.84075Z","end":"2026-07-09T16:46:03.988465Z","steps":["trace[1189367011] 'process raft request' (duration: 132.091673ms)","trace[1189367011] 'compare' (duration: 15.229155ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:03.988729Z","caller":"traceutil/trace.go:171","msg":"trace[570623734] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"147.137864ms","start":"2026-07-09T16:46:03.841559Z","end":"2026-07-09T16:46:03.988697Z","steps":["trace[570623734] 'process raft request' (duration: 146.738064ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:03.988803Z","caller":"traceutil/trace.go:171","msg":"trace[472890220] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"147.202036ms","start":"2026-07-09T16:46:03.841587Z","end":"2026-07-09T16:46:03.988789Z","steps":["trace[472890220] 'process raft request' (duration: 146.950349ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:03.988803Z","caller":"traceutil/trace.go:171","msg":"trace[2064306401] linearizableReadLoop","detail":"{readStateIndex:1279; appliedIndex:1274; }","duration":"144.27058ms","start":"2026-07-09T16:46:03.844506Z","end":"2026-07-09T16:46:03.988777Z","steps":["trace[2064306401] 'read index received' (duration: 54.950189ms)","trace[2064306401] 'applied index is now lower than readState.Index' (duration: 89.31908ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:03.98894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.410334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:03.988973Z","caller":"traceutil/trace.go:171","msg":"trace[8579022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1227; }","duration":"144.481436ms","start":"2026-07-09T16:46:03.844481Z","end":"2026-07-09T16:46:03.988963Z","steps":["trace[8579022] 'agreement among raft nodes before linearized reading' (duration: 144.384104ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:09.694226Z","caller":"traceutil/trace.go:171","msg":"trace[494008248] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"162.851423ms","start":"2026-07-09T16:46:09.531342Z","end":"2026-07-09T16:46:09.694193Z","steps":["trace[494008248] 'process raft request' (duration: 162.646678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:12.486326Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903913080167496676,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:46:12.683839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.458258803s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:12.68392Z","caller":"traceutil/trace.go:171","msg":"trace[3726506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1249; }","duration":"1.458380466s","start":"2026-07-09T16:46:11.225522Z","end":"2026-07-09T16:46:12.683902Z","steps":["trace[3726506] 'range keys from in-memory index tree' (duration: 1.45817216s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:12.683966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:11.225504Z","time spent":"1.458450808s","remote":"127.0.0.1:34572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:46:12.683953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.160192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:12.684028Z","caller":"traceutil/trace.go:171","msg":"trace[97798282] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1249; }","duration":"876.276046ms","start":"2026-07-09T16:46:11.807732Z","end":"2026-07-09T16:46:12.684008Z","steps":["trace[97798282] 'count revisions from in-memory index tree' (duration: 876.075571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:12.684074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:11.807715Z","time spent":"876.347188ms","remote":"127.0.0.1:52624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-07-09T16:46:12.695229Z","caller":"traceutil/trace.go:171","msg":"trace[1202924623] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"732.734294ms","start":"2026-07-09T16:46:11.962463Z","end":"2026-07-09T16:46:12.695197Z","steps":["trace[1202924623] 'process raft request' (duration: 732.389595ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:12.695311Z","caller":"traceutil/trace.go:171","msg":"trace[2110194295] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1302; }","duration":"709.667795ms","start":"2026-07-09T16:46:11.985631Z","end":"2026-07-09T16:46:12.695299Z","steps":["trace[2110194295] 'read index received' (duration: 709.141741ms)","trace[2110194295] 'applied index is now lower than readState.Index' (duration: 524.704µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:12.69535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:11.962447Z","time spent":"732.839868ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:12.695484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.85648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T16:46:12.695526Z","caller":"traceutil/trace.go:171","msg":"trace[1298685691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1250; }","duration":"709.939782ms","start":"2026-07-09T16:46:11.985576Z","end":"2026-07-09T16:46:12.695516Z","steps":["trace[1298685691] 'agreement among raft nodes before linearized reading' (duration: 709.817209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:12.695554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:11.985563Z","time spent":"709.982523ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T16:46:12.695686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.416014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-09T16:46:12.695725Z","caller":"traceutil/trace.go:171","msg":"trace[1719361555] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1250; }","duration":"668.492286ms","start":"2026-07-09T16:46:12.027223Z","end":"2026-07-09T16:46:12.695716Z","steps":["trace[1719361555] 'agreement among raft nodes before linearized reading' (duration: 668.378783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:12.695757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:12.027202Z","time spent":"668.544037ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T16:46:12.695856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.805781ms","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-09T16:46:12.695892Z","caller":"traceutil/trace.go:171","msg":"trace[1810163565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1250; }","duration":"619.843522ms","start":"2026-07-09T16:46:12.07604Z","end":"2026-07-09T16:46:12.695884Z","steps":["trace[1810163565] 'agreement among raft nodes before linearized reading' (duration: 619.7581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:12.695923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:12.075991Z","time spent":"619.924044ms","remote":"127.0.0.1:34820","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-09T16:46:12.695933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.020582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-09T16:46:12.695961Z","caller":"traceutil/trace.go:171","msg":"trace[605795832] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1250; }","duration":"621.087854ms","start":"2026-07-09T16:46:12.074864Z","end":"2026-07-09T16:46:12.695952Z","steps":["trace[605795832] 'agreement among raft nodes before linearized reading' (duration: 621.010012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:12.695994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:12.07484Z","time spent":"621.146385ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-09T16:46:14.941306Z","caller":"traceutil/trace.go:171","msg":"trace[1609232593] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"159.339072ms","start":"2026-07-09T16:46:14.781951Z","end":"2026-07-09T16:46:14.94129Z","steps":["trace[1609232593] 'process raft request' (duration: 127.33918ms)","trace[1609232593] 'compare' (duration: 31.874399ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:14.941486Z","caller":"traceutil/trace.go:171","msg":"trace[401467007] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1313; }","duration":"142.907344ms","start":"2026-07-09T16:46:14.798558Z","end":"2026-07-09T16:46:14.941465Z","steps":["trace[401467007] 'read index received' (duration: 110.671287ms)","trace[401467007] 'applied index is now lower than readState.Index' (duration: 32.234277ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:14.941612Z","caller":"traceutil/trace.go:171","msg":"trace[1744847757] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"159.444595ms","start":"2026-07-09T16:46:14.782149Z","end":"2026-07-09T16:46:14.941593Z","steps":["trace[1744847757] 'process raft request' (duration: 159.119756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:14.941885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.248933ms","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-09T16:46:14.941946Z","caller":"traceutil/trace.go:171","msg":"trace[1655466901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1261; }","duration":"143.411957ms","start":"2026-07-09T16:46:14.798516Z","end":"2026-07-09T16:46:14.941928Z","steps":["trace[1655466901] 'agreement among raft nodes before linearized reading' (duration: 143.179521ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:17.171284Z","caller":"traceutil/trace.go:171","msg":"trace[1313633476] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1317; }","duration":"388.778376ms","start":"2026-07-09T16:46:16.782474Z","end":"2026-07-09T16:46:17.171253Z","steps":["trace[1313633476] 'read index received' (duration: 386.790624ms)","trace[1313633476] 'applied index is now lower than readState.Index' (duration: 1.986591ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:17.17172Z","caller":"traceutil/trace.go:171","msg":"trace[1636265162] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"434.918159ms","start":"2026-07-09T16:46:16.736769Z","end":"2026-07-09T16:46:17.171687Z","steps":["trace[1636265162] 'process raft request' (duration: 432.582798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:17.17184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.230156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T16:46:17.171897Z","caller":"traceutil/trace.go:171","msg":"trace[730082952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1264; }","duration":"377.341399ms","start":"2026-07-09T16:46:16.794544Z","end":"2026-07-09T16:46:17.171885Z","steps":["trace[730082952] 'agreement among raft nodes before linearized reading' (duration: 377.170084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:17.171935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:16.794519Z","time spent":"377.407571ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T16:46:17.171971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:16.736747Z","time spent":"435.050692ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:17.172165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.69629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-09T16:46:17.172204Z","caller":"traceutil/trace.go:171","msg":"trace[2025489775] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1264; }","duration":"389.773613ms","start":"2026-07-09T16:46:16.782421Z","end":"2026-07-09T16:46:17.172195Z","steps":["trace[2025489775] 'agreement among raft nodes before linearized reading' (duration: 389.680229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:17.172236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:16.782394Z","time spent":"389.831953ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T16:46:17.172444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.144086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:46:17.172482Z","caller":"traceutil/trace.go:171","msg":"trace[10550379] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1264; }","duration":"164.222118ms","start":"2026-07-09T16:46:17.008251Z","end":"2026-07-09T16:46:17.172473Z","steps":["trace[10550379] 'agreement among raft nodes before linearized reading' (duration: 164.168396ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:17.172665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.190711ms","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-09T16:46:17.172701Z","caller":"traceutil/trace.go:171","msg":"trace[1689427009] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1264; }","duration":"209.259433ms","start":"2026-07-09T16:46:16.963433Z","end":"2026-07-09T16:46:17.172692Z","steps":["trace[1689427009] 'agreement among raft nodes before linearized reading' (duration: 209.172891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:17.172811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.634528ms","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-09T16:46:17.172842Z","caller":"traceutil/trace.go:171","msg":"trace[374969303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1264; }","duration":"227.713241ms","start":"2026-07-09T16:46:16.945121Z","end":"2026-07-09T16:46:17.172834Z","steps":["trace[374969303] 'agreement among raft nodes before linearized reading' (duration: 227.641529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:17.174498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.423334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-09T16:46:17.17454Z","caller":"traceutil/trace.go:171","msg":"trace[1951136261] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1264; }","duration":"229.482266ms","start":"2026-07-09T16:46:16.94504Z","end":"2026-07-09T16:46:17.174522Z","steps":["trace[1951136261] 'agreement among raft nodes before linearized reading' (duration: 227.880485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:19.724161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.401903ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-09T16:46:19.724642Z","caller":"traceutil/trace.go:171","msg":"trace[975664893] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1323; }","duration":"752.491054ms","start":"2026-07-09T16:46:18.97213Z","end":"2026-07-09T16:46:19.724621Z","steps":["trace[975664893] 'read index received' (duration: 312.659298ms)","trace[975664893] 'applied index is now lower than readState.Index' (duration: 439.829396ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:19.724773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.634397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:46:19.724797Z","caller":"traceutil/trace.go:171","msg":"trace[1468332273] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1269; }","duration":"752.698939ms","start":"2026-07-09T16:46:18.972092Z","end":"2026-07-09T16:46:19.724791Z","steps":["trace[1468332273] 'agreement among raft nodes before linearized reading' (duration: 752.628007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:19.724821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:18.972077Z","time spent":"752.73894ms","remote":"127.0.0.1:34986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:46:19.724931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.436834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:19.724982Z","caller":"traceutil/trace.go:171","msg":"trace[783331346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1270; }","duration":"483.587318ms","start":"2026-07-09T16:46:19.241379Z","end":"2026-07-09T16:46:19.724966Z","steps":["trace[783331346] 'agreement among raft nodes before linearized reading' (duration: 483.489576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:19.724991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.38025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T16:46:19.72501Z","caller":"traceutil/trace.go:171","msg":"trace[186805510] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"547.290316ms","start":"2026-07-09T16:46:19.177713Z","end":"2026-07-09T16:46:19.725003Z","steps":["trace[186805510] 'process raft request' (duration: 546.863085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:19.725018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:19.241358Z","time spent":"483.65254ms","remote":"127.0.0.1:34572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T16:46:19.725037Z","caller":"traceutil/trace.go:171","msg":"trace[164238109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1270; }","duration":"520.449761ms","start":"2026-07-09T16:46:19.204576Z","end":"2026-07-09T16:46:19.725026Z","steps":["trace[164238109] 'agreement among raft nodes before linearized reading' (duration: 520.307017ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:19.725062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:19.204569Z","time spent":"520.488603ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T16:46:19.725072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:19.177697Z","time spent":"547.334577ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:19.725308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.979354ms","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-09T16:46:19.72543Z","caller":"traceutil/trace.go:171","msg":"trace[1528423060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1270; }","duration":"521.092277ms","start":"2026-07-09T16:46:19.204302Z","end":"2026-07-09T16:46:19.725394Z","steps":["trace[1528423060] 'agreement among raft nodes before linearized reading' (duration: 520.80928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:19.725477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:19.204281Z","time spent":"521.18363ms","remote":"127.0.0.1:34820","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-09T16:46:19.72554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.349394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-09T16:46:19.725576Z","caller":"traceutil/trace.go:171","msg":"trace[600620165] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1270; }","duration":"521.418636ms","start":"2026-07-09T16:46:19.204149Z","end":"2026-07-09T16:46:19.725568Z","steps":["trace[600620165] 'agreement among raft nodes before linearized reading' (duration: 521.331834ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:19.725597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:19.204131Z","time spent":"521.460297ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T16:46:19.72559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.092067ms","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-09T16:46:19.72564Z","caller":"traceutil/trace.go:171","msg":"trace[827616165] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1270; }","duration":"526.173479ms","start":"2026-07-09T16:46:19.19945Z","end":"2026-07-09T16:46:19.725624Z","steps":["trace[827616165] 'agreement among raft nodes before linearized reading' (duration: 526.029046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:19.725688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.799133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-09T16:46:19.725687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:19.199434Z","time spent":"526.242362ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-09T16:46:19.725709Z","caller":"traceutil/trace.go:171","msg":"trace[1405661627] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1270; }","duration":"522.859244ms","start":"2026-07-09T16:46:19.202844Z","end":"2026-07-09T16:46:19.725703Z","steps":["trace[1405661627] 'agreement among raft nodes before linearized reading' (duration: 522.802723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:19.72573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:19.202824Z","time spent":"522.901405ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-09T16:46:20.041354Z","caller":"traceutil/trace.go:171","msg":"trace[1611552568] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"312.289868ms","start":"2026-07-09T16:46:19.729035Z","end":"2026-07-09T16:46:20.041324Z","steps":["trace[1611552568] 'process raft request' (duration: 311.942399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:20.042129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:19.729015Z","time spent":"313.044427ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:46:20.144654Z","caller":"traceutil/trace.go:171","msg":"trace[543848384] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"414.714977ms","start":"2026-07-09T16:46:19.729908Z","end":"2026-07-09T16:46:20.144622Z","steps":["trace[543848384] 'process raft request' (duration: 392.134743ms)","trace[543848384] 'compare' (duration: 22.40635ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:20.144703Z","caller":"traceutil/trace.go:171","msg":"trace[1226770290] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"125.369733ms","start":"2026-07-09T16:46:20.019316Z","end":"2026-07-09T16:46:20.144686Z","steps":["trace[1226770290] 'process raft request' (duration: 125.336572ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:20.144741Z","caller":"traceutil/trace.go:171","msg":"trace[106099306] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"414.494421ms","start":"2026-07-09T16:46:19.730217Z","end":"2026-07-09T16:46:20.144711Z","steps":["trace[106099306] 'process raft request' (duration: 414.376638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:20.144806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:19.729893Z","time spent":"414.83139ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:20.144888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:19.730208Z","time spent":"414.598104ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:46:20.144766Z","caller":"traceutil/trace.go:171","msg":"trace[1199434260] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"414.803679ms","start":"2026-07-09T16:46:19.729929Z","end":"2026-07-09T16:46:20.144733Z","steps":["trace[1199434260] 'process raft request' (duration: 414.637005ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:20.14494Z","caller":"traceutil/trace.go:171","msg":"trace[1782218399] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"413.212648ms","start":"2026-07-09T16:46:19.731717Z","end":"2026-07-09T16:46:20.144929Z","steps":["trace[1782218399] 'process raft request' (duration: 412.91012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:20.145003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:19.731708Z","time spent":"413.26283ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:46:20.145031Z","caller":"traceutil/trace.go:171","msg":"trace[1779713670] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"414.236774ms","start":"2026-07-09T16:46:19.730788Z","end":"2026-07-09T16:46:20.145025Z","steps":["trace[1779713670] 'process raft request' (duration: 413.824224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:20.145027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:19.729903Z","time spent":"415.039325ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:20.145105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:19.730779Z","time spent":"414.273815ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:21.715498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.323842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:46:21.715605Z","caller":"traceutil/trace.go:171","msg":"trace[1731507134] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1334; }","duration":"487.176341ms","start":"2026-07-09T16:46:21.228413Z","end":"2026-07-09T16:46:21.715589Z","steps":["trace[1731507134] 'read index received' (duration: 64.672784ms)","trace[1731507134] 'applied index is now lower than readState.Index' (duration: 422.502427ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:21.715693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.277763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:21.715723Z","caller":"traceutil/trace.go:171","msg":"trace[1091174654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1280; }","duration":"487.336185ms","start":"2026-07-09T16:46:21.228376Z","end":"2026-07-09T16:46:21.715712Z","steps":["trace[1091174654] 'agreement among raft nodes before linearized reading' (duration: 487.260843ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:21.715685Z","caller":"traceutil/trace.go:171","msg":"trace[1867141404] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"942.943698ms","start":"2026-07-09T16:46:20.7727Z","end":"2026-07-09T16:46:21.715644Z","steps":["trace[1867141404] 'process raft request' (duration: 520.409071ms)","trace[1867141404] 'compare' (duration: 422.208369ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:21.715756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:21.228361Z","time spent":"487.386536ms","remote":"127.0.0.1:34572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:46:21.71585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:20.77268Z","time spent":"943.090232ms","remote":"127.0.0.1:34656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":822,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:46:22.538898Z","caller":"traceutil/trace.go:171","msg":"trace[1041555132] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"809.820426ms","start":"2026-07-09T16:46:21.729047Z","end":"2026-07-09T16:46:22.538867Z","steps":["trace[1041555132] 'process raft request' (duration: 795.932846ms)","trace[1041555132] 'compare' (duration: 12.892664ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:22.539048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:21.729023Z","time spent":"809.944389ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:46:22.539064Z","caller":"traceutil/trace.go:171","msg":"trace[851027483] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1335; }","duration":"492.799896ms","start":"2026-07-09T16:46:22.045901Z","end":"2026-07-09T16:46:22.538701Z","steps":["trace[851027483] 'read index received' (duration: 479.002229ms)","trace[851027483] 'applied index is now lower than readState.Index' (duration: 13.796546ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:22.539117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.208687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-09T16:46:22.539167Z","caller":"traceutil/trace.go:171","msg":"trace[1495294183] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1281; }","duration":"493.305809ms","start":"2026-07-09T16:46:22.045849Z","end":"2026-07-09T16:46:22.539155Z","steps":["trace[1495294183] 'agreement among raft nodes before linearized reading' (duration: 493.102674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:22.539206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:22.045831Z","time spent":"493.36482ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T16:46:22.539229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.978897ms","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-09T16:46:22.539257Z","caller":"traceutil/trace.go:171","msg":"trace[957708625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1281; }","duration":"390.016787ms","start":"2026-07-09T16:46:22.149231Z","end":"2026-07-09T16:46:22.539248Z","steps":["trace[957708625] 'agreement among raft nodes before linearized reading' (duration: 389.933645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:22.53929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:22.149227Z","time spent":"390.053038ms","remote":"127.0.0.1:34820","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-09T16:46:22.539315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.86613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-09T16:46:22.539384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.539521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:22.539394Z","caller":"traceutil/trace.go:171","msg":"trace[1927613913] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1281; }","duration":"390.973712ms","start":"2026-07-09T16:46:22.148402Z","end":"2026-07-09T16:46:22.539375Z","steps":["trace[1927613913] 'agreement among raft nodes before linearized reading' (duration: 390.767136ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:22.539419Z","caller":"traceutil/trace.go:171","msg":"trace[547916748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1281; }","duration":"310.601923ms","start":"2026-07-09T16:46:22.228809Z","end":"2026-07-09T16:46:22.539411Z","steps":["trace[547916748] 'agreement among raft nodes before linearized reading' (duration: 310.552801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:22.539435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:22.148387Z","time spent":"391.039723ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T16:46:22.53945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:22.228793Z","time spent":"310.650933ms","remote":"127.0.0.1:34572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:46:22.539641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.654875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-09T16:46:22.539682Z","caller":"traceutil/trace.go:171","msg":"trace[1408690409] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1281; }","duration":"390.721746ms","start":"2026-07-09T16:46:22.148951Z","end":"2026-07-09T16:46:22.539673Z","steps":["trace[1408690409] 'agreement among raft nodes before linearized reading' (duration: 390.623943ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:22.539712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:22.148937Z","time spent":"390.766157ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T16:46:22.539762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.806279ms","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-09T16:46:22.539804Z","caller":"traceutil/trace.go:171","msg":"trace[1345272164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1281; }","duration":"390.907511ms","start":"2026-07-09T16:46:22.148886Z","end":"2026-07-09T16:46:22.539793Z","steps":["trace[1345272164] 'agreement among raft nodes before linearized reading' (duration: 390.777428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:22.539838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:22.148865Z","time spent":"390.966312ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T16:46:22.727218Z","caller":"traceutil/trace.go:171","msg":"trace[342596949] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"182.88083ms","start":"2026-07-09T16:46:22.544314Z","end":"2026-07-09T16:46:22.727195Z","steps":["trace[342596949] 'process raft request' (duration: 182.839488ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:22.727344Z","caller":"traceutil/trace.go:171","msg":"trace[1995800524] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"183.863766ms","start":"2026-07-09T16:46:22.543443Z","end":"2026-07-09T16:46:22.727306Z","steps":["trace[1995800524] 'process raft request' (duration: 139.312314ms)","trace[1995800524] 'compare' (duration: 44.190882ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:22.727466Z","caller":"traceutil/trace.go:171","msg":"trace[1355431446] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"183.694881ms","start":"2026-07-09T16:46:22.54376Z","end":"2026-07-09T16:46:22.727455Z","steps":["trace[1355431446] 'process raft request' (duration: 183.31269ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:22.727689Z","caller":"traceutil/trace.go:171","msg":"trace[671801868] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"183.509617ms","start":"2026-07-09T16:46:22.544167Z","end":"2026-07-09T16:46:22.727677Z","steps":["trace[671801868] 'process raft request' (duration: 182.954242ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:23.29913Z","caller":"traceutil/trace.go:171","msg":"trace[1634178221] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"120.378834ms","start":"2026-07-09T16:46:23.178724Z","end":"2026-07-09T16:46:23.299103Z","steps":["trace[1634178221] 'process raft request' (duration: 120.202859ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:24.692514Z","caller":"traceutil/trace.go:171","msg":"trace[662308087] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"146.864678ms","start":"2026-07-09T16:46:24.545623Z","end":"2026-07-09T16:46:24.692488Z","steps":["trace[662308087] 'process raft request' (duration: 146.502328ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:24.980303Z","caller":"traceutil/trace.go:171","msg":"trace[1587084507] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1345; }","duration":"248.58278ms","start":"2026-07-09T16:46:24.731657Z","end":"2026-07-09T16:46:24.98024Z","steps":["trace[1587084507] 'read index received' (duration: 210.468423ms)","trace[1587084507] 'applied index is now lower than readState.Index' (duration: 38.112287ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:24.980563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.851427ms","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-09T16:46:24.980608Z","caller":"traceutil/trace.go:171","msg":"trace[399692384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1290; }","duration":"248.915478ms","start":"2026-07-09T16:46:24.731683Z","end":"2026-07-09T16:46:24.980599Z","steps":["trace[399692384] 'agreement among raft nodes before linearized reading' (duration: 248.796735ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:24.980328Z","caller":"traceutil/trace.go:171","msg":"trace[1182268753] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"283.445391ms","start":"2026-07-09T16:46:24.69685Z","end":"2026-07-09T16:46:24.980295Z","steps":["trace[1182268753] 'process raft request' (duration: 245.437768ms)","trace[1182268753] 'compare' (duration: 37.626443ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:24.980843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.908038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-09T16:46:24.980937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.444706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-09T16:46:24.980961Z","caller":"traceutil/trace.go:171","msg":"trace[983973466] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1290; }","duration":"248.515928ms","start":"2026-07-09T16:46:24.73244Z","end":"2026-07-09T16:46:24.980956Z","steps":["trace[983973466] 'agreement among raft nodes before linearized reading' (duration: 248.453926ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:24.980941Z","caller":"traceutil/trace.go:171","msg":"trace[722515176] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1290; }","duration":"249.299878ms","start":"2026-07-09T16:46:24.731627Z","end":"2026-07-09T16:46:24.980927Z","steps":["trace[722515176] 'agreement among raft nodes before linearized reading' (duration: 248.831186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:24.980879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.542848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-09T16:46:24.981192Z","caller":"traceutil/trace.go:171","msg":"trace[1371054900] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1290; }","duration":"248.870278ms","start":"2026-07-09T16:46:24.732302Z","end":"2026-07-09T16:46:24.981172Z","steps":["trace[1371054900] 'agreement among raft nodes before linearized reading' (duration: 248.420886ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:25.186903Z","caller":"traceutil/trace.go:171","msg":"trace[2078772268] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"201.171923ms","start":"2026-07-09T16:46:24.985712Z","end":"2026-07-09T16:46:25.186884Z","steps":["trace[2078772268] 'process raft request' (duration: 201.127342ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:25.187038Z","caller":"traceutil/trace.go:171","msg":"trace[328609323] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"201.687467ms","start":"2026-07-09T16:46:24.985318Z","end":"2026-07-09T16:46:25.187006Z","steps":["trace[328609323] 'process raft request' (duration: 127.074607ms)","trace[328609323] 'compare' (duration: 74.286011ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:46:25.187155Z","caller":"traceutil/trace.go:171","msg":"trace[364287578] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"201.778789ms","start":"2026-07-09T16:46:24.985361Z","end":"2026-07-09T16:46:25.18714Z","steps":["trace[364287578] 'process raft request' (duration: 201.43971ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:26.784276Z","caller":"traceutil/trace.go:171","msg":"trace[2134249575] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"136.115131ms","start":"2026-07-09T16:46:26.648135Z","end":"2026-07-09T16:46:26.78425Z","steps":["trace[2134249575] 'process raft request' (duration: 135.918736ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:33.205039Z","caller":"traceutil/trace.go:171","msg":"trace[276888814] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"701.541866ms","start":"2026-07-09T16:46:32.503473Z","end":"2026-07-09T16:46:33.205014Z","steps":["trace[276888814] 'process raft request' (duration: 701.408493ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:33.205064Z","caller":"traceutil/trace.go:171","msg":"trace[1740010843] linearizableReadLoop","detail":"{readStateIndex:1408; appliedIndex:1408; }","duration":"469.099724ms","start":"2026-07-09T16:46:32.735926Z","end":"2026-07-09T16:46:33.205026Z","steps":["trace[1740010843] 'read index received' (duration: 469.083504ms)","trace[1740010843] 'applied index is now lower than readState.Index' (duration: 13.27µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:46:33.205448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:32.503455Z","time spent":"701.658289ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:46:33.205484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.530245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.191\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-09T16:46:33.205566Z","caller":"traceutil/trace.go:171","msg":"trace[410218495] range","detail":"{range_begin:/registry/masterleases/199.19.213.191; range_end:; response_count:1; response_revision:1351; }","duration":"469.654838ms","start":"2026-07-09T16:46:32.735886Z","end":"2026-07-09T16:46:33.205541Z","steps":["trace[410218495] 'agreement among raft nodes before linearized reading' (duration: 469.293159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:33.205621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:32.735868Z","time spent":"469.74344ms","remote":"127.0.0.1:34612","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.191\" "} {"level":"warn","ts":"2026-07-09T16:46:33.206176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.305966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:46:33.206191Z","caller":"traceutil/trace.go:171","msg":"trace[512561799] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"402.537681ms","start":"2026-07-09T16:46:32.803631Z","end":"2026-07-09T16:46:33.206169Z","steps":["trace[512561799] 'process raft request' (duration: 402.378577ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:46:33.206248Z","caller":"traceutil/trace.go:171","msg":"trace[505380261] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1352; }","duration":"344.395218ms","start":"2026-07-09T16:46:32.861825Z","end":"2026-07-09T16:46:33.20622Z","steps":["trace[505380261] 'agreement among raft nodes before linearized reading' (duration: 344.280505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:33.206319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:32.86181Z","time spent":"344.488151ms","remote":"127.0.0.1:39400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:46:33.206346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.455531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-09T16:46:33.206416Z","caller":"traceutil/trace.go:171","msg":"trace[435822039] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1352; }","duration":"167.569114ms","start":"2026-07-09T16:46:33.038832Z","end":"2026-07-09T16:46:33.206401Z","steps":["trace[435822039] 'agreement among raft nodes before linearized reading' (duration: 167.350029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:33.20646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.058497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-09T16:46:33.206515Z","caller":"traceutil/trace.go:171","msg":"trace[1976906328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1352; }","duration":"192.15298ms","start":"2026-07-09T16:46:33.014347Z","end":"2026-07-09T16:46:33.2065Z","steps":["trace[1976906328] 'agreement among raft nodes before linearized reading' (duration: 191.978655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:46:33.207082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:46:32.803606Z","time spent":"402.635804ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:46:34.48948Z","caller":"traceutil/trace.go:171","msg":"trace[1342712773] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"292.935066ms","start":"2026-07-09T16:46:34.196517Z","end":"2026-07-09T16:46:34.489452Z","steps":["trace[1342712773] 'process raft request' (duration: 291.5141ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:47:06.842442Z","caller":"traceutil/trace.go:171","msg":"trace[1924680794] transaction","detail":"{read_only:false; response_revision:1524; number_of_response:1; }","duration":"172.883392ms","start":"2026-07-09T16:47:06.66952Z","end":"2026-07-09T16:47:06.842404Z","steps":["trace[1924680794] 'process raft request' (duration: 172.655476ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:47:07.446152Z","caller":"traceutil/trace.go:171","msg":"trace[1873868785] transaction","detail":"{read_only:false; response_revision:1526; number_of_response:1; }","duration":"121.687128ms","start":"2026-07-09T16:47:07.32444Z","end":"2026-07-09T16:47:07.446127Z","steps":["trace[1873868785] 'process raft request' (duration: 121.468713ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:47:07.709455Z","caller":"traceutil/trace.go:171","msg":"trace[1643895773] transaction","detail":"{read_only:false; response_revision:1528; number_of_response:1; }","duration":"258.102536ms","start":"2026-07-09T16:47:07.451254Z","end":"2026-07-09T16:47:07.709357Z","steps":["trace[1643895773] 'process raft request' (duration: 257.658714ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:47:07.709361Z","caller":"traceutil/trace.go:171","msg":"trace[1741288806] transaction","detail":"{read_only:false; response_revision:1529; number_of_response:1; }","duration":"258.008114ms","start":"2026-07-09T16:47:07.451319Z","end":"2026-07-09T16:47:07.709327Z","steps":["trace[1741288806] 'process raft request' (duration: 257.87349ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:47:07.709593Z","caller":"traceutil/trace.go:171","msg":"trace[1863979720] transaction","detail":"{read_only:false; response_revision:1531; number_of_response:1; }","duration":"258.102985ms","start":"2026-07-09T16:47:07.451473Z","end":"2026-07-09T16:47:07.709576Z","steps":["trace[1863979720] 'process raft request' (duration: 258.006743ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:47:07.709641Z","caller":"traceutil/trace.go:171","msg":"trace[1106693245] transaction","detail":"{read_only:false; response_revision:1530; number_of_response:1; }","duration":"258.247429ms","start":"2026-07-09T16:47:07.451373Z","end":"2026-07-09T16:47:07.70962Z","steps":["trace[1106693245] 'process raft request' (duration: 257.89854ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:47:23.897536Z","caller":"traceutil/trace.go:171","msg":"trace[718140560] transaction","detail":"{read_only:false; response_revision:1603; number_of_response:1; }","duration":"113.416413ms","start":"2026-07-09T16:47:23.784093Z","end":"2026-07-09T16:47:23.897509Z","steps":["trace[718140560] 'process raft request' (duration: 113.017523ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:47:24.047477Z","caller":"traceutil/trace.go:171","msg":"trace[1516148413] transaction","detail":"{read_only:false; response_revision:1606; number_of_response:1; }","duration":"144.768892ms","start":"2026-07-09T16:47:23.902678Z","end":"2026-07-09T16:47:24.047447Z","steps":["trace[1516148413] 'process raft request' (duration: 144.719671ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:47:24.047556Z","caller":"traceutil/trace.go:171","msg":"trace[1223959662] transaction","detail":"{read_only:false; response_revision:1605; number_of_response:1; }","duration":"145.259255ms","start":"2026-07-09T16:47:23.90226Z","end":"2026-07-09T16:47:24.047519Z","steps":["trace[1223959662] 'process raft request' (duration: 118.919545ms)","trace[1223959662] 'compare' (duration: 26.080623ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:47:32.280993Z","caller":"traceutil/trace.go:171","msg":"trace[1848011454] linearizableReadLoop","detail":"{readStateIndex:1709; appliedIndex:1708; }","duration":"177.795943ms","start":"2026-07-09T16:47:32.103175Z","end":"2026-07-09T16:47:32.280971Z","steps":["trace[1848011454] 'read index received' (duration: 177.530627ms)","trace[1848011454] 'applied index is now lower than readState.Index' (duration: 264.056µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:47:32.281099Z","caller":"traceutil/trace.go:171","msg":"trace[2013101018] transaction","detail":"{read_only:false; response_revision:1640; number_of_response:1; }","duration":"227.30898ms","start":"2026-07-09T16:47:32.053746Z","end":"2026-07-09T16:47:32.281055Z","steps":["trace[2013101018] 'process raft request' (duration: 227.054463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:47:32.281204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.009019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-09T16:47:32.281237Z","caller":"traceutil/trace.go:171","msg":"trace[1774537407] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1640; }","duration":"178.110782ms","start":"2026-07-09T16:47:32.103117Z","end":"2026-07-09T16:47:32.281228Z","steps":["trace[1774537407] 'agreement among raft nodes before linearized reading' (duration: 177.975269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:47:32.281343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.844679ms","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-09T16:47:32.281434Z","caller":"traceutil/trace.go:171","msg":"trace[79040007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1640; }","duration":"119.953292ms","start":"2026-07-09T16:47:32.161435Z","end":"2026-07-09T16:47:32.281389Z","steps":["trace[79040007] 'agreement among raft nodes before linearized reading' (duration: 119.759627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:47:32.281581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.363463ms","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-09T16:47:32.281604Z","caller":"traceutil/trace.go:171","msg":"trace[452630673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1640; }","duration":"120.426584ms","start":"2026-07-09T16:47:32.16117Z","end":"2026-07-09T16:47:32.281597Z","steps":["trace[452630673] 'agreement among raft nodes before linearized reading' (duration: 120.002834ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:47:32.391401Z","caller":"traceutil/trace.go:171","msg":"trace[2045562421] transaction","detail":"{read_only:false; response_revision:1643; number_of_response:1; }","duration":"105.16786ms","start":"2026-07-09T16:47:32.286198Z","end":"2026-07-09T16:47:32.391366Z","steps":["trace[2045562421] 'process raft request' (duration: 105.098419ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:47:32.391429Z","caller":"traceutil/trace.go:171","msg":"trace[2136170439] transaction","detail":"{read_only:false; response_revision:1642; number_of_response:1; }","duration":"105.272212ms","start":"2026-07-09T16:47:32.286137Z","end":"2026-07-09T16:47:32.391409Z","steps":["trace[2136170439] 'process raft request' (duration: 104.982705ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:47:34.078397Z","caller":"traceutil/trace.go:171","msg":"trace[1649434944] transaction","detail":"{read_only:false; response_revision:1648; number_of_response:1; }","duration":"129.438927ms","start":"2026-07-09T16:47:33.948933Z","end":"2026-07-09T16:47:34.078372Z","steps":["trace[1649434944] 'process raft request' (duration: 129.245672ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:47:36.601091Z","caller":"traceutil/trace.go:171","msg":"trace[2108977983] transaction","detail":"{read_only:false; response_revision:1660; number_of_response:1; }","duration":"129.472407ms","start":"2026-07-09T16:47:36.471596Z","end":"2026-07-09T16:47:36.601068Z","steps":["trace[2108977983] 'process raft request' (duration: 85.020121ms)","trace[2108977983] 'compare' (duration: 43.986154ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:47:36.601083Z","caller":"traceutil/trace.go:171","msg":"trace[2027694066] transaction","detail":"{read_only:false; response_revision:1661; number_of_response:1; }","duration":"129.106078ms","start":"2026-07-09T16:47:36.471951Z","end":"2026-07-09T16:47:36.601057Z","steps":["trace[2027694066] 'process raft request' (duration: 128.78259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:47:40.855522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.750795ms","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-09T16:47:40.855655Z","caller":"traceutil/trace.go:171","msg":"trace[339114982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1678; }","duration":"238.911639ms","start":"2026-07-09T16:47:40.616719Z","end":"2026-07-09T16:47:40.85563Z","steps":["trace[339114982] 'range keys from in-memory index tree' (duration: 238.610091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:47:40.855679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.304022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-09T16:47:40.855733Z","caller":"traceutil/trace.go:171","msg":"trace[1566653523] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1678; }","duration":"243.488987ms","start":"2026-07-09T16:47:40.612231Z","end":"2026-07-09T16:47:40.85572Z","steps":["trace[1566653523] 'range keys from in-memory index tree' (duration: 243.162578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:47:48.820866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.020372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:47:48.820978Z","caller":"traceutil/trace.go:171","msg":"trace[1441184006] transaction","detail":"{read_only:false; response_revision:1715; number_of_response:1; }","duration":"214.075287ms","start":"2026-07-09T16:47:48.606878Z","end":"2026-07-09T16:47:48.820954Z","steps":["trace[1441184006] 'process raft request' (duration: 24.882171ms)","trace[1441184006] 'compare' (duration: 188.93033ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:47:49.062498Z","caller":"traceutil/trace.go:171","msg":"trace[1553205335] transaction","detail":"{read_only:false; response_revision:1717; number_of_response:1; }","duration":"109.637056ms","start":"2026-07-09T16:47:48.952843Z","end":"2026-07-09T16:47:49.06248Z","steps":["trace[1553205335] 'process raft request' (duration: 104.754011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:47:49.292169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.122643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-09T16:47:49.292247Z","caller":"traceutil/trace.go:171","msg":"trace[771932202] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1717; }","duration":"104.252177ms","start":"2026-07-09T16:47:49.187975Z","end":"2026-07-09T16:47:49.292227Z","steps":["trace[771932202] 'range keys from in-memory index tree' (duration: 103.96217ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:47:50.020995Z","caller":"traceutil/trace.go:171","msg":"trace[1353573139] transaction","detail":"{read_only:false; response_revision:1720; number_of_response:1; }","duration":"206.119552ms","start":"2026-07-09T16:47:49.814855Z","end":"2026-07-09T16:47:50.020974Z","steps":["trace[1353573139] 'process raft request' (duration: 205.991229ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:47:50.226342Z","caller":"traceutil/trace.go:171","msg":"trace[1964256520] transaction","detail":"{read_only:false; response_revision:1721; number_of_response:1; }","duration":"270.820431ms","start":"2026-07-09T16:47:49.955494Z","end":"2026-07-09T16:47:50.226315Z","steps":["trace[1964256520] 'process raft request' (duration: 247.1408ms)","trace[1964256520] 'compare' (duration: 23.542608ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:47:50.977764Z","caller":"traceutil/trace.go:171","msg":"trace[257875320] transaction","detail":"{read_only:false; response_revision:1724; number_of_response:1; }","duration":"149.114564ms","start":"2026-07-09T16:47:50.828627Z","end":"2026-07-09T16:47:50.977742Z","steps":["trace[257875320] 'process raft request' (duration: 148.96418ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:47:51.314955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.103651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-09T16:47:51.315014Z","caller":"traceutil/trace.go:171","msg":"trace[1272726591] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1724; }","duration":"249.235445ms","start":"2026-07-09T16:47:51.065766Z","end":"2026-07-09T16:47:51.315002Z","steps":["trace[1272726591] 'range keys from in-memory index tree' (duration: 248.929617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:47:51.315233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.354651ms","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-09T16:47:51.3153Z","caller":"traceutil/trace.go:171","msg":"trace[1017620545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1724; }","duration":"286.490744ms","start":"2026-07-09T16:47:51.028792Z","end":"2026-07-09T16:47:51.315283Z","steps":["trace[1017620545] 'range keys from in-memory index tree' (duration: 286.027952ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:47:51.621893Z","caller":"traceutil/trace.go:171","msg":"trace[2130229956] transaction","detail":"{read_only:false; response_revision:1727; number_of_response:1; }","duration":"129.447387ms","start":"2026-07-09T16:47:51.492129Z","end":"2026-07-09T16:47:51.621576Z","steps":["trace[2130229956] 'process raft request' (duration: 129.250732ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:47:54.437719Z","caller":"traceutil/trace.go:171","msg":"trace[1969367474] linearizableReadLoop","detail":"{readStateIndex:1813; appliedIndex:1812; }","duration":"118.166966ms","start":"2026-07-09T16:47:54.319529Z","end":"2026-07-09T16:47:54.437696Z","steps":["trace[1969367474] 'read index received' (duration: 117.94501ms)","trace[1969367474] 'applied index is now lower than readState.Index' (duration: 221.146µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:47:54.438041Z","caller":"traceutil/trace.go:171","msg":"trace[323739484] transaction","detail":"{read_only:false; response_revision:1739; number_of_response:1; }","duration":"200.819426ms","start":"2026-07-09T16:47:54.23721Z","end":"2026-07-09T16:47:54.43803Z","steps":["trace[323739484] 'process raft request' (duration: 200.348694ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:47:54.438265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.72678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-09T16:47:54.43829Z","caller":"traceutil/trace.go:171","msg":"trace[587670575] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1739; }","duration":"118.799392ms","start":"2026-07-09T16:47:54.319483Z","end":"2026-07-09T16:47:54.438283Z","steps":["trace[587670575] 'agreement among raft nodes before linearized reading' (duration: 118.691169ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:47:54.560933Z","caller":"traceutil/trace.go:171","msg":"trace[255651450] transaction","detail":"{read_only:false; response_revision:1741; number_of_response:1; }","duration":"115.961929ms","start":"2026-07-09T16:47:54.444935Z","end":"2026-07-09T16:47:54.560897Z","steps":["trace[255651450] 'process raft request' (duration: 72.19653ms)","trace[255651450] 'compare' (duration: 43.500042ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:47:57.1697Z","caller":"traceutil/trace.go:171","msg":"trace[811701065] transaction","detail":"{read_only:false; response_revision:1752; number_of_response:1; }","duration":"124.777446ms","start":"2026-07-09T16:47:57.044896Z","end":"2026-07-09T16:47:57.169674Z","steps":["trace[811701065] 'process raft request' (duration: 122.798316ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:47:57.678699Z","caller":"traceutil/trace.go:171","msg":"trace[1731772267] transaction","detail":"{read_only:false; response_revision:1754; number_of_response:1; }","duration":"196.750351ms","start":"2026-07-09T16:47:57.481901Z","end":"2026-07-09T16:47:57.678652Z","steps":["trace[1731772267] 'process raft request' (duration: 99.303879ms)","trace[1731772267] 'compare' (duration: 97.213366ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:47:59.958578Z","caller":"traceutil/trace.go:171","msg":"trace[630434372] linearizableReadLoop","detail":"{readStateIndex:1839; appliedIndex:1838; }","duration":"203.286931ms","start":"2026-07-09T16:47:59.755264Z","end":"2026-07-09T16:47:59.95855Z","steps":["trace[630434372] 'read index received' (duration: 203.121496ms)","trace[630434372] 'applied index is now lower than readState.Index' (duration: 164.325µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:47:59.958821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.512826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-09T16:47:59.958879Z","caller":"traceutil/trace.go:171","msg":"trace[704602976] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1764; }","duration":"203.625489ms","start":"2026-07-09T16:47:59.755237Z","end":"2026-07-09T16:47:59.958862Z","steps":["trace[704602976] 'agreement among raft nodes before linearized reading' (duration: 203.426904ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:47:59.958909Z","caller":"traceutil/trace.go:171","msg":"trace[278877295] transaction","detail":"{read_only:false; response_revision:1764; number_of_response:1; }","duration":"268.296266ms","start":"2026-07-09T16:47:59.690578Z","end":"2026-07-09T16:47:59.958874Z","steps":["trace[278877295] 'process raft request' (duration: 267.774733ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:48:00.128538Z","caller":"traceutil/trace.go:171","msg":"trace[1375505415] transaction","detail":"{read_only:false; response_revision:1765; number_of_response:1; }","duration":"165.041653ms","start":"2026-07-09T16:47:59.963459Z","end":"2026-07-09T16:48:00.1285Z","steps":["trace[1375505415] 'process raft request' (duration: 136.906788ms)","trace[1375505415] 'compare' (duration: 27.874598ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:48:00.631229Z","caller":"traceutil/trace.go:171","msg":"trace[916020750] transaction","detail":"{read_only:false; response_revision:1767; number_of_response:1; }","duration":"165.420954ms","start":"2026-07-09T16:48:00.46578Z","end":"2026-07-09T16:48:00.631201Z","steps":["trace[916020750] 'process raft request' (duration: 165.26007ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:48:10.332214Z","caller":"traceutil/trace.go:171","msg":"trace[3687355] linearizableReadLoop","detail":"{readStateIndex:1888; appliedIndex:1887; }","duration":"103.735824ms","start":"2026-07-09T16:48:10.228456Z","end":"2026-07-09T16:48:10.332192Z","steps":["trace[3687355] 'read index received' (duration: 6.445816ms)","trace[3687355] 'applied index is now lower than readState.Index' (duration: 97.289348ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:48:10.33229Z","caller":"traceutil/trace.go:171","msg":"trace[1406780176] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"129.254111ms","start":"2026-07-09T16:48:10.20302Z","end":"2026-07-09T16:48:10.332274Z","steps":["trace[1406780176] 'process raft request' (duration: 31.924462ms)","trace[1406780176] 'compare' (duration: 97.167755ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:48:10.332392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.932539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:48:10.332437Z","caller":"traceutil/trace.go:171","msg":"trace[1738736018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1811; }","duration":"104.025351ms","start":"2026-07-09T16:48:10.228401Z","end":"2026-07-09T16:48:10.332426Z","steps":["trace[1738736018] 'agreement among raft nodes before linearized reading' (duration: 103.899198ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:48:33.880252Z","caller":"traceutil/trace.go:171","msg":"trace[1904908110] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"120.655447ms","start":"2026-07-09T16:48:33.759576Z","end":"2026-07-09T16:48:33.880232Z","steps":["trace[1904908110] 'process raft request' (duration: 120.553075ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:50:46.649256Z","caller":"traceutil/trace.go:171","msg":"trace[480745797] transaction","detail":"{read_only:false; response_revision:2549; number_of_response:1; }","duration":"107.34015ms","start":"2026-07-09T16:50:46.541864Z","end":"2026-07-09T16:50:46.649204Z","steps":["trace[480745797] 'process raft request' (duration: 107.091984ms)"],"step_count":1}