{"level":"warn","ts":"2026-07-04T10:06:08.569989Z","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-04T10:06:08.570088Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248: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.204.45.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248: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-04T10:06:08.570174Z","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-04T10:06:08.570188Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-07-04T10:06:08.570209Z","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-04T10:06:08.571119Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-07-04T10:06:08.571252Z","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.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248: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-04T10:06:08.574956Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.42576ms"} {"level":"info","ts":"2026-07-04T10:06:08.581093Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-07-04T10:06:08.581151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-07-04T10:06:08.581167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-07-04T10:06:08.581176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-04T10:06:08.581182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-07-04T10:06:08.581211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-07-04T10:06:08.588766Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-04T10:06:08.591612Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-04T10:06:08.59285Z","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-04T10:06:08.59549Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-04T10:06:08.596826Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-04T10:06:08.600436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-07-04T10:06:08.600524Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-07-04T10:06:08.60054Z","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-04T10:06:08.600695Z","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-04T10:06:08.600705Z","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-04T10:06:08.601212Z","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-04T10:06:08.601276Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-07-04T10:06:08.601378Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-07-04T10:06:08.601968Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-04T10:06:08.602031Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-04T10:06:08.685116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-07-04T10:06:08.685202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-04T10:06:08.685225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-07-04T10:06:08.685243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-07-04T10:06:08.685317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-07-04T10:06:08.685339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-07-04T10:06:08.685413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-07-04T10:06:08.691227Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-04T10:06:08.695573Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-07-04T10:06:08.695594Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-04T10:06:08.69569Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-04T10:06:08.697045Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-04T10:06:08.697127Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-04T10:06:08.697985Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-07-04T10:06:08.698182Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-04T10:06:08.698247Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-04T10:06:08.698464Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-04T10:06:08.699614Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"warn","ts":"2026-07-04T10:06:12.584671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.866105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-04T10:06:12.584994Z","caller":"traceutil/trace.go:171","msg":"trace[2114863772] transaction","detail":"{read_only:false; response_revision:199; number_of_response:1; }","duration":"176.551402ms","start":"2026-07-04T10:06:12.408384Z","end":"2026-07-04T10:06:12.584936Z","steps":["trace[2114863772] 'process raft request' (duration: 40.757506ms)","trace[2114863772] 'compare' (duration: 134.710041ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:06:15.656463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.903362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-07-04T10:06:15.656552Z","caller":"traceutil/trace.go:171","msg":"trace[840563465] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:266; }","duration":"244.040506ms","start":"2026-07-04T10:06:15.412497Z","end":"2026-07-04T10:06:15.656537Z","steps":["trace[840563465] 'range keys from in-memory index tree' (duration: 243.471752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:15.656618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.825062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-07-04T10:06:15.656883Z","caller":"traceutil/trace.go:171","msg":"trace[1309104402] transaction","detail":"{read_only:false; number_of_response:0; response_revision:266; }","duration":"244.199629ms","start":"2026-07-04T10:06:15.412635Z","end":"2026-07-04T10:06:15.656834Z","steps":["trace[1309104402] 'process raft request' (duration: 118.07806ms)","trace[1309104402] 'compare' (duration: 125.764821ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:06:15.658821Z","caller":"traceutil/trace.go:171","msg":"trace[228595517] linearizableReadLoop","detail":"{readStateIndex:279; appliedIndex:276; }","duration":"217.779399ms","start":"2026-07-04T10:06:15.441029Z","end":"2026-07-04T10:06:15.658808Z","steps":["trace[228595517] 'read index received' (duration: 89.671304ms)","trace[228595517] 'applied index is now lower than readState.Index' (duration: 128.107455ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:06:15.658905Z","caller":"traceutil/trace.go:171","msg":"trace[366950501] transaction","detail":"{read_only:false; number_of_response:0; response_revision:266; }","duration":"245.264694ms","start":"2026-07-04T10:06:15.413625Z","end":"2026-07-04T10:06:15.65889Z","steps":["trace[366950501] 'process raft request' (duration: 245.163672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:15.659046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.868179ms","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-04T10:06:15.658934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.903883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-07-04T10:06:15.659126Z","caller":"traceutil/trace.go:171","msg":"trace[1017342538] transaction","detail":"{read_only:false; number_of_response:0; response_revision:266; }","duration":"246.091683ms","start":"2026-07-04T10:06:15.413021Z","end":"2026-07-04T10:06:15.659112Z","steps":["trace[1017342538] 'process raft request' (duration: 245.721995ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:06:15.659131Z","caller":"traceutil/trace.go:171","msg":"trace[1436703788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:266; }","duration":"141.985831ms","start":"2026-07-04T10:06:15.517122Z","end":"2026-07-04T10:06:15.659108Z","steps":["trace[1436703788] 'agreement among raft nodes before linearized reading' (duration: 141.811527ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:06:15.659146Z","caller":"traceutil/trace.go:171","msg":"trace[1800167267] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:266; }","duration":"218.140018ms","start":"2026-07-04T10:06:15.440983Z","end":"2026-07-04T10:06:15.659123Z","steps":["trace[1800167267] 'agreement among raft nodes before linearized reading' (duration: 217.903852ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:06:21.846843Z","caller":"traceutil/trace.go:171","msg":"trace[1333769368] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"156.661356ms","start":"2026-07-04T10:06:21.690164Z","end":"2026-07-04T10:06:21.846825Z","steps":["trace[1333769368] 'process raft request' (duration: 156.558564ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:22.830615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.549129ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-04T10:06:22.830779Z","caller":"traceutil/trace.go:171","msg":"trace[240502414] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:373; }","duration":"169.800973ms","start":"2026-07-04T10:06:22.660919Z","end":"2026-07-04T10:06:22.83072Z","steps":["trace[240502414] 'read index received' (duration: 65.845595ms)","trace[240502414] 'applied index is now lower than readState.Index' (duration: 103.954158ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:06:22.830951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.04003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-04T10:06:22.830917Z","caller":"traceutil/trace.go:171","msg":"trace[88775299] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"576.794491ms","start":"2026-07-04T10:06:22.254072Z","end":"2026-07-04T10:06:22.830866Z","steps":["trace[88775299] 'process raft request' (duration: 472.74802ms)","trace[88775299] 'compare' (duration: 103.388165ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:06:22.830995Z","caller":"traceutil/trace.go:171","msg":"trace[447688297] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:359; }","duration":"170.129851ms","start":"2026-07-04T10:06:22.660852Z","end":"2026-07-04T10:06:22.830982Z","steps":["trace[447688297] 'agreement among raft nodes before linearized reading' (duration: 170.016329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:22.831096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:22.254049Z","time spent":"576.959525ms","remote":"127.0.0.1:34452","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-04T10:06:22.831294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.962788ms","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-04T10:06:22.831327Z","caller":"traceutil/trace.go:171","msg":"trace[591253305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:359; }","duration":"121.031739ms","start":"2026-07-04T10:06:22.710285Z","end":"2026-07-04T10:06:22.831317Z","steps":["trace[591253305] 'agreement among raft nodes before linearized reading' (duration: 120.978818ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:22.831581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.159968ms","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-04T10:06:22.831645Z","caller":"traceutil/trace.go:171","msg":"trace[1245732636] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:359; }","duration":"114.264331ms","start":"2026-07-04T10:06:22.717366Z","end":"2026-07-04T10:06:22.83163Z","steps":["trace[1245732636] 'agreement among raft nodes before linearized reading' (duration: 114.145288ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:06:23.033528Z","caller":"traceutil/trace.go:171","msg":"trace[131056845] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"198.071567ms","start":"2026-07-04T10:06:22.835429Z","end":"2026-07-04T10:06:23.0335Z","steps":["trace[131056845] 'process raft request' (duration: 195.038305ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:06:23.03353Z","caller":"traceutil/trace.go:171","msg":"trace[236516327] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"197.922544ms","start":"2026-07-04T10:06:22.83558Z","end":"2026-07-04T10:06:23.033502Z","steps":["trace[236516327] 'process raft request' (duration: 197.78686ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:06:23.034011Z","caller":"traceutil/trace.go:171","msg":"trace[1250175658] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"198.102117ms","start":"2026-07-04T10:06:22.835887Z","end":"2026-07-04T10:06:23.033989Z","steps":["trace[1250175658] 'process raft request' (duration: 197.562894ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:06:23.034022Z","caller":"traceutil/trace.go:171","msg":"trace[351628757] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:374; }","duration":"197.462333ms","start":"2026-07-04T10:06:22.836516Z","end":"2026-07-04T10:06:23.033978Z","steps":["trace[351628757] 'read index received' (duration: 193.93039ms)","trace[351628757] 'applied index is now lower than readState.Index' (duration: 3.529323ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:06:23.035651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.165109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-04T10:06:23.035732Z","caller":"traceutil/trace.go:171","msg":"trace[1179556585] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:362; }","duration":"199.182373ms","start":"2026-07-04T10:06:22.836511Z","end":"2026-07-04T10:06:23.035693Z","steps":["trace[1179556585] 'agreement among raft nodes before linearized reading' (duration: 197.563005ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:06:31.996456Z","caller":"traceutil/trace.go:171","msg":"trace[1363288913] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"101.705537ms","start":"2026-07-04T10:06:31.894727Z","end":"2026-07-04T10:06:31.996433Z","steps":["trace[1363288913] 'process raft request' (duration: 101.583174ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:06:47.239236Z","caller":"traceutil/trace.go:171","msg":"trace[1031791655] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"144.126922ms","start":"2026-07-04T10:06:47.095071Z","end":"2026-07-04T10:06:47.239198Z","steps":["trace[1031791655] 'process raft request' (duration: 80.919117ms)","trace[1031791655] 'compare' (duration: 62.926338ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:06:47.239453Z","caller":"traceutil/trace.go:171","msg":"trace[905091492] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"141.345728ms","start":"2026-07-04T10:06:47.098092Z","end":"2026-07-04T10:06:47.239437Z","steps":["trace[905091492] 'process raft request' (duration: 141.03361ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:06:47.239806Z","caller":"traceutil/trace.go:171","msg":"trace[1392738941] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"138.142352ms","start":"2026-07-04T10:06:47.101634Z","end":"2026-07-04T10:06:47.239777Z","steps":["trace[1392738941] 'process raft request' (duration: 137.740683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:47.719045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.973895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-04T10:06:47.719518Z","caller":"traceutil/trace.go:171","msg":"trace[1128851320] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"471.417206ms","start":"2026-07-04T10:06:47.248071Z","end":"2026-07-04T10:06:47.719488Z","steps":["trace[1128851320] 'process raft request' (duration: 471.191471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:47.719621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:47.248051Z","time spent":"471.526929ms","remote":"127.0.0.1:34686","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-04T10:06:47.719569Z","caller":"traceutil/trace.go:171","msg":"trace[1721210388] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"476.549846ms","start":"2026-07-04T10:06:47.242998Z","end":"2026-07-04T10:06:47.719548Z","steps":["trace[1721210388] 'process raft request' (duration: 213.005057ms)","trace[1721210388] 'compare' (duration: 262.832772ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:06:47.719796Z","caller":"traceutil/trace.go:171","msg":"trace[1333986041] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"471.632291ms","start":"2026-07-04T10:06:47.248151Z","end":"2026-07-04T10:06:47.719783Z","steps":["trace[1333986041] 'process raft request' (duration: 471.16851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:47.719888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:47.248143Z","time spent":"471.681862ms","remote":"127.0.0.1:34686","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-04T10:06:47.720064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:47.242982Z","time spent":"476.752881ms","remote":"127.0.0.1:34686","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-04T10:06:47.721171Z","caller":"traceutil/trace.go:171","msg":"trace[1070364014] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"451.820679ms","start":"2026-07-04T10:06:47.269334Z","end":"2026-07-04T10:06:47.721155Z","steps":["trace[1070364014] 'process raft request' (duration: 450.019097ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:47.721254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:47.269318Z","time spent":"451.89982ms","remote":"127.0.0.1:34470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19834,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-04T10:06:47.721296Z","caller":"traceutil/trace.go:171","msg":"trace[1643954658] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"425.139347ms","start":"2026-07-04T10:06:47.296143Z","end":"2026-07-04T10:06:47.721283Z","steps":["trace[1643954658] 'process raft request' (duration: 424.39763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:47.721443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:47.296134Z","time spent":"425.236329ms","remote":"127.0.0.1:34470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-04T10:06:47.721499Z","caller":"traceutil/trace.go:171","msg":"trace[715062564] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:569; }","duration":"427.555293ms","start":"2026-07-04T10:06:47.29393Z","end":"2026-07-04T10:06:47.721486Z","steps":["trace[715062564] 'read index received' (duration: 162.155341ms)","trace[715062564] 'applied index is now lower than readState.Index' (duration: 265.397502ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:06:47.723159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.222931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:06:47.723292Z","caller":"traceutil/trace.go:171","msg":"trace[1155613709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"429.352815ms","start":"2026-07-04T10:06:47.293894Z","end":"2026-07-04T10:06:47.723247Z","steps":["trace[1155613709] 'agreement among raft nodes before linearized reading' (duration: 427.631525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:47.723325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:47.293882Z","time spent":"429.435996ms","remote":"127.0.0.1:34430","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-04T10:06:53.42841Z","caller":"traceutil/trace.go:171","msg":"trace[1000049327] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"130.952324ms","start":"2026-07-04T10:06:53.29741Z","end":"2026-07-04T10:06:53.428362Z","steps":["trace[1000049327] 'read index received' (duration: 124.078963ms)","trace[1000049327] 'applied index is now lower than readState.Index' (duration: 6.872211ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:06:53.428421Z","caller":"traceutil/trace.go:171","msg":"trace[1555398595] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"835.037658ms","start":"2026-07-04T10:06:52.593365Z","end":"2026-07-04T10:06:53.428403Z","steps":["trace[1555398595] 'process raft request' (duration: 831.993036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:53.428513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.098657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:06:53.428548Z","caller":"traceutil/trace.go:171","msg":"trace[252784405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"131.178699ms","start":"2026-07-04T10:06:53.297359Z","end":"2026-07-04T10:06:53.428538Z","steps":["trace[252784405] 'agreement among raft nodes before linearized reading' (duration: 131.112487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:53.428578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:52.593337Z","time spent":"835.152721ms","remote":"127.0.0.1:34516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-04T10:06:54.136672Z","caller":"traceutil/trace.go:171","msg":"trace[1333275633] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"384.258482ms","start":"2026-07-04T10:06:53.752389Z","end":"2026-07-04T10:06:54.136647Z","steps":["trace[1333275633] 'process raft request' (duration: 384.123329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:54.136846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:53.752374Z","time spent":"384.377575ms","remote":"127.0.0.1:34686","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-04T10:06:54.632627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.854056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-04T10:06:54.633046Z","caller":"traceutil/trace.go:171","msg":"trace[1803268055] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"876.799811ms","start":"2026-07-04T10:06:53.756206Z","end":"2026-07-04T10:06:54.633005Z","steps":["trace[1803268055] 'process raft request' (duration: 759.509855ms)","trace[1803268055] 'compare' (duration: 116.714002ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:06:54.633209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:53.75619Z","time spent":"876.948134ms","remote":"127.0.0.1:34494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":779,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-04T10:06:54.633288Z","caller":"traceutil/trace.go:171","msg":"trace[30235476] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"877.034366ms","start":"2026-07-04T10:06:53.756239Z","end":"2026-07-04T10:06:54.633273Z","steps":["trace[30235476] 'read index received' (duration: 380.339471ms)","trace[30235476] 'applied index is now lower than readState.Index' (duration: 496.694295ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:06:54.633419Z","caller":"traceutil/trace.go:171","msg":"trace[2082525854] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"740.758977ms","start":"2026-07-04T10:06:53.89265Z","end":"2026-07-04T10:06:54.633409Z","steps":["trace[2082525854] 'process raft request' (duration: 740.194794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:54.633489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:53.892623Z","time spent":"740.823478ms","remote":"127.0.0.1:34516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-04T10:06:54.633804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.457376ms","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-04T10:06:54.63389Z","caller":"traceutil/trace.go:171","msg":"trace[1293377990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"877.561398ms","start":"2026-07-04T10:06:53.756312Z","end":"2026-07-04T10:06:54.633873Z","steps":["trace[1293377990] 'agreement among raft nodes before linearized reading' (duration: 877.364543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:54.633899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.697861ms","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-04T10:06:54.63393Z","caller":"traceutil/trace.go:171","msg":"trace[1138591960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"504.774573ms","start":"2026-07-04T10:06:54.129149Z","end":"2026-07-04T10:06:54.633923Z","steps":["trace[1138591960] 'agreement among raft nodes before linearized reading' (duration: 504.668591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:54.633922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.668171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-04T10:06:54.633952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:54.129122Z","time spent":"504.825505ms","remote":"127.0.0.1:34686","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":"info","ts":"2026-07-04T10:06:54.63399Z","caller":"traceutil/trace.go:171","msg":"trace[2003875998] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"877.764933ms","start":"2026-07-04T10:06:53.756206Z","end":"2026-07-04T10:06:54.633971Z","steps":["trace[2003875998] 'agreement among raft nodes before linearized reading' (duration: 877.587029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:54.634034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:53.756195Z","time spent":"877.829145ms","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-04T10:06:54.633937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:53.756306Z","time spent":"877.618769ms","remote":"127.0.0.1:34686","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-04T10:06:55.800685Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415873856256657991,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-04T10:06:56.003279Z","caller":"traceutil/trace.go:171","msg":"trace[285353197] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"877.913589ms","start":"2026-07-04T10:06:55.125306Z","end":"2026-07-04T10:06:56.003219Z","steps":["trace[285353197] 'process raft request' (duration: 877.183452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:56.003469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:55.125277Z","time spent":"878.083473ms","remote":"127.0.0.1:34516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-04T10:06:57.298548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000551471s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-04T10:06:57.298657Z","caller":"traceutil/trace.go:171","msg":"trace[1219616801] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000700974s","start":"2026-07-04T10:06:55.29793Z","end":"2026-07-04T10:06:57.298631Z","steps":["trace[1219616801] 'agreement among raft nodes before linearized reading' (duration: 2.000572241s)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:57.298776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:55.297914Z","time spent":"2.000849328s","remote":"127.0.0.1:34440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-04T10:06:57.67436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.338565499s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-04T10:06:57.674476Z","caller":"traceutil/trace.go:171","msg":"trace[2098602669] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:622; }","duration":"2.3764927s","start":"2026-07-04T10:06:55.29796Z","end":"2026-07-04T10:06:57.674453Z","steps":["trace[2098602669] 'read index received' (duration: 704.34005ms)","trace[2098602669] 'applied index is now lower than readState.Index' (duration: 1.67215052s)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:06:57.674766Z","caller":"traceutil/trace.go:171","msg":"trace[250776262] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"2.470211916s","start":"2026-07-04T10:06:55.204536Z","end":"2026-07-04T10:06:57.674748Z","steps":["trace[250776262] 'process raft request' (duration: 1.131164437s)","trace[250776262] 'compare' (duration: 1.338328693s)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:06:57.674855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:55.204511Z","time spent":"2.470296718s","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-04T10:06:57.674165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.117946ms","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-04T10:06:57.674875Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.339002009s","expected-duration":"1s"} {"level":"info","ts":"2026-07-04T10:06:57.675002Z","caller":"traceutil/trace.go:171","msg":"trace[113040887] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:598; }","duration":"907.974496ms","start":"2026-07-04T10:06:56.767008Z","end":"2026-07-04T10:06:57.674983Z","steps":["trace[113040887] 'range keys from in-memory index tree' (duration: 907.095705ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:06:57.675434Z","caller":"traceutil/trace.go:171","msg":"trace[1100622268] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"1.608510266s","start":"2026-07-04T10:06:56.06689Z","end":"2026-07-04T10:06:57.6754Z","steps":["trace[1100622268] 'process raft request' (duration: 1.608360212s)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:57.67557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:56.066866Z","time spent":"1.608642809s","remote":"127.0.0.1:34658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-04T10:06:57.67622Z","caller":"traceutil/trace.go:171","msg":"trace[214052276] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"373.184435ms","start":"2026-07-04T10:06:57.303015Z","end":"2026-07-04T10:06:57.676199Z","steps":["trace[214052276] 'process raft request' (duration: 372.999841ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:57.676301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:57.302992Z","time spent":"373.273167ms","remote":"127.0.0.1:34494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-04T10:06:57.689456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04236536s","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-04T10:06:57.689541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043721791s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-04T10:06:57.689581Z","caller":"traceutil/trace.go:171","msg":"trace[99945953] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:601; }","duration":"1.043790743s","start":"2026-07-04T10:06:56.645778Z","end":"2026-07-04T10:06:57.689569Z","steps":["trace[99945953] 'agreement among raft nodes before linearized reading' (duration: 1.043707171s)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:06:57.689593Z","caller":"traceutil/trace.go:171","msg":"trace[855353780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"1.042489793s","start":"2026-07-04T10:06:56.647065Z","end":"2026-07-04T10:06:57.689555Z","steps":["trace[855353780] 'agreement among raft nodes before linearized reading' (duration: 1.042266298s)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:57.689669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.548589068s","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-04T10:06:57.689699Z","caller":"traceutil/trace.go:171","msg":"trace[1320259659] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:601; }","duration":"1.54866603s","start":"2026-07-04T10:06:56.141024Z","end":"2026-07-04T10:06:57.68969Z","steps":["trace[1320259659] 'agreement among raft nodes before linearized reading' (duration: 1.548613469s)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:57.689672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:56.647058Z","time spent":"1.042604325s","remote":"127.0.0.1:34686","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-04T10:06:57.689725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:56.140927Z","time spent":"1.548791013s","remote":"127.0.0.1:34686","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-04T10:06:57.689766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.400724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:06:57.689804Z","caller":"traceutil/trace.go:171","msg":"trace[1689934381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"383.459284ms","start":"2026-07-04T10:06:57.306332Z","end":"2026-07-04T10:06:57.689791Z","steps":["trace[1689934381] 'agreement among raft nodes before linearized reading' (duration: 383.404533ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:57.689831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:57.306316Z","time spent":"383.509266ms","remote":"127.0.0.1:34430","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-04T10:06:57.689445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.184983654s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kgr92\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-07-04T10:06:57.68946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042712458s","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-04T10:06:57.68996Z","caller":"traceutil/trace.go:171","msg":"trace[1056525181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:601; }","duration":"1.04324394s","start":"2026-07-04T10:06:56.646706Z","end":"2026-07-04T10:06:57.68995Z","steps":["trace[1056525181] 'agreement among raft nodes before linearized reading' (duration: 1.042646006s)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:57.689998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:56.646697Z","time spent":"1.043290892s","remote":"127.0.0.1:34686","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-04T10:06:57.68962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:56.64576Z","time spent":"1.043851504s","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-04T10:06:57.689935Z","caller":"traceutil/trace.go:171","msg":"trace[1968518585] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kgr92; range_end:; response_count:1; response_revision:601; }","duration":"2.185524315s","start":"2026-07-04T10:06:55.5044Z","end":"2026-07-04T10:06:57.689924Z","steps":["trace[1968518585] 'agreement among raft nodes before linearized reading' (duration: 2.184975113s)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:06:57.690163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:55.504379Z","time spent":"2.185728371s","remote":"127.0.0.1:34598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-kgr92\" "} {"level":"info","ts":"2026-07-04T10:07:00.035813Z","caller":"traceutil/trace.go:171","msg":"trace[626631101] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"337.208357ms","start":"2026-07-04T10:06:59.698585Z","end":"2026-07-04T10:07:00.035794Z","steps":["trace[626631101] 'read index received' (duration: 337.086784ms)","trace[626631101] 'applied index is now lower than readState.Index' (duration: 120.893µs)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:07:00.035887Z","caller":"traceutil/trace.go:171","msg":"trace[34508766] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"405.351606ms","start":"2026-07-04T10:06:59.63053Z","end":"2026-07-04T10:07:00.035881Z","steps":["trace[34508766] 'process raft request' (duration: 405.150021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:00.035954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:59.630508Z","time spent":"405.396487ms","remote":"127.0.0.1:34470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-04T10:07:00.036102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.500253ms","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-04T10:07:00.036186Z","caller":"traceutil/trace.go:171","msg":"trace[1803787932] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:637; }","duration":"337.619926ms","start":"2026-07-04T10:06:59.69854Z","end":"2026-07-04T10:07:00.03616Z","steps":["trace[1803787932] 'agreement among raft nodes before linearized reading' (duration: 337.37359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:00.036238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:59.698522Z","time spent":"337.703708ms","remote":"127.0.0.1:34686","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-04T10:07:00.118279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.758186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-04T10:07:00.118347Z","caller":"traceutil/trace.go:171","msg":"trace[108782210] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:639; }","duration":"417.890439ms","start":"2026-07-04T10:06:59.70044Z","end":"2026-07-04T10:07:00.118331Z","steps":["trace[108782210] 'agreement among raft nodes before linearized reading' (duration: 417.692124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:00.118384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:59.700427Z","time spent":"417.948181ms","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-04T10:07:00.118604Z","caller":"traceutil/trace.go:171","msg":"trace[369036323] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"288.473779ms","start":"2026-07-04T10:06:59.830104Z","end":"2026-07-04T10:07:00.118578Z","steps":["trace[369036323] 'process raft request' (duration: 287.883095ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:00.118686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.438635ms","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-04T10:07:00.118734Z","caller":"traceutil/trace.go:171","msg":"trace[1454096836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"416.522377ms","start":"2026-07-04T10:06:59.702197Z","end":"2026-07-04T10:07:00.11872Z","steps":["trace[1454096836] 'agreement among raft nodes before linearized reading' (duration: 416.363003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:00.118854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:59.70219Z","time spent":"416.6509ms","remote":"127.0.0.1:34686","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-04T10:07:00.119121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.292228ms","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-04T10:07:00.119171Z","caller":"traceutil/trace.go:171","msg":"trace[202776365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:639; }","duration":"418.37856ms","start":"2026-07-04T10:06:59.700781Z","end":"2026-07-04T10:07:00.119159Z","steps":["trace[202776365] 'agreement among raft nodes before linearized reading' (duration: 418.236567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:00.11921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:06:59.700771Z","time spent":"418.428742ms","remote":"127.0.0.1:34686","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-04T10:07:00.343579Z","caller":"traceutil/trace.go:171","msg":"trace[843208223] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"198.611173ms","start":"2026-07-04T10:07:00.144945Z","end":"2026-07-04T10:07:00.343556Z","steps":["trace[843208223] 'process raft request' (duration: 198.561882ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:07:00.343601Z","caller":"traceutil/trace.go:171","msg":"trace[2035701407] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"198.718396ms","start":"2026-07-04T10:07:00.144852Z","end":"2026-07-04T10:07:00.343571Z","steps":["trace[2035701407] 'process raft request' (duration: 198.520121ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:07:00.434123Z","caller":"traceutil/trace.go:171","msg":"trace[1235016615] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:670; }","duration":"137.936267ms","start":"2026-07-04T10:07:00.296165Z","end":"2026-07-04T10:07:00.434102Z","steps":["trace[1235016615] 'read index received' (duration: 47.291353ms)","trace[1235016615] 'applied index is now lower than readState.Index' (duration: 90.643844ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:07:00.434219Z","caller":"traceutil/trace.go:171","msg":"trace[794787216] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"204.763516ms","start":"2026-07-04T10:07:00.229417Z","end":"2026-07-04T10:07:00.43418Z","steps":["trace[794787216] 'process raft request' (duration: 204.555731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:00.434348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.160033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:07:00.43442Z","caller":"traceutil/trace.go:171","msg":"trace[1786436038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"138.265456ms","start":"2026-07-04T10:07:00.29614Z","end":"2026-07-04T10:07:00.434405Z","steps":["trace[1786436038] 'agreement among raft nodes before linearized reading' (duration: 138.04935ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:07:00.470287Z","caller":"traceutil/trace.go:171","msg":"trace[301589473] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"120.847599ms","start":"2026-07-04T10:07:00.34942Z","end":"2026-07-04T10:07:00.470267Z","steps":["trace[301589473] 'process raft request' (duration: 119.481447ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:07:00.470304Z","caller":"traceutil/trace.go:171","msg":"trace[1531635930] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"120.288725ms","start":"2026-07-04T10:07:00.349996Z","end":"2026-07-04T10:07:00.470285Z","steps":["trace[1531635930] 'process raft request' (duration: 120.214864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:03.310078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.735919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:07:03.310181Z","caller":"traceutil/trace.go:171","msg":"trace[1562698334] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:680; }","duration":"539.881412ms","start":"2026-07-04T10:07:02.770275Z","end":"2026-07-04T10:07:03.310157Z","steps":["trace[1562698334] 'range keys from in-memory index tree' (duration: 539.648636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:03.310251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:07:02.770253Z","time spent":"539.984024ms","remote":"127.0.0.1:34430","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-04T10:07:03.335788Z","caller":"traceutil/trace.go:171","msg":"trace[1536732228] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"481.760556ms","start":"2026-07-04T10:07:02.853997Z","end":"2026-07-04T10:07:03.335757Z","steps":["trace[1536732228] 'process raft request' (duration: 481.457849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:03.335949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:07:02.853967Z","time spent":"481.91311ms","remote":"127.0.0.1:34470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-04T10:07:03.335781Z","caller":"traceutil/trace.go:171","msg":"trace[2018756985] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:707; }","duration":"307.567364ms","start":"2026-07-04T10:07:03.028145Z","end":"2026-07-04T10:07:03.335712Z","steps":["trace[2018756985] 'read index received' (duration: 307.319048ms)","trace[2018756985] 'applied index is now lower than readState.Index' (duration: 247.076µs)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:07:03.336256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.085126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-07-04T10:07:03.336307Z","caller":"traceutil/trace.go:171","msg":"trace[1058329677] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:681; }","duration":"308.200459ms","start":"2026-07-04T10:07:03.028092Z","end":"2026-07-04T10:07:03.336293Z","steps":["trace[1058329677] 'agreement among raft nodes before linearized reading' (duration: 307.771829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:03.336442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:07:03.028038Z","time spent":"308.390833ms","remote":"127.0.0.1:34470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24267,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-07-04T10:07:03.509608Z","caller":"traceutil/trace.go:171","msg":"trace[560614438] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:708; }","duration":"173.501337ms","start":"2026-07-04T10:07:03.33606Z","end":"2026-07-04T10:07:03.509561Z","steps":["trace[560614438] 'read index received' (duration: 173.092608ms)","trace[560614438] 'applied index is now lower than readState.Index' (duration: 407.749µs)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:07:03.509707Z","caller":"traceutil/trace.go:171","msg":"trace[1135824098] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"254.686151ms","start":"2026-07-04T10:07:03.254987Z","end":"2026-07-04T10:07:03.509673Z","steps":["trace[1135824098] 'process raft request' (duration: 254.303272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:03.509872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.790974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-04T10:07:03.509926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.232333ms","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-04T10:07:03.509956Z","caller":"traceutil/trace.go:171","msg":"trace[538031594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:682; }","duration":"212.928837ms","start":"2026-07-04T10:07:03.297004Z","end":"2026-07-04T10:07:03.509933Z","steps":["trace[538031594] 'agreement among raft nodes before linearized reading' (duration: 212.750253ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:07:03.510021Z","caller":"traceutil/trace.go:171","msg":"trace[899201598] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:682; }","duration":"261.342836ms","start":"2026-07-04T10:07:03.248627Z","end":"2026-07-04T10:07:03.50997Z","steps":["trace[899201598] 'agreement among raft nodes before linearized reading' (duration: 261.116951ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:07:03.692963Z","caller":"traceutil/trace.go:171","msg":"trace[867487743] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"292.254027ms","start":"2026-07-04T10:07:03.400672Z","end":"2026-07-04T10:07:03.692926Z","steps":["trace[867487743] 'process raft request' (duration: 245.358733ms)","trace[867487743] 'compare' (duration: 46.70548ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:07:03.69302Z","caller":"traceutil/trace.go:171","msg":"trace[297267772] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:709; }","duration":"183.146363ms","start":"2026-07-04T10:07:03.509857Z","end":"2026-07-04T10:07:03.693003Z","steps":["trace[297267772] 'read index received' (duration: 136.116475ms)","trace[297267772] 'applied index is now lower than readState.Index' (duration: 47.029058ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:07:03.693357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.141606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-07-04T10:07:03.693404Z","caller":"traceutil/trace.go:171","msg":"trace[1000520364] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:683; }","duration":"265.226997ms","start":"2026-07-04T10:07:03.428161Z","end":"2026-07-04T10:07:03.693388Z","steps":["trace[1000520364] 'agreement among raft nodes before linearized reading' (duration: 264.93852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:03.6934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.728007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kgr92\" ","response":"range_response_count:1 size:18337"} {"level":"info","ts":"2026-07-04T10:07:03.693443Z","caller":"traceutil/trace.go:171","msg":"trace[1626579656] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kgr92; range_end:; response_count:1; response_revision:685; }","duration":"141.800868ms","start":"2026-07-04T10:07:03.551632Z","end":"2026-07-04T10:07:03.693433Z","steps":["trace[1626579656] 'agreement among raft nodes before linearized reading' (duration: 141.663445ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:07:03.693425Z","caller":"traceutil/trace.go:171","msg":"trace[511024589] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"141.190954ms","start":"2026-07-04T10:07:03.552196Z","end":"2026-07-04T10:07:03.693386Z","steps":["trace[511024589] 'process raft request' (duration: 141.055431ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:07:03.693357Z","caller":"traceutil/trace.go:171","msg":"trace[467119843] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"178.181936ms","start":"2026-07-04T10:07:03.515147Z","end":"2026-07-04T10:07:03.693329Z","steps":["trace[467119843] 'process raft request' (duration: 177.725426ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:07:07.540478Z","caller":"traceutil/trace.go:171","msg":"trace[234210099] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"116.244192ms","start":"2026-07-04T10:07:07.424219Z","end":"2026-07-04T10:07:07.540464Z","steps":["trace[234210099] 'process raft request' (duration: 115.987246ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:07:08.376922Z","caller":"traceutil/trace.go:171","msg":"trace[1264821710] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"164.145069ms","start":"2026-07-04T10:07:08.212745Z","end":"2026-07-04T10:07:08.37689Z","steps":["trace[1264821710] 'process raft request' (duration: 118.246818ms)","trace[1264821710] 'compare' (duration: 45.629825ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:07:08.377128Z","caller":"traceutil/trace.go:171","msg":"trace[1474755991] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"164.350274ms","start":"2026-07-04T10:07:08.212758Z","end":"2026-07-04T10:07:08.377109Z","steps":["trace[1474755991] 'process raft request' (duration: 163.986865ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:07:09.865072Z","caller":"traceutil/trace.go:171","msg":"trace[673003002] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"146.429756ms","start":"2026-07-04T10:07:09.718614Z","end":"2026-07-04T10:07:09.865044Z","steps":["trace[673003002] 'process raft request' (duration: 146.273482ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:07:10.750229Z","caller":"traceutil/trace.go:171","msg":"trace[751420566] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"143.050097ms","start":"2026-07-04T10:07:10.607153Z","end":"2026-07-04T10:07:10.750203Z","steps":["trace[751420566] 'process raft request' (duration: 142.899083ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:07:10.950323Z","caller":"traceutil/trace.go:171","msg":"trace[167109857] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"194.349323ms","start":"2026-07-04T10:07:10.755944Z","end":"2026-07-04T10:07:10.950293Z","steps":["trace[167109857] 'process raft request' (duration: 194.175499ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:07:11.147426Z","caller":"traceutil/trace.go:171","msg":"trace[2083246301] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"390.333215ms","start":"2026-07-04T10:07:10.757067Z","end":"2026-07-04T10:07:11.1474Z","steps":["trace[2083246301] 'process raft request' (duration: 390.134491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:11.147592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:07:10.757034Z","time spent":"390.476649ms","remote":"127.0.0.1:34586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":781,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-04T10:07:11.147577Z","caller":"traceutil/trace.go:171","msg":"trace[1231485575] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"382.008191ms","start":"2026-07-04T10:07:10.765543Z","end":"2026-07-04T10:07:11.147551Z","steps":["trace[1231485575] 'process raft request' (duration: 381.807946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:11.147857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:07:10.765521Z","time spent":"382.150135ms","remote":"127.0.0.1:34598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4857,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-04T10:07:11.256764Z","caller":"traceutil/trace.go:171","msg":"trace[84768695] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"102.01827ms","start":"2026-07-04T10:07:11.15472Z","end":"2026-07-04T10:07:11.256738Z","steps":["trace[84768695] 'process raft request' (duration: 98.824705ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:07:11.257016Z","caller":"traceutil/trace.go:171","msg":"trace[869890571] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"102.117402ms","start":"2026-07-04T10:07:11.154882Z","end":"2026-07-04T10:07:11.257Z","steps":["trace[869890571] 'process raft request' (duration: 101.794084ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:07:12.22763Z","caller":"traceutil/trace.go:171","msg":"trace[1395994394] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"275.507556ms","start":"2026-07-04T10:07:11.952098Z","end":"2026-07-04T10:07:12.227606Z","steps":["trace[1395994394] 'process raft request' (duration: 275.132518ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:07:14.39041Z","caller":"traceutil/trace.go:171","msg":"trace[1269471827] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"122.324743ms","start":"2026-07-04T10:07:14.268051Z","end":"2026-07-04T10:07:14.390376Z","steps":["trace[1269471827] 'process raft request' (duration: 122.17799ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:07:14.565462Z","caller":"traceutil/trace.go:171","msg":"trace[701229249] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"113.696262ms","start":"2026-07-04T10:07:14.45174Z","end":"2026-07-04T10:07:14.565436Z","steps":["trace[701229249] 'process raft request' (duration: 113.554539ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:17.799308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.779488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:07:17.799393Z","caller":"traceutil/trace.go:171","msg":"trace[1253966166] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:775; }","duration":"667.92512ms","start":"2026-07-04T10:07:17.131451Z","end":"2026-07-04T10:07:17.799376Z","steps":["trace[1253966166] 'count revisions from in-memory index tree' (duration: 667.703256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:17.799438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:07:17.131432Z","time spent":"667.993833ms","remote":"127.0.0.1:34506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-07-04T10:07:17.799691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.312824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:07:17.799914Z","caller":"traceutil/trace.go:171","msg":"trace[836115522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"501.543039ms","start":"2026-07-04T10:07:17.298302Z","end":"2026-07-04T10:07:17.799845Z","steps":["trace[836115522] 'range keys from in-memory index tree' (duration: 501.091219ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:17.80005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:07:17.29828Z","time spent":"501.749865ms","remote":"127.0.0.1:34430","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-04T10:07:18.137812Z","caller":"traceutil/trace.go:171","msg":"trace[771204049] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"505.332688ms","start":"2026-07-04T10:07:17.632444Z","end":"2026-07-04T10:07:18.137777Z","steps":["trace[771204049] 'process raft request' (duration: 505.052972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:18.138074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:07:17.632408Z","time spent":"505.531613ms","remote":"127.0.0.1:34658","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-04T10:07:18.280314Z","caller":"traceutil/trace.go:171","msg":"trace[1619005311] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:806; }","duration":"479.199679ms","start":"2026-07-04T10:07:17.801077Z","end":"2026-07-04T10:07:18.280276Z","steps":["trace[1619005311] 'read index received' (duration: 336.426208ms)","trace[1619005311] 'applied index is now lower than readState.Index' (duration: 142.771661ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:07:18.280695Z","caller":"traceutil/trace.go:171","msg":"trace[307476568] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"116.477177ms","start":"2026-07-04T10:07:18.164202Z","end":"2026-07-04T10:07:18.28068Z","steps":["trace[307476568] 'process raft request' (duration: 115.958005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:18.280678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.564417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:07:18.280899Z","caller":"traceutil/trace.go:171","msg":"trace[2123320031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:776; }","duration":"479.830854ms","start":"2026-07-04T10:07:17.801035Z","end":"2026-07-04T10:07:18.280866Z","steps":["trace[2123320031] 'agreement among raft nodes before linearized reading' (duration: 479.362033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:18.281089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:07:17.801017Z","time spent":"480.054358ms","remote":"127.0.0.1:34440","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-04T10:07:18.281401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.116835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:07:18.281481Z","caller":"traceutil/trace.go:171","msg":"trace[442491081] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:777; }","duration":"208.256268ms","start":"2026-07-04T10:07:18.073205Z","end":"2026-07-04T10:07:18.281461Z","steps":["trace[442491081] 'agreement among raft nodes before linearized reading' (duration: 207.421069ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:07:32.252019Z","caller":"traceutil/trace.go:171","msg":"trace[1974327984] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"398.157668ms","start":"2026-07-04T10:07:31.853832Z","end":"2026-07-04T10:07:32.251989Z","steps":["trace[1974327984] 'process raft request' (duration: 397.878271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:32.252171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:07:31.85381Z","time spent":"398.28778ms","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-04T10:07:32.252192Z","caller":"traceutil/trace.go:171","msg":"trace[1649501287] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"192.845798ms","start":"2026-07-04T10:07:32.059036Z","end":"2026-07-04T10:07:32.251882Z","steps":["trace[1649501287] 'read index received' (duration: 192.48019ms)","trace[1649501287] 'applied index is now lower than readState.Index' (duration: 364.368µs)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:07:32.252377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.320629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-h485l\" ","response":"range_response_count:1 size:5677"} {"level":"info","ts":"2026-07-04T10:07:32.252469Z","caller":"traceutil/trace.go:171","msg":"trace[1261723629] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-h485l; range_end:; response_count:1; response_revision:906; }","duration":"193.476144ms","start":"2026-07-04T10:07:32.058972Z","end":"2026-07-04T10:07:32.252448Z","steps":["trace[1261723629] 'agreement among raft nodes before linearized reading' (duration: 193.226578ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:07:32.421736Z","caller":"traceutil/trace.go:171","msg":"trace[471530480] linearizableReadLoop","detail":"{readStateIndex:943; appliedIndex:942; }","duration":"124.858722ms","start":"2026-07-04T10:07:32.296856Z","end":"2026-07-04T10:07:32.421714Z","steps":["trace[471530480] 'read index received' (duration: 124.654867ms)","trace[471530480] 'applied index is now lower than readState.Index' (duration: 202.835µs)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:07:32.421825Z","caller":"traceutil/trace.go:171","msg":"trace[382555981] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"158.242151ms","start":"2026-07-04T10:07:32.263551Z","end":"2026-07-04T10:07:32.421793Z","steps":["trace[382555981] 'process raft request' (duration: 157.991085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:32.421901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.040507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:07:32.42194Z","caller":"traceutil/trace.go:171","msg":"trace[2085270123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:908; }","duration":"125.161189ms","start":"2026-07-04T10:07:32.296771Z","end":"2026-07-04T10:07:32.421932Z","steps":["trace[2085270123] 'agreement among raft nodes before linearized reading' (duration: 125.058077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:32.422016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.225885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-04T10:07:32.42208Z","caller":"traceutil/trace.go:171","msg":"trace[2099438457] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:908; }","duration":"123.377078ms","start":"2026-07-04T10:07:32.298687Z","end":"2026-07-04T10:07:32.422064Z","steps":["trace[2099438457] 'agreement among raft nodes before linearized reading' (duration: 123.202134ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:07:52.735491Z","caller":"traceutil/trace.go:171","msg":"trace[820130856] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"105.485865ms","start":"2026-07-04T10:07:52.629988Z","end":"2026-07-04T10:07:52.735474Z","steps":["trace[820130856] 'read index received' (duration: 105.28626ms)","trace[820130856] 'applied index is now lower than readState.Index' (duration: 198.875µs)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:07:52.735553Z","caller":"traceutil/trace.go:171","msg":"trace[1207819830] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"167.346976ms","start":"2026-07-04T10:07:52.56818Z","end":"2026-07-04T10:07:52.735527Z","steps":["trace[1207819830] 'process raft request' (duration: 167.174872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:52.73567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.670469ms","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-04T10:07:52.735699Z","caller":"traceutil/trace.go:171","msg":"trace[535339325] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:988; }","duration":"105.739281ms","start":"2026-07-04T10:07:52.62995Z","end":"2026-07-04T10:07:52.73569Z","steps":["trace[535339325] 'agreement among raft nodes before linearized reading' (duration: 105.608848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:53.294431Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415873856256658800,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-04T10:07:53.834975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.098468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-04T10:07:53.83513Z","caller":"traceutil/trace.go:171","msg":"trace[855506523] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1028; }","duration":"1.041290401s","start":"2026-07-04T10:07:52.793827Z","end":"2026-07-04T10:07:53.835118Z","steps":["trace[855506523] 'read index received' (duration: 536.659311ms)","trace[855506523] 'applied index is now lower than readState.Index' (duration: 504.63049ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:07:53.835174Z","caller":"traceutil/trace.go:171","msg":"trace[85297738] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"1.096073066s","start":"2026-07-04T10:07:52.73907Z","end":"2026-07-04T10:07:53.835143Z","steps":["trace[85297738] 'process raft request' (duration: 591.407736ms)","trace[85297738] 'compare' (duration: 503.999365ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:07:53.835284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.939651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-04T10:07:53.835303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041482035s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-04T10:07:53.835324Z","caller":"traceutil/trace.go:171","msg":"trace[1478513948] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:991; }","duration":"1.041503915s","start":"2026-07-04T10:07:52.793813Z","end":"2026-07-04T10:07:53.835317Z","steps":["trace[1478513948] 'agreement among raft nodes before linearized reading' (duration: 1.041369042s)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:07:53.835325Z","caller":"traceutil/trace.go:171","msg":"trace[2004613404] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:991; }","duration":"538.001702ms","start":"2026-07-04T10:07:53.29731Z","end":"2026-07-04T10:07:53.835312Z","steps":["trace[2004613404] 'agreement among raft nodes before linearized reading' (duration: 537.946951ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:07:53.835296Z","caller":"traceutil/trace.go:171","msg":"trace[590575641] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"1.024984528s","start":"2026-07-04T10:07:52.810279Z","end":"2026-07-04T10:07:53.835263Z","steps":["trace[590575641] 'process raft request' (duration: 1.024815094s)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:53.83533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:07:52.739053Z","time spent":"1.096185138s","remote":"127.0.0.1:34686","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-04T10:07:53.835347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:07:52.793758Z","time spent":"1.041584638s","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-04T10:07:53.835347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:07:53.297294Z","time spent":"538.047894ms","remote":"127.0.0.1:34430","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-04T10:07:53.835429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020465962s","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-04T10:07:53.835447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:07:52.810257Z","time spent":"1.025107271s","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-04T10:07:53.835477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020436051s","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-04T10:07:53.835497Z","caller":"traceutil/trace.go:171","msg":"trace[969919215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:991; }","duration":"1.020485872s","start":"2026-07-04T10:07:52.815005Z","end":"2026-07-04T10:07:53.835491Z","steps":["trace[969919215] 'agreement among raft nodes before linearized reading' (duration: 1.020430601s)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:53.835515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:07:52.814992Z","time spent":"1.020518223s","remote":"127.0.0.1:34686","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-04T10:07:53.835504Z","caller":"traceutil/trace.go:171","msg":"trace[679840904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:991; }","duration":"1.020588855s","start":"2026-07-04T10:07:52.814893Z","end":"2026-07-04T10:07:53.835482Z","steps":["trace[679840904] 'agreement among raft nodes before linearized reading' (duration: 1.02036748s)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:53.835558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:07:52.814868Z","time spent":"1.020671127s","remote":"127.0.0.1:34686","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-04T10:07:53.836494Z","caller":"traceutil/trace.go:171","msg":"trace[1815399640] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"1.032206157s","start":"2026-07-04T10:07:52.804267Z","end":"2026-07-04T10:07:53.836473Z","steps":["trace[1815399640] 'process raft request' (duration: 1.030788974s)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:53.836641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:07:52.804244Z","time spent":"1.032322731s","remote":"127.0.0.1:34686","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-04T10:07:54.984721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.943683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-04T10:07:54.984794Z","caller":"traceutil/trace.go:171","msg":"trace[1859136794] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"1.146254173s","start":"2026-07-04T10:07:53.838527Z","end":"2026-07-04T10:07:54.984781Z","steps":["trace[1859136794] 'process raft request' (duration: 599.193948ms)","trace[1859136794] 'compare' (duration: 546.863491ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:07:54.984832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:07:53.838516Z","time spent":"1.146299125s","remote":"127.0.0.1:34686","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-04T10:07:54.986234Z","caller":"traceutil/trace.go:171","msg":"trace[1090534813] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1031; }","duration":"988.997214ms","start":"2026-07-04T10:07:53.99722Z","end":"2026-07-04T10:07:54.986218Z","steps":["trace[1090534813] 'read index received' (duration: 440.534856ms)","trace[1090534813] 'applied index is now lower than readState.Index' (duration: 548.461648ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:07:54.986409Z","caller":"traceutil/trace.go:171","msg":"trace[1777850492] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"1.147560834s","start":"2026-07-04T10:07:53.83884Z","end":"2026-07-04T10:07:54.986401Z","steps":["trace[1777850492] 'process raft request' (duration: 1.147269288s)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:54.986485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:07:53.838834Z","time spent":"1.147600895s","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-04T10:07:54.986584Z","caller":"traceutil/trace.go:171","msg":"trace[243586112] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"1.147183256s","start":"2026-07-04T10:07:53.839369Z","end":"2026-07-04T10:07:54.986552Z","steps":["trace[243586112] 'process raft request' (duration: 1.146821357s)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:54.986604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.396124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-04T10:07:54.986637Z","caller":"traceutil/trace.go:171","msg":"trace[884523684] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:994; }","duration":"989.469186ms","start":"2026-07-04T10:07:53.997156Z","end":"2026-07-04T10:07:54.986625Z","steps":["trace[884523684] 'agreement among raft nodes before linearized reading' (duration: 989.415044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:07:54.986657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:07:53.997136Z","time spent":"989.515606ms","remote":"127.0.0.1:34856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-07-04T10:07:54.986669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:07:53.839355Z","time spent":"1.147271208s","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-04T10:07:54.986709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.684448ms","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-04T10:07:54.986791Z","caller":"traceutil/trace.go:171","msg":"trace[1505389101] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:994; }","duration":"246.808891ms","start":"2026-07-04T10:07:54.739958Z","end":"2026-07-04T10:07:54.986767Z","steps":["trace[1505389101] 'agreement among raft nodes before linearized reading' (duration: 246.568725ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:07:59.244131Z","caller":"traceutil/trace.go:171","msg":"trace[1366048805] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"166.868545ms","start":"2026-07-04T10:07:59.077236Z","end":"2026-07-04T10:07:59.244104Z","steps":["trace[1366048805] 'process raft request' (duration: 166.822004ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:07:59.244182Z","caller":"traceutil/trace.go:171","msg":"trace[1937837514] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"167.358767ms","start":"2026-07-04T10:07:59.076782Z","end":"2026-07-04T10:07:59.244141Z","steps":["trace[1937837514] 'process raft request' (duration: 82.204329ms)","trace[1937837514] 'compare' (duration: 84.944033ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:08:03.361623Z","caller":"traceutil/trace.go:171","msg":"trace[1172857943] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"100.098299ms","start":"2026-07-04T10:08:03.261491Z","end":"2026-07-04T10:08:03.361589Z","steps":["trace[1172857943] 'process raft request' (duration: 66.095481ms)","trace[1172857943] 'compare' (duration: 33.879795ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:08:17.625257Z","caller":"traceutil/trace.go:171","msg":"trace[1427887865] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1107; }","duration":"430.016699ms","start":"2026-07-04T10:08:17.195217Z","end":"2026-07-04T10:08:17.625234Z","steps":["trace[1427887865] 'read index received' (duration: 429.933427ms)","trace[1427887865] 'applied index is now lower than readState.Index' (duration: 82.302µs)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:08:17.625316Z","caller":"traceutil/trace.go:171","msg":"trace[1661581071] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"464.546999ms","start":"2026-07-04T10:08:17.160735Z","end":"2026-07-04T10:08:17.625282Z","steps":["trace[1661581071] 'process raft request' (duration: 464.366145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:17.625486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.250334ms","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-04T10:08:17.625528Z","caller":"traceutil/trace.go:171","msg":"trace[709201400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1064; }","duration":"430.343076ms","start":"2026-07-04T10:08:17.195176Z","end":"2026-07-04T10:08:17.625519Z","steps":["trace[709201400] 'agreement among raft nodes before linearized reading' (duration: 430.187203ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:17.625559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:08:17.195159Z","time spent":"430.391848ms","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-04T10:08:17.625622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:08:17.160719Z","time spent":"464.738333ms","remote":"127.0.0.1:34686","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-04T10:08:17.954849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.23062ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-04T10:08:17.955205Z","caller":"traceutil/trace.go:171","msg":"trace[1631631904] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"325.457066ms","start":"2026-07-04T10:08:17.629724Z","end":"2026-07-04T10:08:17.955181Z","steps":["trace[1631631904] 'process raft request' (duration: 325.288792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:17.955392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:08:17.629707Z","time spent":"325.564368ms","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-04T10:08:17.955754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.293565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-04T10:08:17.955818Z","caller":"traceutil/trace.go:171","msg":"trace[690694786] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1065; }","duration":"635.431969ms","start":"2026-07-04T10:08:17.320374Z","end":"2026-07-04T10:08:17.955806Z","steps":["trace[690694786] 'agreement among raft nodes before linearized reading' (duration: 635.06723ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:08:17.955362Z","caller":"traceutil/trace.go:171","msg":"trace[316675760] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1108; }","duration":"329.91021ms","start":"2026-07-04T10:08:17.6254Z","end":"2026-07-04T10:08:17.95531Z","steps":["trace[316675760] 'read index received' (duration: 108.222699ms)","trace[316675760] 'applied index is now lower than readState.Index' (duration: 221.682331ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:08:17.955858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:08:17.320356Z","time spent":"635.49201ms","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-04T10:08:17.955928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.027951ms","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-04T10:08:17.956209Z","caller":"traceutil/trace.go:171","msg":"trace[310484550] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1065; }","duration":"468.365019ms","start":"2026-07-04T10:08:17.487822Z","end":"2026-07-04T10:08:17.956187Z","steps":["trace[310484550] 'agreement among raft nodes before linearized reading' (duration: 467.853697ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:17.956292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:08:17.4878Z","time spent":"468.477702ms","remote":"127.0.0.1:34686","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-04T10:08:19.917883Z","caller":"traceutil/trace.go:171","msg":"trace[1582383934] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1114; }","duration":"288.017688ms","start":"2026-07-04T10:08:19.629847Z","end":"2026-07-04T10:08:19.917865Z","steps":["trace[1582383934] 'read index received' (duration: 287.855534ms)","trace[1582383934] 'applied index is now lower than readState.Index' (duration: 161.514µs)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:08:19.917944Z","caller":"traceutil/trace.go:171","msg":"trace[1402987552] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"392.10561ms","start":"2026-07-04T10:08:19.525808Z","end":"2026-07-04T10:08:19.917914Z","steps":["trace[1402987552] 'process raft request' (duration: 391.934276ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:19.918104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:08:19.525778Z","time spent":"392.242062ms","remote":"127.0.0.1:34686","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-04T10:08:19.919021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.163845ms","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-04T10:08:19.919073Z","caller":"traceutil/trace.go:171","msg":"trace[1085781530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1070; }","duration":"289.264277ms","start":"2026-07-04T10:08:19.6298Z","end":"2026-07-04T10:08:19.919064Z","steps":["trace[1085781530] 'agreement among raft nodes before linearized reading' (duration: 288.164261ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:20.16873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.299716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-04T10:08:20.168898Z","caller":"traceutil/trace.go:171","msg":"trace[11811446] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"245.549831ms","start":"2026-07-04T10:08:19.923312Z","end":"2026-07-04T10:08:20.168862Z","steps":["trace[11811446] 'process raft request' (duration: 124.020729ms)","trace[11811446] 'compare' (duration: 121.03556ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:08:20.168932Z","caller":"traceutil/trace.go:171","msg":"trace[1590032977] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1115; }","duration":"207.402726ms","start":"2026-07-04T10:08:19.96151Z","end":"2026-07-04T10:08:20.168912Z","steps":["trace[1590032977] 'read index received' (duration: 85.834553ms)","trace[1590032977] 'applied index is now lower than readState.Index' (duration: 121.566623ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:08:20.168948Z","caller":"traceutil/trace.go:171","msg":"trace[560457337] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"167.181143ms","start":"2026-07-04T10:08:20.001745Z","end":"2026-07-04T10:08:20.168926Z","steps":["trace[560457337] 'process raft request' (duration: 167.103441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:20.169101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.579981ms","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-04T10:08:20.169325Z","caller":"traceutil/trace.go:171","msg":"trace[700150883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1072; }","duration":"207.829786ms","start":"2026-07-04T10:08:19.961476Z","end":"2026-07-04T10:08:20.169306Z","steps":["trace[700150883] 'agreement among raft nodes before linearized reading' (duration: 207.526769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:20.169399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.490308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-04T10:08:20.169444Z","caller":"traceutil/trace.go:171","msg":"trace[343605949] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1072; }","duration":"123.545079ms","start":"2026-07-04T10:08:20.04587Z","end":"2026-07-04T10:08:20.169415Z","steps":["trace[343605949] 'agreement among raft nodes before linearized reading' (duration: 123.396985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:20.169475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.161359ms","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-04T10:08:20.169522Z","caller":"traceutil/trace.go:171","msg":"trace[720204584] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1072; }","duration":"123.239482ms","start":"2026-07-04T10:08:20.046271Z","end":"2026-07-04T10:08:20.16951Z","steps":["trace[720204584] 'agreement among raft nodes before linearized reading' (duration: 123.063678ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:08:20.305684Z","caller":"traceutil/trace.go:171","msg":"trace[31318526] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"130.715437ms","start":"2026-07-04T10:08:20.174951Z","end":"2026-07-04T10:08:20.305666Z","steps":["trace[31318526] 'process raft request' (duration: 130.626885ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:08:20.305678Z","caller":"traceutil/trace.go:171","msg":"trace[673088851] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"130.84209ms","start":"2026-07-04T10:08:20.174806Z","end":"2026-07-04T10:08:20.305649Z","steps":["trace[673088851] 'process raft request' (duration: 115.575821ms)","trace[673088851] 'compare' (duration: 15.115085ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:08:20.305984Z","caller":"traceutil/trace.go:171","msg":"trace[1767939200] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"130.782768ms","start":"2026-07-04T10:08:20.175181Z","end":"2026-07-04T10:08:20.305964Z","steps":["trace[1767939200] 'process raft request' (duration: 130.423439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:22.69403Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415873856256659033,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-04T10:08:23.112255Z","caller":"traceutil/trace.go:171","msg":"trace[1390087027] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"937.326661ms","start":"2026-07-04T10:08:22.174903Z","end":"2026-07-04T10:08:23.112229Z","steps":["trace[1390087027] 'process raft request' (duration: 937.104946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:23.112418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:08:22.17488Z","time spent":"937.458935ms","remote":"127.0.0.1:34686","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-04T10:08:23.394466Z","caller":"traceutil/trace.go:171","msg":"trace[1277205836] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1121; }","duration":"1.200589429s","start":"2026-07-04T10:08:22.193853Z","end":"2026-07-04T10:08:23.394442Z","steps":["trace[1277205836] 'read index received' (duration: 918.234354ms)","trace[1277205836] 'applied index is now lower than readState.Index' (duration: 282.353995ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:08:23.394615Z","caller":"traceutil/trace.go:171","msg":"trace[1582140156] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"1.217159087s","start":"2026-07-04T10:08:22.177428Z","end":"2026-07-04T10:08:23.394587Z","steps":["trace[1582140156] 'process raft request' (duration: 1.183287782s)","trace[1582140156] 'compare' (duration: 33.63183ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:08:23.395161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:08:22.177399Z","time spent":"1.21768094s","remote":"127.0.0.1:34686","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-04T10:08:23.395235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.688223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:08:23.395291Z","caller":"traceutil/trace.go:171","msg":"trace[29014586] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1078; }","duration":"753.763275ms","start":"2026-07-04T10:08:22.641505Z","end":"2026-07-04T10:08:23.395269Z","steps":["trace[29014586] 'agreement among raft nodes before linearized reading' (duration: 753.703384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:23.395362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:08:22.641484Z","time spent":"753.860817ms","remote":"127.0.0.1:48138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-04T10:08:23.394813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.200950217s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-04T10:08:23.395499Z","caller":"traceutil/trace.go:171","msg":"trace[1942637577] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:1078; }","duration":"1.201645073s","start":"2026-07-04T10:08:22.193798Z","end":"2026-07-04T10:08:23.395443Z","steps":["trace[1942637577] 'agreement among raft nodes before linearized reading' (duration: 1.200873295s)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:23.395555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:08:22.19378Z","time spent":"1.201759065s","remote":"127.0.0.1:34452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"warn","ts":"2026-07-04T10:08:23.395579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098233297s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:08:23.395668Z","caller":"traceutil/trace.go:171","msg":"trace[659760661] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1078; }","duration":"1.09837267s","start":"2026-07-04T10:08:22.297277Z","end":"2026-07-04T10:08:23.39565Z","steps":["trace[659760661] 'agreement among raft nodes before linearized reading' (duration: 1.098153885s)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:23.395718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:08:22.29725Z","time spent":"1.098459652s","remote":"127.0.0.1:34430","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-04T10:08:23.395171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084674558s","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-04T10:08:23.395771Z","caller":"traceutil/trace.go:171","msg":"trace[1160839275] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1078; }","duration":"1.085294553s","start":"2026-07-04T10:08:22.310463Z","end":"2026-07-04T10:08:23.395758Z","steps":["trace[1160839275] 'agreement among raft nodes before linearized reading' (duration: 1.084603587s)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:23.395751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085556469s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-04T10:08:23.39581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:08:22.310448Z","time spent":"1.085350674s","remote":"127.0.0.1:34686","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-04T10:08:23.395866Z","caller":"traceutil/trace.go:171","msg":"trace[1447160026] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1078; }","duration":"1.085734673s","start":"2026-07-04T10:08:22.310105Z","end":"2026-07-04T10:08:23.39584Z","steps":["trace[1447160026] 'agreement among raft nodes before linearized reading' (duration: 1.085330954s)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:23.395904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085701253s","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-04T10:08:23.395993Z","caller":"traceutil/trace.go:171","msg":"trace[313258221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1078; }","duration":"1.085814566s","start":"2026-07-04T10:08:22.310159Z","end":"2026-07-04T10:08:23.395973Z","steps":["trace[313258221] 'agreement among raft nodes before linearized reading' (duration: 1.085548389s)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:23.395929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:08:22.31008Z","time spent":"1.085839666s","remote":"127.0.0.1:34686","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-04T10:08:23.396048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:08:22.310116Z","time spent":"1.085923498s","remote":"127.0.0.1:34686","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-04T10:08:23.396938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183898587s","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-04T10:08:23.397041Z","caller":"traceutil/trace.go:171","msg":"trace[1804279133] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1078; }","duration":"1.18403953s","start":"2026-07-04T10:08:22.21299Z","end":"2026-07-04T10:08:23.39703Z","steps":["trace[1804279133] 'agreement among raft nodes before linearized reading' (duration: 1.182149696s)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:23.397121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:08:22.212941Z","time spent":"1.184168663s","remote":"127.0.0.1:34686","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-04T10:08:27.60245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.453423ms","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-04T10:08:27.602507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.095442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-04T10:08:27.602542Z","caller":"traceutil/trace.go:171","msg":"trace[1140761436] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1092; }","duration":"108.595207ms","start":"2026-07-04T10:08:27.493925Z","end":"2026-07-04T10:08:27.60252Z","steps":["trace[1140761436] 'range keys from in-memory index tree' (duration: 108.284789ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:08:27.602579Z","caller":"traceutil/trace.go:171","msg":"trace[1803322805] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1092; }","duration":"107.196874ms","start":"2026-07-04T10:08:27.495365Z","end":"2026-07-04T10:08:27.602562Z","steps":["trace[1803322805] 'range keys from in-memory index tree' (duration: 106.978249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:27.602451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.359881ms","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-04T10:08:27.60264Z","caller":"traceutil/trace.go:171","msg":"trace[1559332041] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1092; }","duration":"105.597416ms","start":"2026-07-04T10:08:27.497026Z","end":"2026-07-04T10:08:27.602623Z","steps":["trace[1559332041] 'range keys from in-memory index tree' (duration: 105.224457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:27.602811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.05438ms","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-04T10:08:27.60314Z","caller":"traceutil/trace.go:171","msg":"trace[1380042657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1092; }","duration":"107.405399ms","start":"2026-07-04T10:08:27.495723Z","end":"2026-07-04T10:08:27.603128Z","steps":["trace[1380042657] 'range keys from in-memory index tree' (duration: 106.447746ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:08:27.795564Z","caller":"traceutil/trace.go:171","msg":"trace[1918690637] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"188.496652ms","start":"2026-07-04T10:08:27.607048Z","end":"2026-07-04T10:08:27.795544Z","steps":["trace[1918690637] 'process raft request' (duration: 188.41571ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:08:27.795724Z","caller":"traceutil/trace.go:171","msg":"trace[1661171043] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"187.157531ms","start":"2026-07-04T10:08:27.608543Z","end":"2026-07-04T10:08:27.7957Z","steps":["trace[1661171043] 'process raft request' (duration: 186.963006ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:08:27.795568Z","caller":"traceutil/trace.go:171","msg":"trace[1784191464] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"188.503582ms","start":"2026-07-04T10:08:27.607029Z","end":"2026-07-04T10:08:27.795532Z","steps":["trace[1784191464] 'process raft request' (duration: 125.276899ms)","trace[1784191464] 'compare' (duration: 63.047109ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:08:42.797866Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415873856256659294,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-04T10:08:43.298862Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415873856256659294,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-04T10:08:43.800067Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415873856256659294,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-04T10:08:44.29728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00023681s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-04T10:08:44.297346Z","caller":"traceutil/trace.go:171","msg":"trace[89162251] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000346862s","start":"2026-07-04T10:08:42.29698Z","end":"2026-07-04T10:08:44.297327Z","steps":["trace[89162251] 'agreement among raft nodes before linearized reading' (duration: 2.00025325s)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:44.297381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:08:42.296949Z","time spent":"2.000422084s","remote":"127.0.0.1:34430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-04T10:08:44.301059Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415873856256659294,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-04T10:08:44.468459Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.272846055s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-04T10:08:44.468702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:08:42.195473Z","time spent":"2.273224905s","remote":"127.0.0.1:34452","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-04T10:08:44.637745Z","caller":"traceutil/trace.go:171","msg":"trace[1771105552] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"1.449793666s","start":"2026-07-04T10:08:43.187931Z","end":"2026-07-04T10:08:44.637724Z","steps":["trace[1771105552] 'process raft request' (duration: 1.431945597s)","trace[1771105552] 'compare' (duration: 17.696415ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:08:44.637848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:08:43.187911Z","time spent":"1.449884387s","remote":"127.0.0.1:34686","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-04T10:08:44.637909Z","caller":"traceutil/trace.go:171","msg":"trace[996662859] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"167.839557ms","start":"2026-07-04T10:08:44.470053Z","end":"2026-07-04T10:08:44.637892Z","steps":["trace[996662859] 'process raft request' (duration: 167.655873ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:08:44.637908Z","caller":"traceutil/trace.go:171","msg":"trace[1106484541] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1245; }","duration":"2.340869842s","start":"2026-07-04T10:08:42.297005Z","end":"2026-07-04T10:08:44.637875Z","steps":["trace[1106484541] 'read index received' (duration: 2.171588161s)","trace[1106484541] 'applied index is now lower than readState.Index' (duration: 169.274951ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:08:44.638134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403824656s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:08:44.638179Z","caller":"traceutil/trace.go:171","msg":"trace[1287247256] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1197; }","duration":"1.403924559s","start":"2026-07-04T10:08:43.234242Z","end":"2026-07-04T10:08:44.638167Z","steps":["trace[1287247256] 'agreement among raft nodes before linearized reading' (duration: 1.403813266s)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:44.638226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:08:43.23422Z","time spent":"1.403995641s","remote":"127.0.0.1:39028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-04T10:08:44.638273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.620298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:489"} {"level":"info","ts":"2026-07-04T10:08:44.638322Z","caller":"traceutil/trace.go:171","msg":"trace[1475134499] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1197; }","duration":"622.690809ms","start":"2026-07-04T10:08:44.015586Z","end":"2026-07-04T10:08:44.638309Z","steps":["trace[1475134499] 'agreement among raft nodes before linearized reading' (duration: 622.565706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:44.638358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:08:44.015575Z","time spent":"622.775742ms","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-04T10:08:44.638454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.775648ms","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-04T10:08:44.638504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.461758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:08:44.638519Z","caller":"traceutil/trace.go:171","msg":"trace[1187759334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1197; }","duration":"962.872711ms","start":"2026-07-04T10:08:43.67563Z","end":"2026-07-04T10:08:44.638502Z","steps":["trace[1187759334] 'agreement among raft nodes before linearized reading' (duration: 962.725407ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:44.638571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:08:43.675613Z","time spent":"962.947432ms","remote":"127.0.0.1:34686","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-04T10:08:44.638601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.812699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-04T10:08:44.638612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090335272s","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-04T10:08:44.638624Z","caller":"traceutil/trace.go:171","msg":"trace[14995476] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1197; }","duration":"621.85619ms","start":"2026-07-04T10:08:44.016761Z","end":"2026-07-04T10:08:44.638617Z","steps":["trace[14995476] 'agreement among raft nodes before linearized reading' (duration: 621.789219ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:08:44.638644Z","caller":"traceutil/trace.go:171","msg":"trace[1305137912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1197; }","duration":"1.090398393s","start":"2026-07-04T10:08:43.548237Z","end":"2026-07-04T10:08:44.638635Z","steps":["trace[1305137912] 'agreement among raft nodes before linearized reading' (duration: 1.09027469s)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:44.638651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:08:44.016752Z","time spent":"621.893791ms","remote":"127.0.0.1:34686","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-04T10:08:44.638673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:08:43.548218Z","time spent":"1.090448215s","remote":"127.0.0.1:34686","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-04T10:08:44.638532Z","caller":"traceutil/trace.go:171","msg":"trace[1050465250] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1197; }","duration":"340.505799ms","start":"2026-07-04T10:08:44.298018Z","end":"2026-07-04T10:08:44.638524Z","steps":["trace[1050465250] 'agreement among raft nodes before linearized reading' (duration: 340.464928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:44.638742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.129662ms","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-04T10:08:44.638753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:08:44.298008Z","time spent":"340.738995ms","remote":"127.0.0.1:34440","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-04T10:08:44.638783Z","caller":"traceutil/trace.go:171","msg":"trace[2088194050] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1197; }","duration":"696.207784ms","start":"2026-07-04T10:08:43.942562Z","end":"2026-07-04T10:08:44.63877Z","steps":["trace[2088194050] 'agreement among raft nodes before linearized reading' (duration: 696.099892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:44.638824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:08:43.942544Z","time spent":"696.271936ms","remote":"127.0.0.1:34686","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-04T10:08:56.878454Z","caller":"traceutil/trace.go:171","msg":"trace[453904139] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1290; }","duration":"110.634816ms","start":"2026-07-04T10:08:56.767799Z","end":"2026-07-04T10:08:56.878434Z","steps":["trace[453904139] 'read index received' (duration: 110.450952ms)","trace[453904139] 'applied index is now lower than readState.Index' (duration: 182.814µs)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:08:56.87889Z","caller":"traceutil/trace.go:171","msg":"trace[1727199550] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"180.808927ms","start":"2026-07-04T10:08:56.698067Z","end":"2026-07-04T10:08:56.878876Z","steps":["trace[1727199550] 'process raft request' (duration: 180.244014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:56.879269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.415864ms","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-04T10:08:56.88023Z","caller":"traceutil/trace.go:171","msg":"trace[1549128449] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1237; }","duration":"112.468469ms","start":"2026-07-04T10:08:56.767746Z","end":"2026-07-04T10:08:56.880215Z","steps":["trace[1549128449] 'agreement among raft nodes before linearized reading' (duration: 111.365783ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:08:57.072033Z","caller":"traceutil/trace.go:171","msg":"trace[1791210291] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"187.35208ms","start":"2026-07-04T10:08:56.884641Z","end":"2026-07-04T10:08:57.071993Z","steps":["trace[1791210291] 'process raft request' (duration: 187.178996ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:08:57.072337Z","caller":"traceutil/trace.go:171","msg":"trace[1392060413] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"187.598456ms","start":"2026-07-04T10:08:56.884712Z","end":"2026-07-04T10:08:57.07231Z","steps":["trace[1392060413] 'process raft request' (duration: 187.245677ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:08:57.072364Z","caller":"traceutil/trace.go:171","msg":"trace[1204684998] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"186.1036ms","start":"2026-07-04T10:08:56.886234Z","end":"2026-07-04T10:08:57.072338Z","steps":["trace[1204684998] 'process raft request' (duration: 186.059469ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:08:57.072397Z","caller":"traceutil/trace.go:171","msg":"trace[952805567] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"186.472189ms","start":"2026-07-04T10:08:56.885913Z","end":"2026-07-04T10:08:57.072385Z","steps":["trace[952805567] 'process raft request' (duration: 186.270225ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:08:59.226289Z","caller":"traceutil/trace.go:171","msg":"trace[823697033] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"146.459564ms","start":"2026-07-04T10:08:59.079799Z","end":"2026-07-04T10:08:59.226259Z","steps":["trace[823697033] 'process raft request' (duration: 146.27516ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:08:59.372709Z","caller":"traceutil/trace.go:171","msg":"trace[1376052832] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1300; }","duration":"160.133584ms","start":"2026-07-04T10:08:59.212552Z","end":"2026-07-04T10:08:59.372686Z","steps":["trace[1376052832] 'read index received' (duration: 13.774233ms)","trace[1376052832] 'applied index is now lower than readState.Index' (duration: 146.358281ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:08:59.372997Z","caller":"traceutil/trace.go:171","msg":"trace[1088378668] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"290.089642ms","start":"2026-07-04T10:08:59.082839Z","end":"2026-07-04T10:08:59.372929Z","steps":["trace[1088378668] 'process raft request' (duration: 289.805825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:08:59.37307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.506553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:08:59.373117Z","caller":"traceutil/trace.go:171","msg":"trace[901924257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1249; }","duration":"160.613185ms","start":"2026-07-04T10:08:59.21249Z","end":"2026-07-04T10:08:59.373103Z","steps":["trace[901924257] 'agreement among raft nodes before linearized reading' (duration: 160.527273ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:08:59.373475Z","caller":"traceutil/trace.go:171","msg":"trace[293950835] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"292.825525ms","start":"2026-07-04T10:08:59.080636Z","end":"2026-07-04T10:08:59.373462Z","steps":["trace[293950835] 'process raft request' (duration: 288.104235ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:08:59.373597Z","caller":"traceutil/trace.go:171","msg":"trace[1370366661] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"292.430946ms","start":"2026-07-04T10:08:59.081155Z","end":"2026-07-04T10:08:59.373586Z","steps":["trace[1370366661] 'process raft request' (duration: 291.447013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:00.383125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.589415ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-04T10:09:00.38325Z","caller":"traceutil/trace.go:171","msg":"trace[671830000] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1303; }","duration":"345.539608ms","start":"2026-07-04T10:09:00.037691Z","end":"2026-07-04T10:09:00.383231Z","steps":["trace[671830000] 'read index received' (duration: 111.067316ms)","trace[671830000] 'applied index is now lower than readState.Index' (duration: 234.470712ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:09:00.383391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.694011ms","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-04T10:09:00.38342Z","caller":"traceutil/trace.go:171","msg":"trace[617169522] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1249; }","duration":"345.769403ms","start":"2026-07-04T10:09:00.03764Z","end":"2026-07-04T10:09:00.38341Z","steps":["trace[617169522] 'agreement among raft nodes before linearized reading' (duration: 345.698032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:00.383472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:00.037619Z","time spent":"345.842755ms","remote":"127.0.0.1:37934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-07-04T10:09:00.383524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.76453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:09:00.38358Z","caller":"traceutil/trace.go:171","msg":"trace[930362652] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1249; }","duration":"134.874313ms","start":"2026-07-04T10:09:00.248694Z","end":"2026-07-04T10:09:00.383568Z","steps":["trace[930362652] 'agreement among raft nodes before linearized reading' (duration: 134.74454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:02.673998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.782145ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-04T10:09:02.674382Z","caller":"traceutil/trace.go:171","msg":"trace[636190632] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"340.006608ms","start":"2026-07-04T10:09:02.334099Z","end":"2026-07-04T10:09:02.674106Z","steps":["trace[636190632] 'process raft request' (duration: 121.005828ms)","trace[636190632] 'compare' (duration: 218.206432ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:09:02.674488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:02.334075Z","time spent":"340.369487ms","remote":"127.0.0.1:34452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-04T10:09:03.065123Z","caller":"traceutil/trace.go:171","msg":"trace[484377662] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1320; }","duration":"118.195043ms","start":"2026-07-04T10:09:02.946908Z","end":"2026-07-04T10:09:03.065103Z","steps":["trace[484377662] 'read index received' (duration: 118.019969ms)","trace[484377662] 'applied index is now lower than readState.Index' (duration: 174.304µs)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:09:03.065172Z","caller":"traceutil/trace.go:171","msg":"trace[1509567928] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"135.049387ms","start":"2026-07-04T10:09:02.930095Z","end":"2026-07-04T10:09:03.065144Z","steps":["trace[1509567928] 'process raft request' (duration: 134.850333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:03.065281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.352907ms","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-04T10:09:03.06531Z","caller":"traceutil/trace.go:171","msg":"trace[1094931778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1265; }","duration":"118.427458ms","start":"2026-07-04T10:09:02.946876Z","end":"2026-07-04T10:09:03.065303Z","steps":["trace[1094931778] 'agreement among raft nodes before linearized reading' (duration: 118.303245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:03.42546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.248689ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-04T10:09:03.425751Z","caller":"traceutil/trace.go:171","msg":"trace[896272204] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"356.396711ms","start":"2026-07-04T10:09:03.069329Z","end":"2026-07-04T10:09:03.425725Z","steps":["trace[896272204] 'process raft request' (duration: 123.796584ms)","trace[896272204] 'compare' (duration: 232.143336ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:09:03.425862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:03.069308Z","time spent":"356.498673ms","remote":"127.0.0.1:34686","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-04T10:09:03.975793Z","caller":"traceutil/trace.go:171","msg":"trace[338864274] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"545.84291ms","start":"2026-07-04T10:09:03.429922Z","end":"2026-07-04T10:09:03.975765Z","steps":["trace[338864274] 'process raft request' (duration: 545.691096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:03.975945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:03.429896Z","time spent":"545.974833ms","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-04T10:09:04.426053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.75072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-04T10:09:04.426282Z","caller":"traceutil/trace.go:171","msg":"trace[1860112282] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1323; }","duration":"131.106564ms","start":"2026-07-04T10:09:04.295156Z","end":"2026-07-04T10:09:04.426263Z","steps":["trace[1860112282] 'read index received' (duration: 73.041µs)","trace[1860112282] 'applied index is now lower than readState.Index' (duration: 131.031653ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:09:04.426292Z","caller":"traceutil/trace.go:171","msg":"trace[482395086] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"995.793828ms","start":"2026-07-04T10:09:03.430465Z","end":"2026-07-04T10:09:04.426258Z","steps":["trace[482395086] 'process raft request' (duration: 789.739501ms)","trace[482395086] 'compare' (duration: 205.546075ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:09:04.42635Z","caller":"traceutil/trace.go:171","msg":"trace[877932800] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"995.765738ms","start":"2026-07-04T10:09:03.43053Z","end":"2026-07-04T10:09:04.426296Z","steps":["trace[877932800] 'process raft request' (duration: 995.633255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:04.42645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:03.430452Z","time spent":"995.917112ms","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-04T10:09:04.426456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.31539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:09:04.426515Z","caller":"traceutil/trace.go:171","msg":"trace[1295376755] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"995.39864ms","start":"2026-07-04T10:09:03.431091Z","end":"2026-07-04T10:09:04.426489Z","steps":["trace[1295376755] 'process raft request' (duration: 995.130013ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:09:04.426531Z","caller":"traceutil/trace.go:171","msg":"trace[1772275360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1270; }","duration":"131.411612ms","start":"2026-07-04T10:09:04.295078Z","end":"2026-07-04T10:09:04.426489Z","steps":["trace[1772275360] 'agreement among raft nodes before linearized reading' (duration: 131.244998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:04.426577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:03.430524Z","time spent":"995.922692ms","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-04T10:09:04.426608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:03.431071Z","time spent":"995.490471ms","remote":"127.0.0.1:34686","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-04T10:09:05.527581Z","caller":"traceutil/trace.go:171","msg":"trace[1386562818] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"457.561037ms","start":"2026-07-04T10:09:05.069988Z","end":"2026-07-04T10:09:05.527549Z","steps":["trace[1386562818] 'process raft request' (duration: 440.117729ms)","trace[1386562818] 'compare' (duration: 16.973907ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:09:05.527732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:05.069963Z","time spent":"457.69288ms","remote":"127.0.0.1:34686","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-04T10:09:06.138282Z","caller":"traceutil/trace.go:171","msg":"trace[1044420955] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1326; }","duration":"844.135924ms","start":"2026-07-04T10:09:05.294115Z","end":"2026-07-04T10:09:06.138251Z","steps":["trace[1044420955] 'read index received' (duration: 216.00502ms)","trace[1044420955] 'applied index is now lower than readState.Index' (duration: 628.128924ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:09:06.138599Z","caller":"traceutil/trace.go:171","msg":"trace[1793429042] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"999.710061ms","start":"2026-07-04T10:09:05.138867Z","end":"2026-07-04T10:09:06.138577Z","steps":["trace[1793429042] 'process raft request' (duration: 912.84924ms)","trace[1793429042] 'compare' (duration: 86.229436ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:09:06.13872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:05.138844Z","time spent":"999.800333ms","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-04T10:09:06.138986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.877551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-04T10:09:06.139023Z","caller":"traceutil/trace.go:171","msg":"trace[445183574] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1272; }","duration":"844.955873ms","start":"2026-07-04T10:09:05.294056Z","end":"2026-07-04T10:09:06.139012Z","steps":["trace[445183574] 'agreement among raft nodes before linearized reading' (duration: 844.83484ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:06.139056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:05.294034Z","time spent":"845.013095ms","remote":"127.0.0.1:34906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":107,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-07-04T10:09:06.139112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.114869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-04T10:09:06.139189Z","caller":"traceutil/trace.go:171","msg":"trace[689595684] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1272; }","duration":"828.240583ms","start":"2026-07-04T10:09:05.310929Z","end":"2026-07-04T10:09:06.139169Z","steps":["trace[689595684] 'agreement among raft nodes before linearized reading' (duration: 828.082239ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:06.13923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:05.310914Z","time spent":"828.306074ms","remote":"127.0.0.1:34536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-07-04T10:09:06.139324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.242432ms","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-04T10:09:06.139427Z","caller":"traceutil/trace.go:171","msg":"trace[1665083608] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1272; }","duration":"159.388876ms","start":"2026-07-04T10:09:05.980018Z","end":"2026-07-04T10:09:06.139407Z","steps":["trace[1665083608] 'agreement among raft nodes before linearized reading' (duration: 159.11487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:06.139357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.137567ms","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-04T10:09:06.139552Z","caller":"traceutil/trace.go:171","msg":"trace[829184259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1272; }","duration":"709.362783ms","start":"2026-07-04T10:09:05.430166Z","end":"2026-07-04T10:09:06.139529Z","steps":["trace[829184259] 'agreement among raft nodes before linearized reading' (duration: 709.038535ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:06.139605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:05.430148Z","time spent":"709.443185ms","remote":"127.0.0.1:34686","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-04T10:09:06.829676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.397981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-04T10:09:06.829871Z","caller":"traceutil/trace.go:171","msg":"trace[1276223825] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"393.735074ms","start":"2026-07-04T10:09:06.436111Z","end":"2026-07-04T10:09:06.829846Z","steps":["trace[1276223825] 'process raft request' (duration: 393.663363ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:09:06.829884Z","caller":"traceutil/trace.go:171","msg":"trace[1219819209] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"394.37767ms","start":"2026-07-04T10:09:06.435478Z","end":"2026-07-04T10:09:06.829856Z","steps":["trace[1219819209] 'process raft request' (duration: 206.709763ms)","trace[1219819209] 'compare' (duration: 187.292018ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:09:06.829974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:06.436068Z","time spent":"393.860647ms","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-04T10:09:06.830022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:06.435453Z","time spent":"394.488292ms","remote":"127.0.0.1:34686","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-04T10:09:07.931955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.725953ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-04T10:09:08.509576Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415873856256659485,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-04T10:09:08.650094Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.11645318s","expected-duration":"1s"} {"level":"info","ts":"2026-07-04T10:09:08.650424Z","caller":"traceutil/trace.go:171","msg":"trace[726210306] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1334; }","duration":"641.514137ms","start":"2026-07-04T10:09:08.008889Z","end":"2026-07-04T10:09:08.650404Z","steps":["trace[726210306] 'read index received' (duration: 641.397064ms)","trace[726210306] 'applied index is now lower than readState.Index' (duration: 116.363µs)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:09:08.650582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.67972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:09:08.650609Z","caller":"traceutil/trace.go:171","msg":"trace[1044369051] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1278; }","duration":"641.761503ms","start":"2026-07-04T10:09:08.008841Z","end":"2026-07-04T10:09:08.650602Z","steps":["trace[1044369051] 'agreement among raft nodes before linearized reading' (duration: 641.66807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:08.650633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:08.008822Z","time spent":"641.804423ms","remote":"127.0.0.1:34726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-07-04T10:09:08.650602Z","caller":"traceutil/trace.go:171","msg":"trace[1602431533] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"1.117067494s","start":"2026-07-04T10:09:07.533482Z","end":"2026-07-04T10:09:08.650548Z","steps":["trace[1602431533] 'process raft request' (duration: 1.116791358s)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:08.650791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:07.533451Z","time spent":"1.117233608s","remote":"127.0.0.1:34686","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-04T10:09:08.650808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.549512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:09:08.650854Z","caller":"traceutil/trace.go:171","msg":"trace[1956825102] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1278; }","duration":"592.639454ms","start":"2026-07-04T10:09:08.058203Z","end":"2026-07-04T10:09:08.650842Z","steps":["trace[1956825102] 'agreement among raft nodes before linearized reading' (duration: 592.568313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:08.650889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:08.058178Z","time spent":"592.702926ms","remote":"127.0.0.1:34710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-04T10:09:08.650939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.383494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:09:08.650967Z","caller":"traceutil/trace.go:171","msg":"trace[700785378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1278; }","duration":"354.442516ms","start":"2026-07-04T10:09:08.296518Z","end":"2026-07-04T10:09:08.650961Z","steps":["trace[700785378] 'agreement among raft nodes before linearized reading' (duration: 354.401445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:08.650985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:08.296503Z","time spent":"354.478227ms","remote":"127.0.0.1:34430","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-04T10:09:08.651141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.93634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-04T10:09:08.651164Z","caller":"traceutil/trace.go:171","msg":"trace[1728575401] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1278; }","duration":"115.986642ms","start":"2026-07-04T10:09:08.535171Z","end":"2026-07-04T10:09:08.651158Z","steps":["trace[1728575401] 'agreement among raft nodes before linearized reading' (duration: 115.898409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:08.651379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.305692ms","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-04T10:09:08.651403Z","caller":"traceutil/trace.go:171","msg":"trace[1482965409] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1278; }","duration":"472.363572ms","start":"2026-07-04T10:09:08.179034Z","end":"2026-07-04T10:09:08.651397Z","steps":["trace[1482965409] 'agreement among raft nodes before linearized reading' (duration: 472.304931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:08.651423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:08.179015Z","time spent":"472.402144ms","remote":"127.0.0.1:34686","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-04T10:09:08.651532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.162473ms","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-04T10:09:08.651597Z","caller":"traceutil/trace.go:171","msg":"trace[2075503789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1278; }","duration":"444.227574ms","start":"2026-07-04T10:09:08.207353Z","end":"2026-07-04T10:09:08.651581Z","steps":["trace[2075503789] 'agreement among raft nodes before linearized reading' (duration: 444.02841ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:08.651645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:08.207299Z","time spent":"444.335998ms","remote":"127.0.0.1:34686","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-04T10:09:09.06465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.218111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-04T10:09:09.064891Z","caller":"traceutil/trace.go:171","msg":"trace[913444979] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1336; }","duration":"231.40157ms","start":"2026-07-04T10:09:08.833466Z","end":"2026-07-04T10:09:09.064868Z","steps":["trace[913444979] 'read index received' (duration: 41.861µs)","trace[913444979] 'applied index is now lower than readState.Index' (duration: 231.358469ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:09:09.065043Z","caller":"traceutil/trace.go:171","msg":"trace[439511587] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"410.120578ms","start":"2026-07-04T10:09:08.65491Z","end":"2026-07-04T10:09:09.065031Z","steps":["trace[439511587] 'process raft request' (duration: 108.428175ms)","trace[439511587] 'compare' (duration: 301.014747ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:09:09.065139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:08.654896Z","time spent":"410.200059ms","remote":"127.0.0.1:34686","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-04T10:09:09.065375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.111943ms","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-04T10:09:09.06545Z","caller":"traceutil/trace.go:171","msg":"trace[1488435909] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1281; }","duration":"231.222715ms","start":"2026-07-04T10:09:08.834209Z","end":"2026-07-04T10:09:09.065432Z","steps":["trace[1488435909] 'agreement among raft nodes before linearized reading' (duration: 230.981149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:09.06552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.008844ms","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-04T10:09:09.065592Z","caller":"traceutil/trace.go:171","msg":"trace[638609348] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"410.457915ms","start":"2026-07-04T10:09:08.655122Z","end":"2026-07-04T10:09:09.06558Z","steps":["trace[638609348] 'process raft request' (duration: 409.643126ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:09:09.065651Z","caller":"traceutil/trace.go:171","msg":"trace[329348721] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1281; }","duration":"232.195098ms","start":"2026-07-04T10:09:08.833424Z","end":"2026-07-04T10:09:09.065619Z","steps":["trace[329348721] 'agreement among raft nodes before linearized reading' (duration: 231.731617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:09.065698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:08.655115Z","time spent":"410.511106ms","remote":"127.0.0.1:34686","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-04T10:09:11.128658Z","caller":"traceutil/trace.go:171","msg":"trace[696503173] linearizableReadLoop","detail":"{readStateIndex:1341; appliedIndex:1340; }","duration":"418.972755ms","start":"2026-07-04T10:09:10.709659Z","end":"2026-07-04T10:09:11.128631Z","steps":["trace[696503173] 'read index received' (duration: 418.875142ms)","trace[696503173] 'applied index is now lower than readState.Index' (duration: 96.373µs)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:09:11.128781Z","caller":"traceutil/trace.go:171","msg":"trace[207177020] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"471.864231ms","start":"2026-07-04T10:09:10.656906Z","end":"2026-07-04T10:09:11.12877Z","steps":["trace[207177020] 'process raft request' (duration: 471.564974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:11.128897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:10.656876Z","time spent":"471.931313ms","remote":"127.0.0.1:34686","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-04T10:09:11.129272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.603139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-04T10:09:11.129352Z","caller":"traceutil/trace.go:171","msg":"trace[811001586] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1284; }","duration":"419.746952ms","start":"2026-07-04T10:09:10.709587Z","end":"2026-07-04T10:09:11.129334Z","steps":["trace[811001586] 'agreement among raft nodes before linearized reading' (duration: 419.337513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:11.129392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:10.709516Z","time spent":"419.868655ms","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-04T10:09:11.240017Z","caller":"traceutil/trace.go:171","msg":"trace[188135834] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"104.986074ms","start":"2026-07-04T10:09:11.135004Z","end":"2026-07-04T10:09:11.23999Z","steps":["trace[188135834] 'process raft request' (duration: 104.938543ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:09:11.240367Z","caller":"traceutil/trace.go:171","msg":"trace[1940232805] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"198.598833ms","start":"2026-07-04T10:09:11.041549Z","end":"2026-07-04T10:09:11.240148Z","steps":["trace[1940232805] 'process raft request' (duration: 103.09547ms)","trace[1940232805] 'compare' (duration: 95.152814ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:09:11.240459Z","caller":"traceutil/trace.go:171","msg":"trace[339362219] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1341; }","duration":"111.710462ms","start":"2026-07-04T10:09:11.128736Z","end":"2026-07-04T10:09:11.240447Z","steps":["trace[339362219] 'read index received' (duration: 15.871091ms)","trace[339362219] 'applied index is now lower than readState.Index' (duration: 95.838051ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:09:11.240712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.820144ms","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-04T10:09:11.240751Z","caller":"traceutil/trace.go:171","msg":"trace[391754561] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1286; }","duration":"117.931127ms","start":"2026-07-04T10:09:11.12281Z","end":"2026-07-04T10:09:11.240741Z","steps":["trace[391754561] 'agreement among raft nodes before linearized reading' (duration: 117.776363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:11.240923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.967397ms","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-04T10:09:11.241001Z","caller":"traceutil/trace.go:171","msg":"trace[1718565982] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1286; }","duration":"171.140621ms","start":"2026-07-04T10:09:11.069846Z","end":"2026-07-04T10:09:11.240987Z","steps":["trace[1718565982] 'agreement among raft nodes before linearized reading' (duration: 170.982628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:11.241139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.97768ms","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-04T10:09:11.241273Z","caller":"traceutil/trace.go:171","msg":"trace[1284358576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1286; }","duration":"172.156044ms","start":"2026-07-04T10:09:11.06909Z","end":"2026-07-04T10:09:11.241247Z","steps":["trace[1284358576] 'agreement among raft nodes before linearized reading' (duration: 171.785116ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:09:11.945705Z","caller":"traceutil/trace.go:171","msg":"trace[403601775] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1344; }","duration":"247.778222ms","start":"2026-07-04T10:09:11.697902Z","end":"2026-07-04T10:09:11.94568Z","steps":["trace[403601775] 'read index received' (duration: 247.438734ms)","trace[403601775] 'applied index is now lower than readState.Index' (duration: 338.428µs)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:09:11.945952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.040408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:09:11.945744Z","caller":"traceutil/trace.go:171","msg":"trace[680042082] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"700.091246ms","start":"2026-07-04T10:09:11.245618Z","end":"2026-07-04T10:09:11.945709Z","steps":["trace[680042082] 'process raft request' (duration: 699.80626ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:09:11.946057Z","caller":"traceutil/trace.go:171","msg":"trace[797571846] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1290; }","duration":"248.210132ms","start":"2026-07-04T10:09:11.697835Z","end":"2026-07-04T10:09:11.946045Z","steps":["trace[797571846] 'agreement among raft nodes before linearized reading' (duration: 248.048399ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:09:11.945756Z","caller":"traceutil/trace.go:171","msg":"trace[1441034380] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"700.001394ms","start":"2026-07-04T10:09:11.245726Z","end":"2026-07-04T10:09:11.945727Z","steps":["trace[1441034380] 'process raft request' (duration: 699.862521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:11.946184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:11.2456Z","time spent":"700.474855ms","remote":"127.0.0.1:34686","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-04T10:09:11.946262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:11.245693Z","time spent":"700.492486ms","remote":"127.0.0.1:34686","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-04T10:09:11.945827Z","caller":"traceutil/trace.go:171","msg":"trace[239153629] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"700.012114ms","start":"2026-07-04T10:09:11.245806Z","end":"2026-07-04T10:09:11.945818Z","steps":["trace[239153629] 'process raft request' (duration: 699.83253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:11.946411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:11.245781Z","time spent":"700.603558ms","remote":"127.0.0.1:34686","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-04T10:09:12.870251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.247818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:09:12.870394Z","caller":"traceutil/trace.go:171","msg":"trace[525453086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1290; }","duration":"572.409741ms","start":"2026-07-04T10:09:12.297913Z","end":"2026-07-04T10:09:12.870323Z","steps":["trace[525453086] 'range keys from in-memory index tree' (duration: 572.155796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:12.870437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:12.297872Z","time spent":"572.552355ms","remote":"127.0.0.1:34430","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-04T10:09:12.870562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.488604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-04T10:09:12.870573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.632786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:09:12.870635Z","caller":"traceutil/trace.go:171","msg":"trace[1680128332] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:1290; }","duration":"673.703889ms","start":"2026-07-04T10:09:12.196908Z","end":"2026-07-04T10:09:12.870612Z","steps":["trace[1680128332] 'range keys from in-memory index tree' (duration: 673.29147ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:09:12.87066Z","caller":"traceutil/trace.go:171","msg":"trace[390945068] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1290; }","duration":"495.78582ms","start":"2026-07-04T10:09:12.374855Z","end":"2026-07-04T10:09:12.87064Z","steps":["trace[390945068] 'count revisions from in-memory index tree' (duration: 495.537984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:12.870675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:12.196868Z","time spent":"673.798811ms","remote":"127.0.0.1:34452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"warn","ts":"2026-07-04T10:09:12.870703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:12.374782Z","time spent":"495.910453ms","remote":"127.0.0.1:34704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-07-04T10:09:14.975406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.489501ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-04T10:09:14.975502Z","caller":"traceutil/trace.go:171","msg":"trace[496031878] linearizableReadLoop","detail":"{readStateIndex:1363; appliedIndex:1362; }","duration":"221.244162ms","start":"2026-07-04T10:09:14.754242Z","end":"2026-07-04T10:09:14.975486Z","steps":["trace[496031878] 'read index received' (duration: 36.351µs)","trace[496031878] 'applied index is now lower than readState.Index' (duration: 221.206601ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:09:14.975553Z","caller":"traceutil/trace.go:171","msg":"trace[1782872854] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"368.704949ms","start":"2026-07-04T10:09:14.606828Z","end":"2026-07-04T10:09:14.975533Z","steps":["trace[1782872854] 'process raft request' (duration: 78.014384ms)","trace[1782872854] 'compare' (duration: 290.235885ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:09:14.975569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.321464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-04T10:09:14.975599Z","caller":"traceutil/trace.go:171","msg":"trace[1522711091] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1305; }","duration":"221.382726ms","start":"2026-07-04T10:09:14.754208Z","end":"2026-07-04T10:09:14.97559Z","steps":["trace[1522711091] 'agreement among raft nodes before linearized reading' (duration: 221.322314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:14.975699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:14.606804Z","time spent":"368.789072ms","remote":"127.0.0.1:34794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-04T10:09:15.295686Z","caller":"traceutil/trace.go:171","msg":"trace[79898351] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"287.149603ms","start":"2026-07-04T10:09:15.008513Z","end":"2026-07-04T10:09:15.295663Z","steps":["trace[79898351] 'process raft request' (duration: 286.974299ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:09:15.306163Z","caller":"traceutil/trace.go:171","msg":"trace[1191180533] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"164.605578ms","start":"2026-07-04T10:09:15.141538Z","end":"2026-07-04T10:09:15.306143Z","steps":["trace[1191180533] 'process raft request' (duration: 164.469615ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:09:19.502367Z","caller":"traceutil/trace.go:171","msg":"trace[587283537] linearizableReadLoop","detail":"{readStateIndex:1397; appliedIndex:1396; }","duration":"100.035099ms","start":"2026-07-04T10:09:19.402309Z","end":"2026-07-04T10:09:19.502344Z","steps":["trace[587283537] 'read index received' (duration: 99.633659ms)","trace[587283537] 'applied index is now lower than readState.Index' (duration: 400.68µs)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:09:19.50252Z","caller":"traceutil/trace.go:171","msg":"trace[1319358155] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"106.873929ms","start":"2026-07-04T10:09:19.395612Z","end":"2026-07-04T10:09:19.502486Z","steps":["trace[1319358155] 'process raft request' (duration: 106.46921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:19.502659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.331985ms","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-04T10:09:19.502729Z","caller":"traceutil/trace.go:171","msg":"trace[100988711] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1338; }","duration":"100.441498ms","start":"2026-07-04T10:09:19.402277Z","end":"2026-07-04T10:09:19.502718Z","steps":["trace[100988711] 'agreement among raft nodes before linearized reading' (duration: 100.220933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:20.650682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.746327ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-04T10:09:20.650791Z","caller":"traceutil/trace.go:171","msg":"trace[718749952] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1403; }","duration":"459.082322ms","start":"2026-07-04T10:09:20.191693Z","end":"2026-07-04T10:09:20.650775Z","steps":["trace[718749952] 'read index received' (duration: 40.391µs)","trace[718749952] 'applied index is now lower than readState.Index' (duration: 459.040641ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:09:20.650889Z","caller":"traceutil/trace.go:171","msg":"trace[1270571928] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"550.265803ms","start":"2026-07-04T10:09:20.100547Z","end":"2026-07-04T10:09:20.650812Z","steps":["trace[1270571928] 'process raft request' (duration: 31.079886ms)","trace[1270571928] 'compare' (duration: 518.592003ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:09:20.651076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:20.100528Z","time spent":"550.461988ms","remote":"127.0.0.1:34494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-04T10:09:20.651145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.434711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:09:20.651193Z","caller":"traceutil/trace.go:171","msg":"trace[1348414189] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1345; }","duration":"459.528502ms","start":"2026-07-04T10:09:20.191652Z","end":"2026-07-04T10:09:20.651181Z","steps":["trace[1348414189] 'agreement among raft nodes before linearized reading' (duration: 459.352428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:20.651222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.949996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-04T10:09:20.651274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:20.191633Z","time spent":"459.629935ms","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-04T10:09:20.651167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.237551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:09:20.651358Z","caller":"traceutil/trace.go:171","msg":"trace[1940138771] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1345; }","duration":"242.10698ms","start":"2026-07-04T10:09:20.4092Z","end":"2026-07-04T10:09:20.651307Z","steps":["trace[1940138771] 'agreement among raft nodes before linearized reading' (duration: 241.881115ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:09:20.65141Z","caller":"traceutil/trace.go:171","msg":"trace[750403729] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1345; }","duration":"354.530298ms","start":"2026-07-04T10:09:20.29686Z","end":"2026-07-04T10:09:20.65139Z","steps":["trace[750403729] 'agreement among raft nodes before linearized reading' (duration: 354.21337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:20.651486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:20.296836Z","time spent":"354.624109ms","remote":"127.0.0.1:34430","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-04T10:09:21.208801Z","caller":"traceutil/trace.go:171","msg":"trace[974394054] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"521.075651ms","start":"2026-07-04T10:09:20.687698Z","end":"2026-07-04T10:09:21.208774Z","steps":["trace[974394054] 'process raft request' (duration: 520.879936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:21.209003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:20.687672Z","time spent":"521.206474ms","remote":"127.0.0.1:34494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":929,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-04T10:09:21.301959Z","caller":"traceutil/trace.go:171","msg":"trace[323420520] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1405; }","duration":"192.427008ms","start":"2026-07-04T10:09:21.109453Z","end":"2026-07-04T10:09:21.30188Z","steps":["trace[323420520] 'read index received' (duration: 99.24417ms)","trace[323420520] 'applied index is now lower than readState.Index' (duration: 93.181678ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:09:21.302033Z","caller":"traceutil/trace.go:171","msg":"trace[436712733] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"611.629708ms","start":"2026-07-04T10:09:20.690333Z","end":"2026-07-04T10:09:21.301963Z","steps":["trace[436712733] 'process raft request' (duration: 551.163124ms)","trace[436712733] 'compare' (duration: 60.156746ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:09:21.302085Z","caller":"traceutil/trace.go:171","msg":"trace[751794764] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"155.71508ms","start":"2026-07-04T10:09:21.146308Z","end":"2026-07-04T10:09:21.302024Z","steps":["trace[751794764] 'process raft request' (duration: 155.516555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:21.302181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.710414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-07-04T10:09:21.302246Z","caller":"traceutil/trace.go:171","msg":"trace[172204399] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1349; }","duration":"192.835727ms","start":"2026-07-04T10:09:21.109399Z","end":"2026-07-04T10:09:21.302234Z","steps":["trace[172204399] 'agreement among raft nodes before linearized reading' (duration: 192.630372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:21.302255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-04T10:09:20.690308Z","time spent":"611.840232ms","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-04T10:09:21.730968Z","caller":"traceutil/trace.go:171","msg":"trace[1066386089] linearizableReadLoop","detail":"{readStateIndex:1413; appliedIndex:1412; }","duration":"217.563016ms","start":"2026-07-04T10:09:21.513358Z","end":"2026-07-04T10:09:21.730921Z","steps":["trace[1066386089] 'read index received' (duration: 162.9509ms)","trace[1066386089] 'applied index is now lower than readState.Index' (duration: 54.610556ms)"],"step_count":2} {"level":"info","ts":"2026-07-04T10:09:21.731004Z","caller":"traceutil/trace.go:171","msg":"trace[1063132269] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"219.804678ms","start":"2026-07-04T10:09:21.511169Z","end":"2026-07-04T10:09:21.730974Z","steps":["trace[1063132269] 'process raft request' (duration: 165.196962ms)","trace[1063132269] 'compare' (duration: 54.365611ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:09:21.731191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.815972ms","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-04T10:09:21.731233Z","caller":"traceutil/trace.go:171","msg":"trace[1003259614] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1354; }","duration":"217.878243ms","start":"2026-07-04T10:09:21.513344Z","end":"2026-07-04T10:09:21.731222Z","steps":["trace[1003259614] 'agreement among raft nodes before linearized reading' (duration: 217.703079ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:22.23154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.732914ms","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-04T10:09:22.231622Z","caller":"traceutil/trace.go:171","msg":"trace[1339580717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1355; }","duration":"218.844216ms","start":"2026-07-04T10:09:22.012762Z","end":"2026-07-04T10:09:22.231606Z","steps":["trace[1339580717] 'range keys from in-memory index tree' (duration: 218.606101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:22.231637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.957089ms","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-04T10:09:22.231692Z","caller":"traceutil/trace.go:171","msg":"trace[1968391105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1355; }","duration":"222.053671ms","start":"2026-07-04T10:09:22.009623Z","end":"2026-07-04T10:09:22.231676Z","steps":["trace[1968391105] 'range keys from in-memory index tree' (duration: 221.514408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-04T10:09:22.231551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.242632ms","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-04T10:09:22.231805Z","caller":"traceutil/trace.go:171","msg":"trace[1803429268] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1355; }","duration":"224.531229ms","start":"2026-07-04T10:09:22.007262Z","end":"2026-07-04T10:09:22.231793Z","steps":["trace[1803429268] 'range keys from in-memory index tree' (duration: 224.093468ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:09:22.377035Z","caller":"traceutil/trace.go:171","msg":"trace[127645284] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"141.336874ms","start":"2026-07-04T10:09:22.235672Z","end":"2026-07-04T10:09:22.377009Z","steps":["trace[127645284] 'process raft request' (duration: 139.303596ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:09:22.37731Z","caller":"traceutil/trace.go:171","msg":"trace[1726019069] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"139.958002ms","start":"2026-07-04T10:09:22.237327Z","end":"2026-07-04T10:09:22.377285Z","steps":["trace[1726019069] 'process raft request' (duration: 139.8498ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:09:22.3773Z","caller":"traceutil/trace.go:171","msg":"trace[1436313205] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"140.040674ms","start":"2026-07-04T10:09:22.237187Z","end":"2026-07-04T10:09:22.377228Z","steps":["trace[1436313205] 'process raft request' (duration: 139.716066ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:09:22.396058Z","caller":"traceutil/trace.go:171","msg":"trace[1139616814] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"112.92527ms","start":"2026-07-04T10:09:22.283096Z","end":"2026-07-04T10:09:22.396021Z","steps":["trace[1139616814] 'process raft request' (duration: 112.805937ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:09:23.896104Z","caller":"traceutil/trace.go:171","msg":"trace[1548289679] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"114.468806ms","start":"2026-07-04T10:09:23.781607Z","end":"2026-07-04T10:09:23.896076Z","steps":["trace[1548289679] 'process raft request' (duration: 114.329552ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:09:24.500874Z","caller":"traceutil/trace.go:171","msg":"trace[1252124316] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"114.979287ms","start":"2026-07-04T10:09:24.38587Z","end":"2026-07-04T10:09:24.50085Z","steps":["trace[1252124316] 'process raft request' (duration: 114.829204ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:09:24.502623Z","caller":"traceutil/trace.go:171","msg":"trace[1326888540] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"114.387274ms","start":"2026-07-04T10:09:24.388203Z","end":"2026-07-04T10:09:24.50259Z","steps":["trace[1326888540] 'process raft request' (duration: 114.331203ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:09:24.502877Z","caller":"traceutil/trace.go:171","msg":"trace[1662291566] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"115.47438ms","start":"2026-07-04T10:09:24.387394Z","end":"2026-07-04T10:09:24.502868Z","steps":["trace[1662291566] 'process raft request' (duration: 114.966848ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:10:02.92431Z","caller":"traceutil/trace.go:171","msg":"trace[408065903] transaction","detail":"{read_only:false; response_revision:1560; number_of_response:1; }","duration":"102.710558ms","start":"2026-07-04T10:10:02.821572Z","end":"2026-07-04T10:10:02.924282Z","steps":["trace[408065903] 'process raft request' (duration: 72.703264ms)","trace[408065903] 'compare' (duration: 29.720667ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:10:03.521443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.743977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-04T10:10:03.521563Z","caller":"traceutil/trace.go:171","msg":"trace[537902248] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1561; }","duration":"224.955513ms","start":"2026-07-04T10:10:03.29658Z","end":"2026-07-04T10:10:03.521535Z","steps":["trace[537902248] 'range keys from in-memory index tree' (duration: 224.606834ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:10:09.798415Z","caller":"traceutil/trace.go:171","msg":"trace[1845705611] transaction","detail":"{read_only:false; response_revision:1589; number_of_response:1; }","duration":"150.913667ms","start":"2026-07-04T10:10:09.64748Z","end":"2026-07-04T10:10:09.798393Z","steps":["trace[1845705611] 'process raft request' (duration: 150.801555ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:11:15.087424Z","caller":"traceutil/trace.go:171","msg":"trace[1811554787] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"106.881205ms","start":"2026-07-04T10:11:14.980506Z","end":"2026-07-04T10:11:15.087387Z","steps":["trace[1811554787] 'process raft request' (duration: 106.64086ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:11:28.727928Z","caller":"traceutil/trace.go:171","msg":"trace[734636267] transaction","detail":"{read_only:false; response_revision:1951; number_of_response:1; }","duration":"150.169644ms","start":"2026-07-04T10:11:28.577724Z","end":"2026-07-04T10:11:28.727894Z","steps":["trace[734636267] 'process raft request' (duration: 150.011431ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:11:28.871106Z","caller":"traceutil/trace.go:171","msg":"trace[4742654] transaction","detail":"{read_only:false; response_revision:1952; number_of_response:1; }","duration":"108.017282ms","start":"2026-07-04T10:11:28.763058Z","end":"2026-07-04T10:11:28.871075Z","steps":["trace[4742654] 'process raft request' (duration: 46.132315ms)","trace[4742654] 'compare' (duration: 61.571639ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:11:29.139675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.825829ms","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-04T10:11:29.140159Z","caller":"traceutil/trace.go:171","msg":"trace[1371501036] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1952; }","duration":"119.970403ms","start":"2026-07-04T10:11:29.019773Z","end":"2026-07-04T10:11:29.139743Z","steps":["trace[1371501036] 'range keys from in-memory index tree' (duration: 119.640916ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:11:35.005454Z","caller":"traceutil/trace.go:171","msg":"trace[1686761557] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"109.970618ms","start":"2026-07-04T10:11:34.895458Z","end":"2026-07-04T10:11:35.005428Z","steps":["trace[1686761557] 'process raft request' (duration: 109.826334ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:11:38.895791Z","caller":"traceutil/trace.go:171","msg":"trace[1841537353] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"123.22551ms","start":"2026-07-04T10:11:38.772541Z","end":"2026-07-04T10:11:38.895766Z","steps":["trace[1841537353] 'process raft request' (duration: 123.093747ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:13:14.071257Z","caller":"traceutil/trace.go:171","msg":"trace[1126003776] transaction","detail":"{read_only:false; response_revision:2459; number_of_response:1; }","duration":"121.149215ms","start":"2026-07-04T10:13:13.950078Z","end":"2026-07-04T10:13:14.071227Z","steps":["trace[1126003776] 'process raft request' (duration: 120.998261ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:13:14.120486Z","caller":"traceutil/trace.go:171","msg":"trace[270389629] linearizableReadLoop","detail":"{readStateIndex:2567; appliedIndex:2566; }","duration":"115.656676ms","start":"2026-07-04T10:13:14.004801Z","end":"2026-07-04T10:13:14.120457Z","steps":["trace[270389629] 'read index received' (duration: 66.460676ms)","trace[270389629] 'applied index is now lower than readState.Index' (duration: 49.1945ms)"],"step_count":2} {"level":"warn","ts":"2026-07-04T10:13:14.120749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.923803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-04T10:13:14.120855Z","caller":"traceutil/trace.go:171","msg":"trace[1766547889] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2460; }","duration":"116.041315ms","start":"2026-07-04T10:13:14.004746Z","end":"2026-07-04T10:13:14.120787Z","steps":["trace[1766547889] 'agreement among raft nodes before linearized reading' (duration: 115.81274ms)"],"step_count":1} {"level":"info","ts":"2026-07-04T10:13:14.121335Z","caller":"traceutil/trace.go:171","msg":"trace[1181151804] transaction","detail":"{read_only:false; response_revision:2460; number_of_response:1; }","duration":"171.197175ms","start":"2026-07-04T10:13:13.95012Z","end":"2026-07-04T10:13:14.121317Z","steps":["trace[1181151804] 'process raft request' (duration: 170.188751ms)"],"step_count":1}