{"level":"warn","ts":"2026-05-09T12:55:44.412852Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T12:55:44.412967Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.75:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.75:2380","--initial-cluster=instance=https://199.19.213.75:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.75:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.75: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-05-09T12:55:44.413485Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T12:55:44.413509Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.75:2380"]} {"level":"info","ts":"2026-05-09T12:55:44.413643Z","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-05-09T12:55:44.414776Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.75:2379"]} {"level":"info","ts":"2026-05-09T12:55:44.414852Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.75:2380"],"listen-peer-urls":["https://199.19.213.75:2380"],"advertise-client-urls":["https://199.19.213.75:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.75:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.75: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-05-09T12:55:44.429516Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"13.805315ms"} {"level":"info","ts":"2026-05-09T12:55:44.449453Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"42c325ad8bcf8012","cluster-id":"1e2ab336c559df5e"} {"level":"info","ts":"2026-05-09T12:55:44.449525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T12:55:44.449617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became follower at term 0"} {"level":"info","ts":"2026-05-09T12:55:44.449656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 42c325ad8bcf8012 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T12:55:44.449666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became follower at term 1"} {"level":"info","ts":"2026-05-09T12:55:44.4498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 switched to configuration voters=(4810730254267023378)"} {"level":"warn","ts":"2026-05-09T12:55:44.46122Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T12:55:44.463081Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T12:55:44.464412Z","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-05-09T12:55:44.467751Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"42c325ad8bcf8012","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T12:55:44.468303Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"42c325ad8bcf8012","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T12:55:44.468215Z","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-05-09T12:55:44.468613Z","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-05-09T12:55:44.468727Z","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-05-09T12:55:44.470228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 switched to configuration voters=(4810730254267023378)"} {"level":"info","ts":"2026-05-09T12:55:44.47047Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1e2ab336c559df5e","local-member-id":"42c325ad8bcf8012","added-peer-id":"42c325ad8bcf8012","added-peer-peer-urls":["https://199.19.213.75:2380"]} {"level":"info","ts":"2026-05-09T12:55:44.474619Z","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-05-09T12:55:44.474864Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"42c325ad8bcf8012","initial-advertise-peer-urls":["https://199.19.213.75:2380"],"listen-peer-urls":["https://199.19.213.75:2380"],"advertise-client-urls":["https://199.19.213.75:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.75:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T12:55:44.474913Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T12:55:44.475046Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.75:2380"} {"level":"info","ts":"2026-05-09T12:55:44.47507Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.75:2380"} {"level":"info","ts":"2026-05-09T12:55:45.150464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T12:55:45.150533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T12:55:45.150557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 received MsgPreVoteResp from 42c325ad8bcf8012 at term 1"} {"level":"info","ts":"2026-05-09T12:55:45.150604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became candidate at term 2"} {"level":"info","ts":"2026-05-09T12:55:45.150624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 received MsgVoteResp from 42c325ad8bcf8012 at term 2"} {"level":"info","ts":"2026-05-09T12:55:45.150645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became leader at term 2"} {"level":"info","ts":"2026-05-09T12:55:45.150672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 42c325ad8bcf8012 elected leader 42c325ad8bcf8012 at term 2"} {"level":"info","ts":"2026-05-09T12:55:45.768256Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T12:55:45.788483Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"42c325ad8bcf8012","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.75:2379]}","request-path":"/0/members/42c325ad8bcf8012/attributes","cluster-id":"1e2ab336c559df5e","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T12:55:45.788522Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T12:55:45.788608Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T12:55:45.789062Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T12:55:45.789092Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1e2ab336c559df5e","local-member-id":"42c325ad8bcf8012","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T12:55:45.789107Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T12:55:45.789256Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T12:55:45.789309Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T12:55:45.793412Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.75:2379"} {"level":"info","ts":"2026-05-09T12:55:45.793601Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-09T12:55:51.691634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.128212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:55:51.691776Z","caller":"traceutil/trace.go:171","msg":"trace[861738914] linearizableReadLoop","detail":"{readStateIndex:319; appliedIndex:318; }","duration":"351.263784ms","start":"2026-05-09T12:55:51.340495Z","end":"2026-05-09T12:55:51.691759Z","steps":["trace[861738914] 'read index received' (duration: 101.391743ms)","trace[861738914] 'applied index is now lower than readState.Index' (duration: 249.870831ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:55:51.69179Z","caller":"traceutil/trace.go:171","msg":"trace[200423024] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"386.735585ms","start":"2026-05-09T12:55:51.305026Z","end":"2026-05-09T12:55:51.691761Z","steps":["trace[200423024] 'process raft request' (duration: 136.911085ms)","trace[200423024] 'compare' (duration: 248.573329ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:55:51.691891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.399848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T12:55:51.691905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:55:51.305012Z","time spent":"386.826968ms","remote":"127.0.0.1:44562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:55:51.691984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.2981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-05-09T12:55:51.691929Z","caller":"traceutil/trace.go:171","msg":"trace[118252196] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:0; response_revision:307; }","duration":"351.47224ms","start":"2026-05-09T12:55:51.340438Z","end":"2026-05-09T12:55:51.69191Z","steps":["trace[118252196] 'agreement among raft nodes before linearized reading' (duration: 351.418738ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:55:51.692009Z","caller":"traceutil/trace.go:171","msg":"trace[1344498858] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:307; }","duration":"301.346711ms","start":"2026-05-09T12:55:51.390655Z","end":"2026-05-09T12:55:51.692002Z","steps":["trace[1344498858] 'agreement among raft nodes before linearized reading' (duration: 301.30095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:55:51.692042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:55:51.390643Z","time spent":"301.390753ms","remote":"127.0.0.1:44588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"warn","ts":"2026-05-09T12:55:51.692057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:55:51.340414Z","time spent":"351.607982ms","remote":"127.0.0.1:44588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"info","ts":"2026-05-09T12:56:07.180254Z","caller":"traceutil/trace.go:171","msg":"trace[297628903] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"104.320641ms","start":"2026-05-09T12:56:07.075909Z","end":"2026-05-09T12:56:07.18023Z","steps":["trace[297628903] 'process raft request' (duration: 104.016582ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:15.10215Z","caller":"traceutil/trace.go:171","msg":"trace[2056002339] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"173.25053ms","start":"2026-05-09T12:56:14.928873Z","end":"2026-05-09T12:56:15.102124Z","steps":["trace[2056002339] 'process raft request' (duration: 167.475606ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:15.107284Z","caller":"traceutil/trace.go:171","msg":"trace[878001326] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"111.114536ms","start":"2026-05-09T12:56:14.996154Z","end":"2026-05-09T12:56:15.107268Z","steps":["trace[878001326] 'process raft request' (duration: 110.568993ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:22.633726Z","caller":"traceutil/trace.go:171","msg":"trace[1497317191] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"242.010777ms","start":"2026-05-09T12:56:22.391681Z","end":"2026-05-09T12:56:22.633692Z","steps":["trace[1497317191] 'process raft request' (duration: 241.836342ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:28.634774Z","caller":"traceutil/trace.go:171","msg":"trace[625548333] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"313.373068ms","start":"2026-05-09T12:56:28.321377Z","end":"2026-05-09T12:56:28.63475Z","steps":["trace[625548333] 'process raft request' (duration: 313.183183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:28.634921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:28.321357Z","time spent":"313.490261ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:56:29.556466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.438751ms","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-05-09T12:56:29.556475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.15275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-09T12:56:29.556532Z","caller":"traceutil/trace.go:171","msg":"trace[1895430409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:630; }","duration":"214.548564ms","start":"2026-05-09T12:56:29.341968Z","end":"2026-05-09T12:56:29.556517Z","steps":["trace[1895430409] 'range keys from in-memory index tree' (duration: 214.206845ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:29.556552Z","caller":"traceutil/trace.go:171","msg":"trace[1274596254] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:630; }","duration":"165.257771ms","start":"2026-05-09T12:56:29.391273Z","end":"2026-05-09T12:56:29.55653Z","steps":["trace[1274596254] 'range keys from in-memory index tree' (duration: 165.018325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:29.556564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.743319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.75\" ","response":"range_response_count:1 size:132"} {"level":"warn","ts":"2026-05-09T12:56:29.556656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.144073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:56:29.556663Z","caller":"traceutil/trace.go:171","msg":"trace[1660220951] range","detail":"{range_begin:/registry/masterleases/199.19.213.75; range_end:; response_count:1; response_revision:630; }","duration":"826.870112ms","start":"2026-05-09T12:56:28.729768Z","end":"2026-05-09T12:56:29.556638Z","steps":["trace[1660220951] 'range keys from in-memory index tree' (duration: 826.674567ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:29.556676Z","caller":"traceutil/trace.go:171","msg":"trace[294388933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"828.192235ms","start":"2026-05-09T12:56:28.728478Z","end":"2026-05-09T12:56:29.55667Z","steps":["trace[294388933] 'range keys from in-memory index tree' (duration: 828.085262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:29.556706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:28.728465Z","time spent":"828.236016ms","remote":"127.0.0.1:44366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T12:56:29.556721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:28.729762Z","time spent":"826.944694ms","remote":"127.0.0.1:44396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.19.213.75\" "} {"level":"warn","ts":"2026-05-09T12:56:29.556744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.763671ms","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-05-09T12:56:29.556794Z","caller":"traceutil/trace.go:171","msg":"trace[208458915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:630; }","duration":"897.847954ms","start":"2026-05-09T12:56:28.658932Z","end":"2026-05-09T12:56:29.55678Z","steps":["trace[208458915] 'range keys from in-memory index tree' (duration: 897.648648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:29.556828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:28.658916Z","time spent":"897.904865ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T12:56:29.557289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.167455ms","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-05-09T12:56:29.557409Z","caller":"traceutil/trace.go:171","msg":"trace[2074855029] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"743.340808ms","start":"2026-05-09T12:56:28.814049Z","end":"2026-05-09T12:56:29.55739Z","steps":["trace[2074855029] 'range keys from in-memory index tree' (duration: 742.344635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:29.557453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:28.814028Z","time spent":"743.415161ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-09T12:56:36.10299Z","caller":"traceutil/trace.go:171","msg":"trace[1031316608] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"428.765191ms","start":"2026-05-09T12:56:35.674199Z","end":"2026-05-09T12:56:36.102964Z","steps":["trace[1031316608] 'read index received' (duration: 428.577246ms)","trace[1031316608] 'applied index is now lower than readState.Index' (duration: 186.875µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:56:36.103098Z","caller":"traceutil/trace.go:171","msg":"trace[510560018] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"437.143299ms","start":"2026-05-09T12:56:35.6659Z","end":"2026-05-09T12:56:36.103043Z","steps":["trace[510560018] 'process raft request' (duration: 436.909353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:36.103323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.056724ms","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-05-09T12:56:36.103339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:35.665876Z","time spent":"437.340194ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:56:36.103382Z","caller":"traceutil/trace.go:171","msg":"trace[1187921014] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"428.146366ms","start":"2026-05-09T12:56:35.675219Z","end":"2026-05-09T12:56:36.103365Z","steps":["trace[1187921014] 'agreement among raft nodes before linearized reading' (duration: 427.957551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:36.103424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:35.675211Z","time spent":"428.203128ms","remote":"127.0.0.1:44646","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-05-09T12:56:36.103591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.296944ms","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-05-09T12:56:36.103712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.434283ms","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-05-09T12:56:36.1038Z","caller":"traceutil/trace.go:171","msg":"trace[306765309] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"429.597222ms","start":"2026-05-09T12:56:35.674174Z","end":"2026-05-09T12:56:36.103771Z","steps":["trace[306765309] 'agreement among raft nodes before linearized reading' (duration: 428.945816ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:36.103913Z","caller":"traceutil/trace.go:171","msg":"trace[1986558050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"428.632858ms","start":"2026-05-09T12:56:35.675215Z","end":"2026-05-09T12:56:36.103848Z","steps":["trace[1986558050] 'agreement among raft nodes before linearized reading' (duration: 428.036183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:36.103996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:35.675204Z","time spent":"428.777432ms","remote":"127.0.0.1:44646","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-05-09T12:56:36.103903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:35.674163Z","time spent":"429.723585ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T12:56:37.473268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.198779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:56:37.473397Z","caller":"traceutil/trace.go:171","msg":"trace[593689091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"748.336153ms","start":"2026-05-09T12:56:36.725036Z","end":"2026-05-09T12:56:37.473372Z","steps":["trace[593689091] 'range keys from in-memory index tree' (duration: 748.070857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:37.473473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:36.724975Z","time spent":"748.482677ms","remote":"127.0.0.1:44366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T12:56:49.161502Z","caller":"traceutil/trace.go:171","msg":"trace[190393780] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"218.919313ms","start":"2026-05-09T12:56:48.942553Z","end":"2026-05-09T12:56:49.161472Z","steps":["trace[190393780] 'process raft request' (duration: 218.672737ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:49.290128Z","caller":"traceutil/trace.go:171","msg":"trace[1303775501] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"321.049518ms","start":"2026-05-09T12:56:48.969056Z","end":"2026-05-09T12:56:49.290106Z","steps":["trace[1303775501] 'process raft request' (duration: 276.942314ms)","trace[1303775501] 'compare' (duration: 43.963081ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:56:49.2903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:48.96903Z","time spent":"321.15932ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:56:49.50326Z","caller":"traceutil/trace.go:171","msg":"trace[784258772] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"332.302827ms","start":"2026-05-09T12:56:49.170926Z","end":"2026-05-09T12:56:49.503229Z","steps":["trace[784258772] 'process raft request' (duration: 254.335972ms)","trace[784258772] 'compare' (duration: 77.76226ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:56:49.503432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:49.170908Z","time spent":"332.42554ms","remote":"127.0.0.1:44550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4176,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:56:49.503452Z","caller":"traceutil/trace.go:171","msg":"trace[176915914] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:805; }","duration":"183.71102ms","start":"2026-05-09T12:56:49.31971Z","end":"2026-05-09T12:56:49.503421Z","steps":["trace[176915914] 'read index received' (duration: 105.574011ms)","trace[176915914] 'applied index is now lower than readState.Index' (duration: 78.134869ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:56:49.503681Z","caller":"traceutil/trace.go:171","msg":"trace[2118703628] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"328.132725ms","start":"2026-05-09T12:56:49.175511Z","end":"2026-05-09T12:56:49.503644Z","steps":["trace[2118703628] 'process raft request' (duration: 327.654142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:49.50386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:49.175485Z","time spent":"328.281239ms","remote":"127.0.0.1:44876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14465,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:56:49.503711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.065209ms","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-05-09T12:56:49.505542Z","caller":"traceutil/trace.go:171","msg":"trace[1320961391] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:777; }","duration":"184.410717ms","start":"2026-05-09T12:56:49.319616Z","end":"2026-05-09T12:56:49.504027Z","steps":["trace[1320961391] 'agreement among raft nodes before linearized reading' (duration: 183.901035ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:50.261408Z","caller":"traceutil/trace.go:171","msg":"trace[1034700740] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"122.400568ms","start":"2026-05-09T12:56:50.138974Z","end":"2026-05-09T12:56:50.261374Z","steps":["trace[1034700740] 'process raft request' (duration: 105.328724ms)","trace[1034700740] 'compare' (duration: 16.869259ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:56:52.082627Z","caller":"traceutil/trace.go:171","msg":"trace[1877676151] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"143.898671ms","start":"2026-05-09T12:56:51.938695Z","end":"2026-05-09T12:56:52.082594Z","steps":["trace[1877676151] 'process raft request' (duration: 143.748798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:55.456681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150912675s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:56:55.456778Z","caller":"traceutil/trace.go:171","msg":"trace[17993525] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:844; }","duration":"1.154708229s","start":"2026-05-09T12:56:54.302054Z","end":"2026-05-09T12:56:55.456762Z","steps":["trace[17993525] 'read index received' (duration: 3.571519ms)","trace[17993525] 'applied index is now lower than readState.Index' (duration: 1.15113556s)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:56:55.456823Z","caller":"traceutil/trace.go:171","msg":"trace[140320954] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"1.158243137s","start":"2026-05-09T12:56:54.298547Z","end":"2026-05-09T12:56:55.456791Z","steps":["trace[140320954] 'compare' (duration: 1.150083113s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:55.456915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:54.29853Z","time spent":"1.15833981s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:56:55.456965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154710299s","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-05-09T12:56:55.456978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.620709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:56:55.456995Z","caller":"traceutil/trace.go:171","msg":"trace[371138559] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:813; }","duration":"1.154739729s","start":"2026-05-09T12:56:54.302244Z","end":"2026-05-09T12:56:55.456984Z","steps":["trace[371138559] 'agreement among raft nodes before linearized reading' (duration: 1.154600496s)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:55.457022Z","caller":"traceutil/trace.go:171","msg":"trace[1656799972] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:813; }","duration":"121.709561ms","start":"2026-05-09T12:56:55.3353Z","end":"2026-05-09T12:56:55.457009Z","steps":["trace[1656799972] 'agreement among raft nodes before linearized reading' (duration: 121.626489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:55.457088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000779378s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wzgd7\" ","response":"range_response_count:1 size:3964"} {"level":"warn","ts":"2026-05-09T12:56:55.457093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.153921139s","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-05-09T12:56:55.45712Z","caller":"traceutil/trace.go:171","msg":"trace[1374900652] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wzgd7; range_end:; response_count:1; response_revision:813; }","duration":"1.000833749s","start":"2026-05-09T12:56:54.456273Z","end":"2026-05-09T12:56:55.457107Z","steps":["trace[1374900652] 'agreement among raft nodes before linearized reading' (duration: 1.000740357s)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:55.457121Z","caller":"traceutil/trace.go:171","msg":"trace[890354953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:813; }","duration":"1.153971431s","start":"2026-05-09T12:56:54.303142Z","end":"2026-05-09T12:56:55.457114Z","steps":["trace[890354953] 'agreement among raft nodes before linearized reading' (duration: 1.153900019s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:55.457149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:54.303131Z","time spent":"1.154012881s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T12:56:55.457149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:54.456258Z","time spent":"1.00088435s","remote":"127.0.0.1:44562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3986,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wzgd7\" "} {"level":"warn","ts":"2026-05-09T12:56:55.457125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.155017406s","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-05-09T12:56:55.457026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:54.302198Z","time spent":"1.154821571s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-09T12:56:55.458292Z","caller":"traceutil/trace.go:171","msg":"trace[853288193] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:813; }","duration":"1.15516391s","start":"2026-05-09T12:56:54.302049Z","end":"2026-05-09T12:56:55.457213Z","steps":["trace[853288193] 'agreement among raft nodes before linearized reading' (duration: 1.154785431s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:55.462624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:54.301994Z","time spent":"1.160603826s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-09T12:56:55.578031Z","caller":"traceutil/trace.go:171","msg":"trace[904416502] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"113.262051ms","start":"2026-05-09T12:56:55.464743Z","end":"2026-05-09T12:56:55.578005Z","steps":["trace[904416502] 'process raft request' (duration: 108.128083ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:55.580599Z","caller":"traceutil/trace.go:171","msg":"trace[1535266523] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"115.389744ms","start":"2026-05-09T12:56:55.465185Z","end":"2026-05-09T12:56:55.580575Z","steps":["trace[1535266523] 'process raft request' (duration: 115.219019ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:55.58091Z","caller":"traceutil/trace.go:171","msg":"trace[800733266] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"114.966803ms","start":"2026-05-09T12:56:55.465913Z","end":"2026-05-09T12:56:55.58088Z","steps":["trace[800733266] 'process raft request' (duration: 114.626065ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:55.581272Z","caller":"traceutil/trace.go:171","msg":"trace[1736932787] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"115.151207ms","start":"2026-05-09T12:56:55.466075Z","end":"2026-05-09T12:56:55.581227Z","steps":["trace[1736932787] 'process raft request' (duration: 114.691996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:00.414386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.986708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050256"} {"level":"info","ts":"2026-05-09T12:57:00.414466Z","caller":"traceutil/trace.go:171","msg":"trace[587393982] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:866; }","duration":"130.119491ms","start":"2026-05-09T12:57:00.284328Z","end":"2026-05-09T12:57:00.414448Z","steps":["trace[587393982] 'range keys from in-memory index tree' (duration: 128.934821ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:11.791952Z","caller":"traceutil/trace.go:171","msg":"trace[1697655796] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:994; }","duration":"132.555614ms","start":"2026-05-09T12:57:11.65937Z","end":"2026-05-09T12:57:11.791925Z","steps":["trace[1697655796] 'read index received' (duration: 132.359999ms)","trace[1697655796] 'applied index is now lower than readState.Index' (duration: 194.525µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:57:11.791986Z","caller":"traceutil/trace.go:171","msg":"trace[1980147059] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"134.504723ms","start":"2026-05-09T12:57:11.657459Z","end":"2026-05-09T12:57:11.791964Z","steps":["trace[1980147059] 'process raft request' (duration: 134.275247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:11.792155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.760439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-09T12:57:11.792198Z","caller":"traceutil/trace.go:171","msg":"trace[216869130] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:960; }","duration":"132.841671ms","start":"2026-05-09T12:57:11.659346Z","end":"2026-05-09T12:57:11.792188Z","steps":["trace[216869130] 'agreement among raft nodes before linearized reading' (duration: 132.696107ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:11.801115Z","caller":"traceutil/trace.go:171","msg":"trace[774588438] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"141.363537ms","start":"2026-05-09T12:57:11.65974Z","end":"2026-05-09T12:57:11.801103Z","steps":["trace[774588438] 'process raft request' (duration: 141.258675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:11.80129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.980025ms","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-05-09T12:57:11.801326Z","caller":"traceutil/trace.go:171","msg":"trace[686045211] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:962; }","duration":"135.048257ms","start":"2026-05-09T12:57:11.666268Z","end":"2026-05-09T12:57:11.801316Z","steps":["trace[686045211] 'agreement among raft nodes before linearized reading' (duration: 134.925673ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:20.19131Z","caller":"traceutil/trace.go:171","msg":"trace[1924458598] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"112.078291ms","start":"2026-05-09T12:57:20.079161Z","end":"2026-05-09T12:57:20.191239Z","steps":["trace[1924458598] 'process raft request' (duration: 111.860455ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:25.792152Z","caller":"traceutil/trace.go:171","msg":"trace[363699685] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"137.987202ms","start":"2026-05-09T12:57:25.654142Z","end":"2026-05-09T12:57:25.792129Z","steps":["trace[363699685] 'process raft request' (duration: 137.441448ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:26.161683Z","caller":"traceutil/trace.go:171","msg":"trace[1568494377] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"210.513454ms","start":"2026-05-09T12:57:25.951136Z","end":"2026-05-09T12:57:26.161649Z","steps":["trace[1568494377] 'process raft request' (duration: 121.297436ms)","trace[1568494377] 'compare' (duration: 89.065994ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:57:27.243499Z","caller":"traceutil/trace.go:171","msg":"trace[319144543] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"327.996652ms","start":"2026-05-09T12:57:26.915469Z","end":"2026-05-09T12:57:27.243465Z","steps":["trace[319144543] 'process raft request' (duration: 327.840408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:27.243683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:57:26.915442Z","time spent":"328.150156ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:57:30.979962Z","caller":"traceutil/trace.go:171","msg":"trace[540354872] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1067; }","duration":"254.475395ms","start":"2026-05-09T12:57:30.725461Z","end":"2026-05-09T12:57:30.979936Z","steps":["trace[540354872] 'read index received' (duration: 254.370572ms)","trace[540354872] 'applied index is now lower than readState.Index' (duration: 103.283µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:57:30.980069Z","caller":"traceutil/trace.go:171","msg":"trace[335855230] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"563.223995ms","start":"2026-05-09T12:57:30.41681Z","end":"2026-05-09T12:57:30.980034Z","steps":["trace[335855230] 'process raft request' (duration: 562.977949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:30.980117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.210494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:57:30.980156Z","caller":"traceutil/trace.go:171","msg":"trace[47048944] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1028; }","duration":"103.291196ms","start":"2026-05-09T12:57:30.876851Z","end":"2026-05-09T12:57:30.980142Z","steps":["trace[47048944] 'agreement among raft nodes before linearized reading' (duration: 103.218844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:30.980143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.65304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T12:57:30.980217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:57:30.416789Z","time spent":"563.345409ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:57:30.980237Z","caller":"traceutil/trace.go:171","msg":"trace[1077193831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1028; }","duration":"254.798183ms","start":"2026-05-09T12:57:30.725419Z","end":"2026-05-09T12:57:30.980217Z","steps":["trace[1077193831] 'agreement among raft nodes before linearized reading' (duration: 254.629359ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:31.474018Z","caller":"traceutil/trace.go:171","msg":"trace[1140935606] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"219.77208ms","start":"2026-05-09T12:57:31.254225Z","end":"2026-05-09T12:57:31.473997Z","steps":["trace[1140935606] 'process raft request' (duration: 219.637376ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:32.328919Z","caller":"traceutil/trace.go:171","msg":"trace[942524402] linearizableReadLoop","detail":"{readStateIndex:1070; appliedIndex:1069; }","duration":"302.988604ms","start":"2026-05-09T12:57:32.02591Z","end":"2026-05-09T12:57:32.328898Z","steps":["trace[942524402] 'read index received' (duration: 302.946613ms)","trace[942524402] 'applied index is now lower than readState.Index' (duration: 41.051µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:57:32.329021Z","caller":"traceutil/trace.go:171","msg":"trace[1740295012] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"490.911261ms","start":"2026-05-09T12:57:31.83809Z","end":"2026-05-09T12:57:32.329001Z","steps":["trace[1740295012] 'process raft request' (duration: 490.675325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:32.329132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:57:31.838079Z","time spent":"490.985353ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:57:32.329116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.196389ms","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-05-09T12:57:32.329237Z","caller":"traceutil/trace.go:171","msg":"trace[2037077839] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1030; }","duration":"303.339603ms","start":"2026-05-09T12:57:32.025871Z","end":"2026-05-09T12:57:32.329211Z","steps":["trace[2037077839] 'agreement among raft nodes before linearized reading' (duration: 303.142248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:32.329351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:57:32.025858Z","time spent":"303.444186ms","remote":"127.0.0.1:44646","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-05-09T12:57:32.829891Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228612364283038403,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T12:57:33.330318Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228612364283038403,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T12:57:33.757467Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.314370906s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T12:57:33.832076Z","caller":"traceutil/trace.go:171","msg":"trace[1723423934] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"1.570577616s","start":"2026-05-09T12:57:32.261471Z","end":"2026-05-09T12:57:33.832048Z","steps":["trace[1723423934] 'process raft request' (duration: 1.496178238s)","trace[1723423934] 'compare' (duration: 74.218094ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:57:33.832221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:57:32.261444Z","time spent":"1.5707048s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:57:34.691774Z","caller":"traceutil/trace.go:171","msg":"trace[778752895] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"1.213034411s","start":"2026-05-09T12:57:33.478717Z","end":"2026-05-09T12:57:34.691751Z","steps":["trace[778752895] 'process raft request' (duration: 1.21298412s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:34.691863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.510909738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T12:57:34.691879Z","caller":"traceutil/trace.go:171","msg":"trace[1008068852] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"2.358979259s","start":"2026-05-09T12:57:32.33288Z","end":"2026-05-09T12:57:34.691859Z","steps":["trace[1008068852] 'process raft request' (duration: 2.358706142s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:34.691898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:57:33.478693Z","time spent":"1.213153493s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:57:34.6919Z","caller":"traceutil/trace.go:171","msg":"trace[1374186650] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1033; }","duration":"2.511017891s","start":"2026-05-09T12:57:32.180873Z","end":"2026-05-09T12:57:34.691891Z","steps":["trace[1374186650] 'agreement among raft nodes before linearized reading' (duration: 2.510928578s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:34.691939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:57:32.180839Z","time spent":"2.511091353s","remote":"127.0.0.1:44820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-09T12:57:34.691963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.551826532s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T12:57:34.691983Z","caller":"traceutil/trace.go:171","msg":"trace[2020395658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1033; }","duration":"2.551847503s","start":"2026-05-09T12:57:32.14013Z","end":"2026-05-09T12:57:34.691977Z","steps":["trace[2020395658] 'agreement among raft nodes before linearized reading' (duration: 2.551774021s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:34.691981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:57:32.332863Z","time spent":"2.359064881s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:57:34.691999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:57:32.140086Z","time spent":"2.551907024s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-09T12:57:34.692061Z","caller":"traceutil/trace.go:171","msg":"trace[422661861] linearizableReadLoop","detail":"{readStateIndex:1072; appliedIndex:1070; }","duration":"2.363064623s","start":"2026-05-09T12:57:32.328984Z","end":"2026-05-09T12:57:34.692048Z","steps":["trace[422661861] 'read index received' (duration: 1.428666804s)","trace[422661861] 'applied index is now lower than readState.Index' (duration: 934.07897ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:57:34.692056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.482142434s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-05-09T12:57:34.692128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.62500801s","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-05-09T12:57:34.692145Z","caller":"traceutil/trace.go:171","msg":"trace[1631008401] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1033; }","duration":"2.625026211s","start":"2026-05-09T12:57:32.067114Z","end":"2026-05-09T12:57:34.69214Z","steps":["trace[1631008401] 'agreement among raft nodes before linearized reading' (duration: 2.62498242s)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:34.692138Z","caller":"traceutil/trace.go:171","msg":"trace[456503703] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1033; }","duration":"2.482271247s","start":"2026-05-09T12:57:32.209847Z","end":"2026-05-09T12:57:34.692118Z","steps":["trace[456503703] 'agreement among raft nodes before linearized reading' (duration: 2.48198645s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:34.692158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:57:32.067065Z","time spent":"2.625089712s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T12:57:34.692213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:57:32.209832Z","time spent":"2.482342169s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T12:57:34.701023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.977596265s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:57:34.701071Z","caller":"traceutil/trace.go:171","msg":"trace[1294969040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1034; }","duration":"1.977682208s","start":"2026-05-09T12:57:32.723372Z","end":"2026-05-09T12:57:34.701054Z","steps":["trace[1294969040] 'agreement among raft nodes before linearized reading' (duration: 1.977574235s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:34.701105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:57:32.723338Z","time spent":"1.97776184s","remote":"127.0.0.1:44366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T12:57:34.701348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.288866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T12:57:34.701376Z","caller":"traceutil/trace.go:171","msg":"trace[404782470] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1035; }","duration":"179.362028ms","start":"2026-05-09T12:57:34.522005Z","end":"2026-05-09T12:57:34.701367Z","steps":["trace[404782470] 'agreement among raft nodes before linearized reading' (duration: 179.142863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:34.701577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.327282ms","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-05-09T12:57:34.701647Z","caller":"traceutil/trace.go:171","msg":"trace[2097368260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1036; }","duration":"368.434915ms","start":"2026-05-09T12:57:34.333188Z","end":"2026-05-09T12:57:34.701622Z","steps":["trace[2097368260] 'agreement among raft nodes before linearized reading' (duration: 367.952243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:34.701683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:57:34.333167Z","time spent":"368.509736ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-09T12:57:38.887375Z","caller":"traceutil/trace.go:171","msg":"trace[847631817] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"135.611161ms","start":"2026-05-09T12:57:38.751725Z","end":"2026-05-09T12:57:38.887336Z","steps":["trace[847631817] 'process raft request' (duration: 135.298283ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:38.88742Z","caller":"traceutil/trace.go:171","msg":"trace[270755256] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"135.58193ms","start":"2026-05-09T12:57:38.75181Z","end":"2026-05-09T12:57:38.887392Z","steps":["trace[270755256] 'process raft request' (duration: 135.402975ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:38.887371Z","caller":"traceutil/trace.go:171","msg":"trace[354275367] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"135.509469ms","start":"2026-05-09T12:57:38.751833Z","end":"2026-05-09T12:57:38.887342Z","steps":["trace[354275367] 'process raft request' (duration: 135.430147ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:38.887711Z","caller":"traceutil/trace.go:171","msg":"trace[389461261] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"135.826116ms","start":"2026-05-09T12:57:38.75187Z","end":"2026-05-09T12:57:38.887696Z","steps":["trace[389461261] 'process raft request' (duration: 135.433447ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:38.887721Z","caller":"traceutil/trace.go:171","msg":"trace[1791626051] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"135.700293ms","start":"2026-05-09T12:57:38.751992Z","end":"2026-05-09T12:57:38.887693Z","steps":["trace[1791626051] 'process raft request' (duration: 135.56299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:40.928326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.601088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:57:40.928474Z","caller":"traceutil/trace.go:171","msg":"trace[200581318] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"166.587593ms","start":"2026-05-09T12:57:40.761855Z","end":"2026-05-09T12:57:40.928442Z","steps":["trace[200581318] 'process raft request' (duration: 43.770838ms)","trace[200581318] 'compare' (duration: 122.486896ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:57:41.313427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.273906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:57:41.313716Z","caller":"traceutil/trace.go:171","msg":"trace[212454537] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"378.88606ms","start":"2026-05-09T12:57:40.934811Z","end":"2026-05-09T12:57:41.313697Z","steps":["trace[212454537] 'process raft request' (duration: 378.809488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:41.313801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:57:40.934773Z","time spent":"378.989833ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:57:41.31386Z","caller":"traceutil/trace.go:171","msg":"trace[1731674127] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"380.244136ms","start":"2026-05-09T12:57:40.933591Z","end":"2026-05-09T12:57:41.313835Z","steps":["trace[1731674127] 'process raft request' (duration: 379.945028ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:41.313882Z","caller":"traceutil/trace.go:171","msg":"trace[221216203] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"381.428755ms","start":"2026-05-09T12:57:40.932418Z","end":"2026-05-09T12:57:41.313846Z","steps":["trace[221216203] 'process raft request' (duration: 248.613476ms)","trace[221216203] 'compare' (duration: 132.145072ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:57:41.313915Z","caller":"traceutil/trace.go:171","msg":"trace[1175976063] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"378.48575ms","start":"2026-05-09T12:57:40.93541Z","end":"2026-05-09T12:57:41.313896Z","steps":["trace[1175976063] 'process raft request' (duration: 378.252404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:41.313998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:57:40.933577Z","time spent":"380.338128ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:57:41.314022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:57:40.935389Z","time spent":"378.579222ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:57:41.31404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:57:40.932391Z","time spent":"381.565619ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:57:41.314135Z","caller":"traceutil/trace.go:171","msg":"trace[2080253575] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"245.744002ms","start":"2026-05-09T12:57:41.068385Z","end":"2026-05-09T12:57:41.314129Z","steps":["trace[2080253575] 'process raft request' (duration: 245.456345ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:47.524907Z","caller":"traceutil/trace.go:171","msg":"trace[1874130076] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1115; }","duration":"128.91353ms","start":"2026-05-09T12:57:47.395969Z","end":"2026-05-09T12:57:47.524883Z","steps":["trace[1874130076] 'read index received' (duration: 128.717385ms)","trace[1874130076] 'applied index is now lower than readState.Index' (duration: 195.265µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:57:47.524979Z","caller":"traceutil/trace.go:171","msg":"trace[1497133563] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"152.373179ms","start":"2026-05-09T12:57:47.372571Z","end":"2026-05-09T12:57:47.524944Z","steps":["trace[1497133563] 'process raft request' (duration: 152.185624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:47.525151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.165547ms","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-05-09T12:57:47.525186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.34092ms","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-05-09T12:57:47.525204Z","caller":"traceutil/trace.go:171","msg":"trace[974471110] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1073; }","duration":"129.27291ms","start":"2026-05-09T12:57:47.395917Z","end":"2026-05-09T12:57:47.525189Z","steps":["trace[974471110] 'agreement among raft nodes before linearized reading' (duration: 129.104746ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:47.525236Z","caller":"traceutil/trace.go:171","msg":"trace[1641514062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1073; }","duration":"127.417882ms","start":"2026-05-09T12:57:47.397805Z","end":"2026-05-09T12:57:47.525223Z","steps":["trace[1641514062] 'agreement among raft nodes before linearized reading' (duration: 127.275668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:47.525213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.530475ms","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-05-09T12:57:47.525376Z","caller":"traceutil/trace.go:171","msg":"trace[954138267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1073; }","duration":"127.74065ms","start":"2026-05-09T12:57:47.397622Z","end":"2026-05-09T12:57:47.525362Z","steps":["trace[954138267] 'agreement among raft nodes before linearized reading' (duration: 127.405902ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:55.678091Z","caller":"traceutil/trace.go:171","msg":"trace[475496446] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1144; }","duration":"122.384044ms","start":"2026-05-09T12:57:55.555689Z","end":"2026-05-09T12:57:55.678073Z","steps":["trace[475496446] 'read index received' (duration: 122.272361ms)","trace[475496446] 'applied index is now lower than readState.Index' (duration: 110.893µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:57:55.678169Z","caller":"traceutil/trace.go:171","msg":"trace[1515241174] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"122.585379ms","start":"2026-05-09T12:57:55.55555Z","end":"2026-05-09T12:57:55.678135Z","steps":["trace[1515241174] 'process raft request' (duration: 122.395034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:55.678361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.649861ms","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-05-09T12:57:55.678407Z","caller":"traceutil/trace.go:171","msg":"trace[1228486652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1100; }","duration":"122.727472ms","start":"2026-05-09T12:57:55.555668Z","end":"2026-05-09T12:57:55.678396Z","steps":["trace[1228486652] 'agreement among raft nodes before linearized reading' (duration: 122.498917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:56.07246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.38704ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:57:56.072639Z","caller":"traceutil/trace.go:171","msg":"trace[733302406] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"389.459921ms","start":"2026-05-09T12:57:55.683158Z","end":"2026-05-09T12:57:56.072618Z","steps":["trace[733302406] 'process raft request' (duration: 234.837624ms)","trace[733302406] 'compare' (duration: 154.249847ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:57:56.072717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:57:55.68314Z","time spent":"389.540473ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:57:56.706818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.86093ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:57:56.706982Z","caller":"traceutil/trace.go:171","msg":"trace[951424143] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"1.023742709s","start":"2026-05-09T12:57:55.683223Z","end":"2026-05-09T12:57:56.706966Z","steps":["trace[951424143] 'process raft request' (duration: 892.664693ms)","trace[951424143] 'compare' (duration: 130.676446ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:57:56.707025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:57:55.6832Z","time spent":"1.023805062s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:57:56.707244Z","caller":"traceutil/trace.go:171","msg":"trace[1378614266] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1145; }","duration":"1.012423021s","start":"2026-05-09T12:57:55.694776Z","end":"2026-05-09T12:57:56.707199Z","steps":["trace[1378614266] 'read index received' (duration: 223.251568ms)","trace[1378614266] 'applied index is now lower than readState.Index' (duration: 789.169693ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:57:56.707398Z","caller":"traceutil/trace.go:171","msg":"trace[614416742] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"1.02254738s","start":"2026-05-09T12:57:55.684838Z","end":"2026-05-09T12:57:56.707386Z","steps":["trace[614416742] 'process raft request' (duration: 1.022078138s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:56.707459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:57:55.684824Z","time spent":"1.022609621s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:57:56.707546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01277394s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:57:56.707571Z","caller":"traceutil/trace.go:171","msg":"trace[2117375753] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1103; }","duration":"1.01281036s","start":"2026-05-09T12:57:55.694753Z","end":"2026-05-09T12:57:56.707563Z","steps":["trace[2117375753] 'agreement among raft nodes before linearized reading' (duration: 1.012771899s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:56.707589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:57:55.694745Z","time spent":"1.012839831s","remote":"127.0.0.1:44366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T12:57:56.707654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.531412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T12:57:56.707681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.142083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:57:56.707761Z","caller":"traceutil/trace.go:171","msg":"trace[1902257188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1103; }","duration":"983.295678ms","start":"2026-05-09T12:57:55.72445Z","end":"2026-05-09T12:57:56.707746Z","steps":["trace[1902257188] 'agreement among raft nodes before linearized reading' (duration: 983.116733ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:56.707753Z","caller":"traceutil/trace.go:171","msg":"trace[602484811] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1103; }","duration":"925.700077ms","start":"2026-05-09T12:57:55.78203Z","end":"2026-05-09T12:57:56.70773Z","steps":["trace[602484811] 'agreement among raft nodes before linearized reading' (duration: 925.548903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:56.707804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:57:55.724439Z","time spent":"983.354399ms","remote":"127.0.0.1:44370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T12:57:56.707836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:57:55.782006Z","time spent":"925.795229ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-05-09T12:57:58.722044Z","caller":"traceutil/trace.go:171","msg":"trace[604121928] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"641.261748ms","start":"2026-05-09T12:57:58.080673Z","end":"2026-05-09T12:57:58.721934Z","steps":["trace[604121928] 'process raft request' (duration: 640.985091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:58.723225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:57:58.080635Z","time spent":"641.646307ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:57:58.875456Z","caller":"traceutil/trace.go:171","msg":"trace[580812871] linearizableReadLoop","detail":"{readStateIndex:1154; appliedIndex:1152; }","duration":"139.919582ms","start":"2026-05-09T12:57:58.735505Z","end":"2026-05-09T12:57:58.875425Z","steps":["trace[580812871] 'read index received' (duration: 123.82318ms)","trace[580812871] 'applied index is now lower than readState.Index' (duration: 16.094171ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:57:58.875653Z","caller":"traceutil/trace.go:171","msg":"trace[917130698] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"146.464618ms","start":"2026-05-09T12:57:58.729149Z","end":"2026-05-09T12:57:58.875614Z","steps":["trace[917130698] 'process raft request' (duration: 130.207153ms)","trace[917130698] 'compare' (duration: 15.883736ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:57:58.875706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.173318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.75\" ","response":"range_response_count:1 size:132"} {"level":"info","ts":"2026-05-09T12:57:58.875747Z","caller":"traceutil/trace.go:171","msg":"trace[2110636193] range","detail":"{range_begin:/registry/masterleases/199.19.213.75; range_end:; response_count:1; response_revision:1109; }","duration":"140.26017ms","start":"2026-05-09T12:57:58.735473Z","end":"2026-05-09T12:57:58.875733Z","steps":["trace[2110636193] 'agreement among raft nodes before linearized reading' (duration: 140.071046ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:58.875764Z","caller":"traceutil/trace.go:171","msg":"trace[399544911] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"146.018257ms","start":"2026-05-09T12:57:58.729697Z","end":"2026-05-09T12:57:58.875716Z","steps":["trace[399544911] 'process raft request' (duration: 145.670898ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:00.237244Z","caller":"traceutil/trace.go:171","msg":"trace[630515846] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"441.999492ms","start":"2026-05-09T12:57:59.795167Z","end":"2026-05-09T12:58:00.237166Z","steps":["trace[630515846] 'process raft request' (duration: 441.809777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:00.237458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:57:59.795146Z","time spent":"442.222648ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:58:01.019242Z","caller":"traceutil/trace.go:171","msg":"trace[54585236] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"127.73295ms","start":"2026-05-09T12:58:00.891478Z","end":"2026-05-09T12:58:01.019211Z","steps":["trace[54585236] 'process raft request' (duration: 127.458043ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:01.050568Z","caller":"traceutil/trace.go:171","msg":"trace[1698707721] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"152.816781ms","start":"2026-05-09T12:58:00.897719Z","end":"2026-05-09T12:58:01.050536Z","steps":["trace[1698707721] 'process raft request' (duration: 152.599685ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:02.880145Z","caller":"traceutil/trace.go:171","msg":"trace[1884792408] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"132.855463ms","start":"2026-05-09T12:58:02.747256Z","end":"2026-05-09T12:58:02.880111Z","steps":["trace[1884792408] 'process raft request' (duration: 132.559705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:03.124369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.719057ms","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-05-09T12:58:03.124465Z","caller":"traceutil/trace.go:171","msg":"trace[790605649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1121; }","duration":"100.878701ms","start":"2026-05-09T12:58:03.023565Z","end":"2026-05-09T12:58:03.124444Z","steps":["trace[790605649] 'range keys from in-memory index tree' (duration: 100.392929ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:03.17136Z","caller":"traceutil/trace.go:171","msg":"trace[1780325297] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"114.149553ms","start":"2026-05-09T12:58:03.057186Z","end":"2026-05-09T12:58:03.171336Z","steps":["trace[1780325297] 'process raft request' (duration: 113.987718ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:03.31981Z","caller":"traceutil/trace.go:171","msg":"trace[397174262] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"126.354031ms","start":"2026-05-09T12:58:03.193423Z","end":"2026-05-09T12:58:03.319777Z","steps":["trace[397174262] 'process raft request' (duration: 126.125974ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:13.074653Z","caller":"traceutil/trace.go:171","msg":"trace[1496426929] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"138.256919ms","start":"2026-05-09T12:58:12.93637Z","end":"2026-05-09T12:58:13.074627Z","steps":["trace[1496426929] 'process raft request' (duration: 138.112886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:14.831385Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228612364283038818,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T12:58:15.331742Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228612364283038818,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T12:58:15.832638Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228612364283038818,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T12:58:15.853579Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.71344548s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T12:58:15.855933Z","caller":"traceutil/trace.go:171","msg":"trace[1585369876] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1259; }","duration":"1.525000393s","start":"2026-05-09T12:58:14.33091Z","end":"2026-05-09T12:58:15.85591Z","steps":["trace[1585369876] 'read index received' (duration: 1.52286925s)","trace[1585369876] 'applied index is now lower than readState.Index' (duration: 2.130413ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:58:15.855959Z","caller":"traceutil/trace.go:171","msg":"trace[694760245] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"1.028867188s","start":"2026-05-09T12:58:14.827061Z","end":"2026-05-09T12:58:15.855928Z","steps":["trace[694760245] 'process raft request' (duration: 1.028735135s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:15.856107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:14.827039Z","time spent":"1.028984202s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:58:15.856249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.271109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-09T12:58:15.85625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.559403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T12:58:15.856246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.525304711s","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-05-09T12:58:15.856273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.778841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-09T12:58:15.856309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.981572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:58:15.856301Z","caller":"traceutil/trace.go:171","msg":"trace[1781161689] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1211; }","duration":"566.618984ms","start":"2026-05-09T12:58:15.289666Z","end":"2026-05-09T12:58:15.856285Z","steps":["trace[1781161689] 'agreement among raft nodes before linearized reading' (duration: 566.539072ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:15.856327Z","caller":"traceutil/trace.go:171","msg":"trace[575130265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1211; }","duration":"134.054383ms","start":"2026-05-09T12:58:15.722268Z","end":"2026-05-09T12:58:15.856322Z","steps":["trace[575130265] 'agreement among raft nodes before linearized reading' (duration: 134.028402ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:15.856316Z","caller":"traceutil/trace.go:171","msg":"trace[1210667871] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1211; }","duration":"1.525493705s","start":"2026-05-09T12:58:14.330803Z","end":"2026-05-09T12:58:15.856297Z","steps":["trace[1210667871] 'agreement among raft nodes before linearized reading' (duration: 1.525215398s)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:15.856323Z","caller":"traceutil/trace.go:171","msg":"trace[509641244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1211; }","duration":"608.888344ms","start":"2026-05-09T12:58:15.247421Z","end":"2026-05-09T12:58:15.856309Z","steps":["trace[509641244] 'agreement among raft nodes before linearized reading' (duration: 608.73525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:15.856344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:15.289583Z","time spent":"566.751627ms","remote":"127.0.0.1:36850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-09T12:58:15.856368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:14.330782Z","time spent":"1.525577368s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T12:58:15.856368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:15.247397Z","time spent":"608.963066ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T12:58:15.856413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.350943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:58:15.856443Z","caller":"traceutil/trace.go:171","msg":"trace[1097903850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1211; }","duration":"162.378894ms","start":"2026-05-09T12:58:15.694055Z","end":"2026-05-09T12:58:15.856434Z","steps":["trace[1097903850] 'agreement among raft nodes before linearized reading' (duration: 162.319902ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:15.856279Z","caller":"traceutil/trace.go:171","msg":"trace[2145339640] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1211; }","duration":"488.404212ms","start":"2026-05-09T12:58:15.367867Z","end":"2026-05-09T12:58:15.856271Z","steps":["trace[2145339640] 'agreement among raft nodes before linearized reading' (duration: 488.249858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:15.856531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:15.367846Z","time spent":"488.678989ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T12:58:15.856566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.388557ms","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-05-09T12:58:15.856609Z","caller":"traceutil/trace.go:171","msg":"trace[861420005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1211; }","duration":"615.465089ms","start":"2026-05-09T12:58:15.241131Z","end":"2026-05-09T12:58:15.856596Z","steps":["trace[861420005] 'agreement among raft nodes before linearized reading' (duration: 615.362076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:15.856636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.55654ms","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-05-09T12:58:15.856654Z","caller":"traceutil/trace.go:171","msg":"trace[1194247772] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1211; }","duration":"778.616451ms","start":"2026-05-09T12:58:15.078033Z","end":"2026-05-09T12:58:15.856649Z","steps":["trace[1194247772] 'agreement among raft nodes before linearized reading' (duration: 778.55447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:15.856653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:15.241121Z","time spent":"615.52223ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T12:58:15.856667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:15.078013Z","time spent":"778.650751ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-09T12:58:15.856869Z","caller":"traceutil/trace.go:171","msg":"trace[2063846736] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"643.874801ms","start":"2026-05-09T12:58:15.212094Z","end":"2026-05-09T12:58:15.855969Z","steps":["trace[2063846736] 'process raft request' (duration: 643.788158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:15.857078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:15.212058Z","time spent":"644.908207ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:58:15.858219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02777104s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:58:15.858256Z","caller":"traceutil/trace.go:171","msg":"trace[590054491] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1211; }","duration":"1.029965915s","start":"2026-05-09T12:58:14.82828Z","end":"2026-05-09T12:58:15.858246Z","steps":["trace[590054491] 'agreement among raft nodes before linearized reading' (duration: 1.027786391s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:15.858283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:14.828274Z","time spent":"1.029996015s","remote":"127.0.0.1:54552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-05-09T12:58:28.104072Z","caller":"traceutil/trace.go:171","msg":"trace[1833196999] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1302; }","duration":"145.694615ms","start":"2026-05-09T12:58:27.958349Z","end":"2026-05-09T12:58:28.104043Z","steps":["trace[1833196999] 'read index received' (duration: 145.52778ms)","trace[1833196999] 'applied index is now lower than readState.Index' (duration: 165.715µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:58:28.104135Z","caller":"traceutil/trace.go:171","msg":"trace[1239227712] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"155.600914ms","start":"2026-05-09T12:58:27.948491Z","end":"2026-05-09T12:58:28.104092Z","steps":["trace[1239227712] 'process raft request' (duration: 155.411129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:28.104306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.92767ms","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-05-09T12:58:28.104333Z","caller":"traceutil/trace.go:171","msg":"trace[1810313473] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1250; }","duration":"146.015863ms","start":"2026-05-09T12:58:27.95831Z","end":"2026-05-09T12:58:28.104325Z","steps":["trace[1810313473] 'agreement among raft nodes before linearized reading' (duration: 145.869359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:28.104436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.292613ms","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-05-09T12:58:28.104436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.286389ms","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-05-09T12:58:28.104505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.494184ms","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-05-09T12:58:28.104521Z","caller":"traceutil/trace.go:171","msg":"trace[540193497] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1250; }","duration":"138.394082ms","start":"2026-05-09T12:58:27.966113Z","end":"2026-05-09T12:58:28.104507Z","steps":["trace[540193497] 'agreement among raft nodes before linearized reading' (duration: 138.138856ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:28.104521Z","caller":"traceutil/trace.go:171","msg":"trace[694688147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1250; }","duration":"137.418137ms","start":"2026-05-09T12:58:27.967081Z","end":"2026-05-09T12:58:28.104499Z","steps":["trace[694688147] 'agreement among raft nodes before linearized reading' (duration: 137.173891ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:28.10457Z","caller":"traceutil/trace.go:171","msg":"trace[1616477111] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1250; }","duration":"138.616307ms","start":"2026-05-09T12:58:27.965934Z","end":"2026-05-09T12:58:28.10455Z","steps":["trace[1616477111] 'agreement among raft nodes before linearized reading' (duration: 138.360621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:28.105124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.10773ms","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-05-09T12:58:28.105172Z","caller":"traceutil/trace.go:171","msg":"trace[274524320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1250; }","duration":"139.214152ms","start":"2026-05-09T12:58:27.965945Z","end":"2026-05-09T12:58:28.105159Z","steps":["trace[274524320] 'agreement among raft nodes before linearized reading' (duration: 138.270199ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:31.152492Z","caller":"traceutil/trace.go:171","msg":"trace[327118305] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"332.355287ms","start":"2026-05-09T12:58:30.820108Z","end":"2026-05-09T12:58:31.152464Z","steps":["trace[327118305] 'process raft request' (duration: 332.183332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:31.152693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:30.820055Z","time spent":"332.526631ms","remote":"127.0.0.1:44562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11371,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:58:31.285611Z","caller":"traceutil/trace.go:171","msg":"trace[278225433] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"125.229881ms","start":"2026-05-09T12:58:31.16034Z","end":"2026-05-09T12:58:31.285569Z","steps":["trace[278225433] 'process raft request' (duration: 120.293128ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:31.312628Z","caller":"traceutil/trace.go:171","msg":"trace[2115425978] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"152.098095ms","start":"2026-05-09T12:58:31.160501Z","end":"2026-05-09T12:58:31.312599Z","steps":["trace[2115425978] 'process raft request' (duration: 151.399308ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:31.313593Z","caller":"traceutil/trace.go:171","msg":"trace[1093597661] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"148.408473ms","start":"2026-05-09T12:58:31.165084Z","end":"2026-05-09T12:58:31.313492Z","steps":["trace[1093597661] 'process raft request' (duration: 147.005298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:32.289569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.457064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:58:32.289674Z","caller":"traceutil/trace.go:171","msg":"trace[758804264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1282; }","duration":"563.609558ms","start":"2026-05-09T12:58:31.726039Z","end":"2026-05-09T12:58:32.289648Z","steps":["trace[758804264] 'range keys from in-memory index tree' (duration: 563.28812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:32.289734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:31.726016Z","time spent":"563.7086ms","remote":"127.0.0.1:44366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T12:58:32.430894Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228612364283038982,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T12:58:32.931287Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228612364283038982,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T12:58:33.097305Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.249933624s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T12:58:33.09795Z","caller":"traceutil/trace.go:171","msg":"trace[1083806014] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"1.250651451s","start":"2026-05-09T12:58:31.847263Z","end":"2026-05-09T12:58:33.097915Z","steps":["trace[1083806014] 'process raft request' (duration: 1.250377064s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:33.098153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:31.847243Z","time spent":"1.250801405s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:58:34.291772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000420198s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-09T12:58:34.291907Z","caller":"traceutil/trace.go:171","msg":"trace[1657209620] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000611064s","start":"2026-05-09T12:58:32.291271Z","end":"2026-05-09T12:58:34.291882Z","steps":["trace[1657209620] 'agreement among raft nodes before linearized reading' (duration: 2.000444479s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:34.291965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:32.29125Z","time spent":"2.000704956s","remote":"127.0.0.1:44370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/09 12:58:34 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-09T12:58:34.331142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.986082ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:58:34.331276Z","caller":"traceutil/trace.go:171","msg":"trace[1049895308] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1336; }","duration":"2.400702239s","start":"2026-05-09T12:58:31.930554Z","end":"2026-05-09T12:58:34.331256Z","steps":["trace[1049895308] 'read index received' (duration: 1.167042273s)","trace[1049895308] 'applied index is now lower than readState.Index' (duration: 1.233658296s)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:58:34.331521Z","caller":"traceutil/trace.go:171","msg":"trace[1747964712] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"2.21135211s","start":"2026-05-09T12:58:32.120152Z","end":"2026-05-09T12:58:34.331504Z","steps":["trace[1747964712] 'process raft request' (duration: 1.458832323s)","trace[1747964712] 'compare' (duration: 751.49195ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:58:34.331587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:32.120118Z","time spent":"2.211433632s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:58:34.331654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.401079599s","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-05-09T12:58:34.331775Z","caller":"traceutil/trace.go:171","msg":"trace[1331716220] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1284; }","duration":"2.401266193s","start":"2026-05-09T12:58:31.930486Z","end":"2026-05-09T12:58:34.331752Z","steps":["trace[1331716220] 'agreement among raft nodes before linearized reading' (duration: 2.401068968s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:34.331828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:31.930461Z","time spent":"2.401353816s","remote":"127.0.0.1:44462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-05-09T12:58:34.394828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.193121ms","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-05-09T12:58:34.39496Z","caller":"traceutil/trace.go:171","msg":"trace[1457441621] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1285; }","duration":"487.389566ms","start":"2026-05-09T12:58:33.907538Z","end":"2026-05-09T12:58:34.394928Z","steps":["trace[1457441621] 'agreement among raft nodes before linearized reading' (duration: 487.13142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:34.395072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:33.907519Z","time spent":"487.537969ms","remote":"127.0.0.1:44590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-05-09T12:58:34.395334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"999.392088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T12:58:34.395457Z","caller":"traceutil/trace.go:171","msg":"trace[752719581] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1285; }","duration":"999.594152ms","start":"2026-05-09T12:58:33.395837Z","end":"2026-05-09T12:58:34.395431Z","steps":["trace[752719581] 'agreement among raft nodes before linearized reading' (duration: 999.07247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:34.395526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:33.395816Z","time spent":"999.696356ms","remote":"127.0.0.1:44446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":135,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-05-09T12:58:34.395607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.773949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:58:34.395662Z","caller":"traceutil/trace.go:171","msg":"trace[1612323758] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1285; }","duration":"228.870681ms","start":"2026-05-09T12:58:34.166778Z","end":"2026-05-09T12:58:34.395648Z","steps":["trace[1612323758] 'agreement among raft nodes before linearized reading' (duration: 228.796889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:34.394878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.213224366s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-09T12:58:34.395968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.204555699s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-05-09T12:58:34.395963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.205354699s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-09T12:58:34.396019Z","caller":"traceutil/trace.go:171","msg":"trace[766251759] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1285; }","duration":"2.204664412s","start":"2026-05-09T12:58:32.191342Z","end":"2026-05-09T12:58:34.396007Z","steps":["trace[766251759] 'agreement among raft nodes before linearized reading' (duration: 2.204524188s)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:34.396032Z","caller":"traceutil/trace.go:171","msg":"trace[285024651] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1285; }","duration":"2.205469043s","start":"2026-05-09T12:58:32.190549Z","end":"2026-05-09T12:58:34.396018Z","steps":["trace[285024651] 'agreement among raft nodes before linearized reading' (duration: 2.205221737s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:34.396059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:32.191308Z","time spent":"2.204742714s","remote":"127.0.0.1:44646","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":"info","ts":"2026-05-09T12:58:34.39595Z","caller":"traceutil/trace.go:171","msg":"trace[394834090] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1285; }","duration":"2.214307964s","start":"2026-05-09T12:58:32.181593Z","end":"2026-05-09T12:58:34.395901Z","steps":["trace[394834090] 'agreement among raft nodes before linearized reading' (duration: 2.213073953s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:34.396066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:32.19052Z","time spent":"2.205537594s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T12:58:34.396113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.203552234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-09T12:58:34.396157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:32.181562Z","time spent":"2.21453102s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T12:58:34.395848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.111333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T12:58:34.396243Z","caller":"traceutil/trace.go:171","msg":"trace[1003074200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1285; }","duration":"2.203715638s","start":"2026-05-09T12:58:32.192485Z","end":"2026-05-09T12:58:34.396201Z","steps":["trace[1003074200] 'agreement among raft nodes before linearized reading' (duration: 2.203253747s)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:34.396259Z","caller":"traceutil/trace.go:171","msg":"trace[378372781] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1285; }","duration":"431.559155ms","start":"2026-05-09T12:58:33.964688Z","end":"2026-05-09T12:58:34.396247Z","steps":["trace[378372781] 'agreement among raft nodes before linearized reading' (duration: 431.123214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:34.3963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:33.964657Z","time spent":"431.630417ms","remote":"127.0.0.1:44562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-05-09T12:58:34.396299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:32.192472Z","time spent":"2.203816761s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T12:58:34.396404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.20376943s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T12:58:34.396463Z","caller":"traceutil/trace.go:171","msg":"trace[1463022030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1285; }","duration":"2.203854501s","start":"2026-05-09T12:58:32.192591Z","end":"2026-05-09T12:58:34.396445Z","steps":["trace[1463022030] 'agreement among raft nodes before linearized reading' (duration: 2.203670527s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:34.396498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:32.192582Z","time spent":"2.203907993s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T12:58:34.542606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.050746ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:58:34.542731Z","caller":"traceutil/trace.go:171","msg":"trace[1034374001] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"140.911604ms","start":"2026-05-09T12:58:34.4018Z","end":"2026-05-09T12:58:34.542712Z","steps":["trace[1034374001] 'process raft request' (duration: 29.655673ms)","trace[1034374001] 'compare' (duration: 110.907813ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:58:34.63469Z","caller":"traceutil/trace.go:171","msg":"trace[1470319500] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"231.160779ms","start":"2026-05-09T12:58:34.403504Z","end":"2026-05-09T12:58:34.634665Z","steps":["trace[1470319500] 'process raft request' (duration: 231.090177ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:34.634766Z","caller":"traceutil/trace.go:171","msg":"trace[834706953] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"232.853121ms","start":"2026-05-09T12:58:34.401855Z","end":"2026-05-09T12:58:34.634708Z","steps":["trace[834706953] 'process raft request' (duration: 232.591415ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:34.635067Z","caller":"traceutil/trace.go:171","msg":"trace[1908320396] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"231.481966ms","start":"2026-05-09T12:58:34.403561Z","end":"2026-05-09T12:58:34.635043Z","steps":["trace[1908320396] 'process raft request' (duration: 231.063326ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:34.635141Z","caller":"traceutil/trace.go:171","msg":"trace[232871782] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"231.421946ms","start":"2026-05-09T12:58:34.403707Z","end":"2026-05-09T12:58:34.635129Z","steps":["trace[232871782] 'process raft request' (duration: 231.297923ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:36.321543Z","caller":"traceutil/trace.go:171","msg":"trace[663968754] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"379.001307ms","start":"2026-05-09T12:58:35.942513Z","end":"2026-05-09T12:58:36.321515Z","steps":["trace[663968754] 'process raft request' (duration: 378.837873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:36.32171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:35.942479Z","time spent":"379.14147ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:58:37.048687Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228612364283038998,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T12:58:37.549642Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228612364283038998,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T12:58:37.871848Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.534652475s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T12:58:37.872355Z","caller":"traceutil/trace.go:171","msg":"trace[1737249528] linearizableReadLoop","detail":"{readStateIndex:1348; appliedIndex:1347; }","duration":"1.324677518s","start":"2026-05-09T12:58:36.547651Z","end":"2026-05-09T12:58:37.872329Z","steps":["trace[1737249528] 'read index received' (duration: 1.324324668s)","trace[1737249528] 'applied index is now lower than readState.Index' (duration: 351.41µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:58:37.872508Z","caller":"traceutil/trace.go:171","msg":"trace[141784138] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"1.535434105s","start":"2026-05-09T12:58:36.337061Z","end":"2026-05-09T12:58:37.872495Z","steps":["trace[141784138] 'process raft request' (duration: 1.535049816s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:37.872637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:36.337028Z","time spent":"1.535518337s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:58:37.87273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23342736s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-09T12:58:37.872778Z","caller":"traceutil/trace.go:171","msg":"trace[708669617] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1293; }","duration":"1.233524283s","start":"2026-05-09T12:58:36.639234Z","end":"2026-05-09T12:58:37.872758Z","steps":["trace[708669617] 'agreement among raft nodes before linearized reading' (duration: 1.233349078s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:37.872812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:36.639211Z","time spent":"1.233594674s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T12:58:37.872906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147086944s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:58:37.873036Z","caller":"traceutil/trace.go:171","msg":"trace[1077374956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1293; }","duration":"1.147267708s","start":"2026-05-09T12:58:36.725751Z","end":"2026-05-09T12:58:37.873019Z","steps":["trace[1077374956] 'agreement among raft nodes before linearized reading' (duration: 1.147100144s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:37.873056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233707647s","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-05-09T12:58:37.873085Z","caller":"traceutil/trace.go:171","msg":"trace[1115974597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1293; }","duration":"1.233789079s","start":"2026-05-09T12:58:36.639288Z","end":"2026-05-09T12:58:37.873077Z","steps":["trace[1115974597] 'agreement among raft nodes before linearized reading' (duration: 1.233717788s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:37.873088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:36.725729Z","time spent":"1.14734983s","remote":"127.0.0.1:44366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T12:58:37.873113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:36.639259Z","time spent":"1.23384962s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T12:58:37.873234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.801788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T12:58:37.873294Z","caller":"traceutil/trace.go:171","msg":"trace[719947405] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1293; }","duration":"568.90644ms","start":"2026-05-09T12:58:37.304368Z","end":"2026-05-09T12:58:37.873275Z","steps":["trace[719947405] 'agreement among raft nodes before linearized reading' (duration: 568.825818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:37.873323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234046895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-09T12:58:37.873317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.354707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T12:58:37.873372Z","caller":"traceutil/trace.go:171","msg":"trace[1971555918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1293; }","duration":"1.234125858s","start":"2026-05-09T12:58:36.639239Z","end":"2026-05-09T12:58:37.873364Z","steps":["trace[1971555918] 'agreement among raft nodes before linearized reading' (duration: 1.234034615s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:37.873378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:37.304344Z","time spent":"568.980413ms","remote":"127.0.0.1:44632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-05-09T12:58:37.873393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:36.639226Z","time spent":"1.234163158s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T12:58:37.872991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233281375s","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-05-09T12:58:37.873467Z","caller":"traceutil/trace.go:171","msg":"trace[755986591] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1293; }","duration":"1.233775588s","start":"2026-05-09T12:58:36.639685Z","end":"2026-05-09T12:58:37.873461Z","steps":["trace[755986591] 'agreement among raft nodes before linearized reading' (duration: 1.233214094s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:37.873486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:36.639677Z","time spent":"1.233803958s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-09T12:58:37.873384Z","caller":"traceutil/trace.go:171","msg":"trace[1777743162] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1293; }","duration":"747.424189ms","start":"2026-05-09T12:58:37.125948Z","end":"2026-05-09T12:58:37.873373Z","steps":["trace[1777743162] 'agreement among raft nodes before linearized reading' (duration: 747.328666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:37.873589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.325919599s","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-05-09T12:58:37.873656Z","caller":"traceutil/trace.go:171","msg":"trace[221485322] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1293; }","duration":"1.326055842s","start":"2026-05-09T12:58:36.547585Z","end":"2026-05-09T12:58:37.873641Z","steps":["trace[221485322] 'agreement among raft nodes before linearized reading' (duration: 1.325810766s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:37.873688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:36.547559Z","time spent":"1.326123694s","remote":"127.0.0.1:44646","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-05-09T12:58:37.873693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:37.125875Z","time spent":"747.667405ms","remote":"127.0.0.1:44782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-05-09T12:58:38.041736Z","caller":"traceutil/trace.go:171","msg":"trace[1098469315] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"161.293635ms","start":"2026-05-09T12:58:37.880403Z","end":"2026-05-09T12:58:38.041696Z","steps":["trace[1098469315] 'process raft request' (duration: 114.021239ms)","trace[1098469315] 'compare' (duration: 47.003599ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:58:38.042068Z","caller":"traceutil/trace.go:171","msg":"trace[1286716658] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1348; }","duration":"157.377887ms","start":"2026-05-09T12:58:37.884664Z","end":"2026-05-09T12:58:38.042041Z","steps":["trace[1286716658] 'read index received' (duration: 35.570412ms)","trace[1286716658] 'applied index is now lower than readState.Index' (duration: 121.805915ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:58:38.042232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.546631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:58:38.042272Z","caller":"traceutil/trace.go:171","msg":"trace[276870456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1298; }","duration":"157.627214ms","start":"2026-05-09T12:58:37.884635Z","end":"2026-05-09T12:58:38.042263Z","steps":["trace[276870456] 'agreement among raft nodes before linearized reading' (duration: 157.504811ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:38.042513Z","caller":"traceutil/trace.go:171","msg":"trace[2074250677] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"162.025474ms","start":"2026-05-09T12:58:37.880473Z","end":"2026-05-09T12:58:38.042498Z","steps":["trace[2074250677] 'process raft request' (duration: 161.150032ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:38.042766Z","caller":"traceutil/trace.go:171","msg":"trace[2010231848] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"162.239599ms","start":"2026-05-09T12:58:37.880513Z","end":"2026-05-09T12:58:38.042753Z","steps":["trace[2010231848] 'process raft request' (duration: 161.376478ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:38.042831Z","caller":"traceutil/trace.go:171","msg":"trace[956924491] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"162.232979ms","start":"2026-05-09T12:58:37.880593Z","end":"2026-05-09T12:58:38.042826Z","steps":["trace[956924491] 'process raft request' (duration: 161.400258ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:38.466925Z","caller":"traceutil/trace.go:171","msg":"trace[287561161] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"279.847309ms","start":"2026-05-09T12:58:38.187034Z","end":"2026-05-09T12:58:38.466882Z","steps":["trace[287561161] 'process raft request' (duration: 279.543702ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:38.70858Z","caller":"traceutil/trace.go:171","msg":"trace[1789825361] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"520.722152ms","start":"2026-05-09T12:58:38.187827Z","end":"2026-05-09T12:58:38.70855Z","steps":["trace[1789825361] 'process raft request' (duration: 501.48645ms)","trace[1789825361] 'compare' (duration: 19.068538ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:58:38.708959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:38.18781Z","time spent":"520.854015ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:58:47.745951Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.039064644s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-09T12:58:47.899585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.731461202s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:58:47.899809Z","caller":"traceutil/trace.go:171","msg":"trace[1458165817] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"1.921601892s","start":"2026-05-09T12:58:45.978166Z","end":"2026-05-09T12:58:47.899768Z","steps":["trace[1458165817] 'process raft request' (duration: 189.827932ms)","trace[1458165817] 'compare' (duration: 1.730895388s)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:58:47.899897Z","caller":"traceutil/trace.go:171","msg":"trace[1918625612] linearizableReadLoop","detail":"{readStateIndex:1417; appliedIndex:1416; }","duration":"1.88369657s","start":"2026-05-09T12:58:46.016191Z","end":"2026-05-09T12:58:47.899888Z","steps":["trace[1918625612] 'read index received' (duration: 151.794777ms)","trace[1918625612] 'applied index is now lower than readState.Index' (duration: 1.731900883s)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:58:47.90001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:45.978131Z","time spent":"1.921768666s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:58:47.899858Z","caller":"traceutil/trace.go:171","msg":"trace[304283513] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"1.317363445s","start":"2026-05-09T12:58:46.582472Z","end":"2026-05-09T12:58:47.899835Z","steps":["trace[304283513] 'process raft request' (duration: 1.317246762s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:47.900185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.819401558s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-09T12:58:47.900268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:46.582442Z","time spent":"1.317792796s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:58:47.900273Z","caller":"traceutil/trace.go:171","msg":"trace[425109139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1361; }","duration":"1.819528961s","start":"2026-05-09T12:58:46.080721Z","end":"2026-05-09T12:58:47.90025Z","steps":["trace[425109139] 'agreement among raft nodes before linearized reading' (duration: 1.819283385s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:47.90025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.175626269s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T12:58:47.900318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:46.080706Z","time spent":"1.819603812s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-09T12:58:47.900358Z","caller":"traceutil/trace.go:171","msg":"trace[1383250400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1361; }","duration":"1.175794723s","start":"2026-05-09T12:58:46.724536Z","end":"2026-05-09T12:58:47.90033Z","steps":["trace[1383250400] 'agreement among raft nodes before linearized reading' (duration: 1.175604628s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:47.900427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:46.72451Z","time spent":"1.175902726s","remote":"127.0.0.1:44366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T12:58:47.900462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.432906913s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T12:58:47.900447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.436341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-05-09T12:58:47.900491Z","caller":"traceutil/trace.go:171","msg":"trace[828375260] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1361; }","duration":"1.432970745s","start":"2026-05-09T12:58:46.467511Z","end":"2026-05-09T12:58:47.900482Z","steps":["trace[828375260] 'agreement among raft nodes before linearized reading' (duration: 1.432916633s)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:47.900511Z","caller":"traceutil/trace.go:171","msg":"trace[636720387] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1361; }","duration":"726.527734ms","start":"2026-05-09T12:58:47.173965Z","end":"2026-05-09T12:58:47.900493Z","steps":["trace[636720387] 'agreement among raft nodes before linearized reading' (duration: 726.315509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:47.900523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:46.467478Z","time spent":"1.433034716s","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-09T12:58:47.900547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:47.17395Z","time spent":"726.588345ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-09T12:58:47.900768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.500859617s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-05-09T12:58:47.900781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.044533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-09T12:58:47.900802Z","caller":"traceutil/trace.go:171","msg":"trace[454164512] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1361; }","duration":"1.50100595s","start":"2026-05-09T12:58:46.399787Z","end":"2026-05-09T12:58:47.900793Z","steps":["trace[454164512] 'agreement among raft nodes before linearized reading' (duration: 1.500915258s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:47.900831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:46.399761Z","time spent":"1.501064062s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-05-09T12:58:47.900834Z","caller":"traceutil/trace.go:171","msg":"trace[472942723] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1361; }","duration":"850.117564ms","start":"2026-05-09T12:58:47.050701Z","end":"2026-05-09T12:58:47.900819Z","steps":["trace[472942723] 'agreement among raft nodes before linearized reading' (duration: 850.012971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:47.900876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:47.050691Z","time spent":"850.174435ms","remote":"127.0.0.1:44430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":140,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-05-09T12:58:47.900889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.884702025s","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-05-09T12:58:47.900951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.813562042s","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-05-09T12:58:47.900949Z","caller":"traceutil/trace.go:171","msg":"trace[467714538] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1361; }","duration":"1.884807829s","start":"2026-05-09T12:58:46.016128Z","end":"2026-05-09T12:58:47.900935Z","steps":["trace[467714538] 'agreement among raft nodes before linearized reading' (duration: 1.884590823s)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:47.90098Z","caller":"traceutil/trace.go:171","msg":"trace[283110655] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1361; }","duration":"1.813601603s","start":"2026-05-09T12:58:46.087367Z","end":"2026-05-09T12:58:47.900968Z","steps":["trace[283110655] 'agreement among raft nodes before linearized reading' (duration: 1.81352089s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:47.900986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:46.016099Z","time spent":"1.884880151s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T12:58:47.901008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:46.087359Z","time spent":"1.813642875s","remote":"127.0.0.1:44646","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-05-09T12:58:47.901115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.819212004s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T12:58:47.90114Z","caller":"traceutil/trace.go:171","msg":"trace[985242570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1361; }","duration":"1.819250225s","start":"2026-05-09T12:58:46.08188Z","end":"2026-05-09T12:58:47.90113Z","steps":["trace[985242570] 'agreement among raft nodes before linearized reading' (duration: 1.819185103s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:47.901164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:46.08187Z","time spent":"1.819288475s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T12:58:47.901423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.819872981s","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-05-09T12:58:47.901461Z","caller":"traceutil/trace.go:171","msg":"trace[544628813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1361; }","duration":"1.819935022s","start":"2026-05-09T12:58:46.081518Z","end":"2026-05-09T12:58:47.901453Z","steps":["trace[544628813] 'agreement among raft nodes before linearized reading' (duration: 1.81984745s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:47.901489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:46.081508Z","time spent":"1.819975282s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T12:58:47.901598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.730491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:30779"} {"level":"info","ts":"2026-05-09T12:58:47.901692Z","caller":"traceutil/trace.go:171","msg":"trace[358190463] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1361; }","duration":"852.889525ms","start":"2026-05-09T12:58:47.048782Z","end":"2026-05-09T12:58:47.901672Z","steps":["trace[358190463] 'agreement among raft nodes before linearized reading' (duration: 852.585348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:47.901738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:47.048757Z","time spent":"852.972617ms","remote":"127.0.0.1:44418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":30801,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-05-09T12:58:48.131665Z","caller":"traceutil/trace.go:171","msg":"trace[63245343] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"225.877046ms","start":"2026-05-09T12:58:47.905752Z","end":"2026-05-09T12:58:48.131629Z","steps":["trace[63245343] 'process raft request' (duration: 220.87303ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:48.134444Z","caller":"traceutil/trace.go:171","msg":"trace[1683812399] linearizableReadLoop","detail":"{readStateIndex:1423; appliedIndex:1418; }","duration":"226.623335ms","start":"2026-05-09T12:58:47.907791Z","end":"2026-05-09T12:58:48.134414Z","steps":["trace[1683812399] 'read index received' (duration: 218.83525ms)","trace[1683812399] 'applied index is now lower than readState.Index' (duration: 7.786275ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:58:48.135002Z","caller":"traceutil/trace.go:171","msg":"trace[1613157675] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"227.777083ms","start":"2026-05-09T12:58:47.907198Z","end":"2026-05-09T12:58:48.134975Z","steps":["trace[1613157675] 'process raft request' (duration: 226.879611ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:48.135058Z","caller":"traceutil/trace.go:171","msg":"trace[1819881450] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"226.975593ms","start":"2026-05-09T12:58:47.908057Z","end":"2026-05-09T12:58:48.135032Z","steps":["trace[1819881450] 'process raft request' (duration: 226.230645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:48.135159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.327302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-05-09T12:58:48.135236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.383453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:30779"} {"level":"info","ts":"2026-05-09T12:58:48.135273Z","caller":"traceutil/trace.go:171","msg":"trace[1702981629] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1368; }","duration":"227.483626ms","start":"2026-05-09T12:58:47.907765Z","end":"2026-05-09T12:58:48.135249Z","steps":["trace[1702981629] 'agreement among raft nodes before linearized reading' (duration: 227.24572ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:48.135203Z","caller":"traceutil/trace.go:171","msg":"trace[1819588300] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"227.422644ms","start":"2026-05-09T12:58:47.907756Z","end":"2026-05-09T12:58:48.135178Z","steps":["trace[1819588300] 'process raft request' (duration: 226.479161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:48.135311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.06971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:58:48.135366Z","caller":"traceutil/trace.go:171","msg":"trace[1495249532] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1368; }","duration":"224.169543ms","start":"2026-05-09T12:58:47.911184Z","end":"2026-05-09T12:58:48.135353Z","steps":["trace[1495249532] 'agreement among raft nodes before linearized reading' (duration: 224.0504ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:48.13529Z","caller":"traceutil/trace.go:171","msg":"trace[978059150] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"228.229294ms","start":"2026-05-09T12:58:47.90704Z","end":"2026-05-09T12:58:48.135269Z","steps":["trace[978059150] 'process raft request' (duration: 226.991634ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:48.135457Z","caller":"traceutil/trace.go:171","msg":"trace[340840912] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"229.072126ms","start":"2026-05-09T12:58:47.906363Z","end":"2026-05-09T12:58:48.135435Z","steps":["trace[340840912] 'process raft request' (duration: 227.539658ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:48.135404Z","caller":"traceutil/trace.go:171","msg":"trace[991827106] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"226.465521ms","start":"2026-05-09T12:58:47.908751Z","end":"2026-05-09T12:58:48.135216Z","steps":["trace[991827106] 'process raft request' (duration: 225.61088ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:48.135301Z","caller":"traceutil/trace.go:171","msg":"trace[404313038] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1368; }","duration":"227.498417ms","start":"2026-05-09T12:58:47.90779Z","end":"2026-05-09T12:58:48.135288Z","steps":["trace[404313038] 'agreement among raft nodes before linearized reading' (duration: 227.262011ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:50.26425Z","caller":"traceutil/trace.go:171","msg":"trace[1079782459] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"116.099842ms","start":"2026-05-09T12:58:50.148116Z","end":"2026-05-09T12:58:50.264216Z","steps":["trace[1079782459] 'process raft request' (duration: 115.871886ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:50.264565Z","caller":"traceutil/trace.go:171","msg":"trace[941869042] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"116.326617ms","start":"2026-05-09T12:58:50.148194Z","end":"2026-05-09T12:58:50.264521Z","steps":["trace[941869042] 'process raft request' (duration: 115.975949ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:50.264625Z","caller":"traceutil/trace.go:171","msg":"trace[330548275] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"116.295417ms","start":"2026-05-09T12:58:50.148297Z","end":"2026-05-09T12:58:50.264592Z","steps":["trace[330548275] 'process raft request' (duration: 116.142213ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:50.264992Z","caller":"traceutil/trace.go:171","msg":"trace[357174536] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"114.688087ms","start":"2026-05-09T12:58:50.150291Z","end":"2026-05-09T12:58:50.264979Z","steps":["trace[357174536] 'process raft request' (duration: 114.600874ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:50.265014Z","caller":"traceutil/trace.go:171","msg":"trace[257792777] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"114.79538ms","start":"2026-05-09T12:58:50.150183Z","end":"2026-05-09T12:58:50.264979Z","steps":["trace[257792777] 'process raft request' (duration: 114.372529ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:52.354302Z","caller":"traceutil/trace.go:171","msg":"trace[474220804] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"167.176443ms","start":"2026-05-09T12:58:52.187098Z","end":"2026-05-09T12:58:52.354274Z","steps":["trace[474220804] 'process raft request' (duration: 166.964797ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:59:26.832436Z","caller":"traceutil/trace.go:171","msg":"trace[896015777] transaction","detail":"{read_only:false; response_revision:1541; number_of_response:1; }","duration":"105.052638ms","start":"2026-05-09T12:59:26.727367Z","end":"2026-05-09T12:59:26.83242Z","steps":["trace[896015777] 'process raft request' (duration: 104.961586ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:59:26.83243Z","caller":"traceutil/trace.go:171","msg":"trace[1364734903] transaction","detail":"{read_only:false; response_revision:1540; number_of_response:1; }","duration":"106.465183ms","start":"2026-05-09T12:59:26.725936Z","end":"2026-05-09T12:59:26.832401Z","steps":["trace[1364734903] 'process raft request' (duration: 77.236737ms)","trace[1364734903] 'compare' (duration: 29.046511ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:59:28.966378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.880121ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:59:28.966601Z","caller":"traceutil/trace.go:171","msg":"trace[1803415735] linearizableReadLoop","detail":"{readStateIndex:1615; appliedIndex:1613; }","duration":"129.708441ms","start":"2026-05-09T12:59:28.836875Z","end":"2026-05-09T12:59:28.966583Z","steps":["trace[1803415735] 'read index received' (duration: 6.456281ms)","trace[1803415735] 'applied index is now lower than readState.Index' (duration: 123.251ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:59:28.966667Z","caller":"traceutil/trace.go:171","msg":"trace[1722000309] transaction","detail":"{read_only:false; response_revision:1549; number_of_response:1; }","duration":"217.600523ms","start":"2026-05-09T12:59:28.749034Z","end":"2026-05-09T12:59:28.966634Z","steps":["trace[1722000309] 'process raft request' (duration: 217.48367ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:59:28.966667Z","caller":"traceutil/trace.go:171","msg":"trace[2125928166] transaction","detail":"{read_only:false; response_revision:1548; number_of_response:1; }","duration":"223.517299ms","start":"2026-05-09T12:59:28.743083Z","end":"2026-05-09T12:59:28.9666Z","steps":["trace[2125928166] 'process raft request' (duration: 100.28816ms)","trace[2125928166] 'compare' (duration: 122.725636ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:59:28.966803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.387668ms","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-05-09T12:59:28.966833Z","caller":"traceutil/trace.go:171","msg":"trace[1783549719] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1549; }","duration":"128.46268ms","start":"2026-05-09T12:59:28.83836Z","end":"2026-05-09T12:59:28.966822Z","steps":["trace[1783549719] 'agreement among raft nodes before linearized reading' (duration: 128.320186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:59:28.966895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.003799ms","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-05-09T12:59:28.966952Z","caller":"traceutil/trace.go:171","msg":"trace[1140023429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1549; }","duration":"130.10535ms","start":"2026-05-09T12:59:28.836829Z","end":"2026-05-09T12:59:28.966935Z","steps":["trace[1140023429] 'agreement among raft nodes before linearized reading' (duration: 129.837344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:59:28.966992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.134601ms","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-05-09T12:59:28.967012Z","caller":"traceutil/trace.go:171","msg":"trace[1720757233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1549; }","duration":"103.180462ms","start":"2026-05-09T12:59:28.863826Z","end":"2026-05-09T12:59:28.967007Z","steps":["trace[1720757233] 'agreement among raft nodes before linearized reading' (duration: 103.121751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:59:37.274827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.458524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:59:37.275051Z","caller":"traceutil/trace.go:171","msg":"trace[605610368] linearizableReadLoop","detail":"{readStateIndex:1654; appliedIndex:1652; }","duration":"160.503195ms","start":"2026-05-09T12:59:37.11453Z","end":"2026-05-09T12:59:37.275033Z","steps":["trace[605610368] 'read index received' (duration: 32.707443ms)","trace[605610368] 'applied index is now lower than readState.Index' (duration: 127.794402ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:59:37.275156Z","caller":"traceutil/trace.go:171","msg":"trace[1349158217] transaction","detail":"{read_only:false; response_revision:1587; number_of_response:1; }","duration":"177.899187ms","start":"2026-05-09T12:59:37.097246Z","end":"2026-05-09T12:59:37.275145Z","steps":["trace[1349158217] 'process raft request' (duration: 177.707782ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:59:37.275195Z","caller":"traceutil/trace.go:171","msg":"trace[1162279998] transaction","detail":"{read_only:false; response_revision:1586; number_of_response:1; }","duration":"178.07362ms","start":"2026-05-09T12:59:37.09709Z","end":"2026-05-09T12:59:37.275163Z","steps":["trace[1162279998] 'process raft request' (duration: 50.180446ms)","trace[1162279998] 'compare' (duration: 127.351671ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:59:37.275473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.988473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-09T12:59:37.275514Z","caller":"traceutil/trace.go:171","msg":"trace[812847118] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1587; }","duration":"158.182378ms","start":"2026-05-09T12:59:37.117321Z","end":"2026-05-09T12:59:37.275504Z","steps":["trace[812847118] 'agreement among raft nodes before linearized reading' (duration: 158.041705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:59:37.275636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.094316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T12:59:37.275659Z","caller":"traceutil/trace.go:171","msg":"trace[1371591592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1587; }","duration":"158.132977ms","start":"2026-05-09T12:59:37.11752Z","end":"2026-05-09T12:59:37.275653Z","steps":["trace[1371591592] 'agreement among raft nodes before linearized reading' (duration: 158.063175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:59:37.275657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.130811ms","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-05-09T12:59:37.275703Z","caller":"traceutil/trace.go:171","msg":"trace[1158333195] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1587; }","duration":"161.199823ms","start":"2026-05-09T12:59:37.114491Z","end":"2026-05-09T12:59:37.275691Z","steps":["trace[1158333195] 'agreement among raft nodes before linearized reading' (duration: 161.08561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:59:37.603142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.466273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:59:37.60356Z","caller":"traceutil/trace.go:171","msg":"trace[576384738] transaction","detail":"{read_only:false; response_revision:1588; number_of_response:1; }","duration":"322.176339ms","start":"2026-05-09T12:59:37.281089Z","end":"2026-05-09T12:59:37.603265Z","steps":["trace[576384738] 'process raft request' (duration: 124.46037ms)","trace[576384738] 'compare' (duration: 197.185675ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:59:37.603784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:59:37.281052Z","time spent":"322.576779ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:59:37.673564Z","caller":"traceutil/trace.go:171","msg":"trace[1559492015] transaction","detail":"{read_only:false; response_revision:1590; number_of_response:1; }","duration":"392.081334ms","start":"2026-05-09T12:59:37.281461Z","end":"2026-05-09T12:59:37.673543Z","steps":["trace[1559492015] 'process raft request' (duration: 392.053124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:59:37.673674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:59:37.28144Z","time spent":"392.176116ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:59:37.673659Z","caller":"traceutil/trace.go:171","msg":"trace[1092633433] transaction","detail":"{read_only:false; response_revision:1589; number_of_response:1; }","duration":"392.173876ms","start":"2026-05-09T12:59:37.281451Z","end":"2026-05-09T12:59:37.673625Z","steps":["trace[1092633433] 'process raft request' (duration: 391.981232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:59:37.673827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:59:37.281419Z","time spent":"392.31328ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T13:00:14.701167Z","caller":"traceutil/trace.go:171","msg":"trace[476023111] transaction","detail":"{read_only:false; response_revision:1758; number_of_response:1; }","duration":"104.910517ms","start":"2026-05-09T13:00:14.596231Z","end":"2026-05-09T13:00:14.701141Z","steps":["trace[476023111] 'process raft request' (duration: 104.754503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:00:25.889703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.702532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T13:00:25.889801Z","caller":"traceutil/trace.go:171","msg":"trace[1778051168] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1809; }","duration":"126.857416ms","start":"2026-05-09T13:00:25.762924Z","end":"2026-05-09T13:00:25.889782Z","steps":["trace[1778051168] 'count revisions from in-memory index tree' (duration: 126.563678ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:00:33.696481Z","caller":"traceutil/trace.go:171","msg":"trace[922618317] linearizableReadLoop","detail":"{readStateIndex:1921; appliedIndex:1920; }","duration":"152.292061ms","start":"2026-05-09T13:00:33.544161Z","end":"2026-05-09T13:00:33.696453Z","steps":["trace[922618317] 'read index received' (duration: 152.177408ms)","trace[922618317] 'applied index is now lower than readState.Index' (duration: 112.873µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:00:33.696512Z","caller":"traceutil/trace.go:171","msg":"trace[518356819] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"164.710916ms","start":"2026-05-09T13:00:33.531771Z","end":"2026-05-09T13:00:33.696482Z","steps":["trace[518356819] 'process raft request' (duration: 164.538082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:00:33.696693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.505456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-09T13:00:33.696754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.988171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-09T13:00:33.696777Z","caller":"traceutil/trace.go:171","msg":"trace[1315365070] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1843; }","duration":"152.622039ms","start":"2026-05-09T13:00:33.54413Z","end":"2026-05-09T13:00:33.696752Z","steps":["trace[1315365070] 'agreement among raft nodes before linearized reading' (duration: 152.458605ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:00:33.696797Z","caller":"traceutil/trace.go:171","msg":"trace[956708338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1843; }","duration":"112.080843ms","start":"2026-05-09T13:00:33.584704Z","end":"2026-05-09T13:00:33.696784Z","steps":["trace[956708338] 'agreement among raft nodes before linearized reading' (duration: 111.905659ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:01:31.559954Z","caller":"traceutil/trace.go:171","msg":"trace[2005031660] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"464.215336ms","start":"2026-05-09T13:01:31.095712Z","end":"2026-05-09T13:01:31.559928Z","steps":["trace[2005031660] 'process raft request' (duration: 464.035461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:01:31.560129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:01:31.095687Z","time spent":"464.360549ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T13:01:31.650292Z","caller":"traceutil/trace.go:171","msg":"trace[1362236151] linearizableReadLoop","detail":"{readStateIndex:2200; appliedIndex:2198; }","duration":"209.062935ms","start":"2026-05-09T13:01:31.441178Z","end":"2026-05-09T13:01:31.650241Z","steps":["trace[1362236151] 'read index received' (duration: 118.612561ms)","trace[1362236151] 'applied index is now lower than readState.Index' (duration: 90.448754ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:01:31.650383Z","caller":"traceutil/trace.go:171","msg":"trace[2060162792] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"240.044492ms","start":"2026-05-09T13:01:31.410296Z","end":"2026-05-09T13:01:31.650341Z","steps":["trace[2060162792] 'process raft request' (duration: 233.592864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:01:31.650539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.31333ms","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:509"} {"level":"info","ts":"2026-05-09T13:01:31.650611Z","caller":"traceutil/trace.go:171","msg":"trace[2034118998] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2110; }","duration":"209.468774ms","start":"2026-05-09T13:01:31.441123Z","end":"2026-05-09T13:01:31.650591Z","steps":["trace[2034118998] 'agreement among raft nodes before linearized reading' (duration: 209.28612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:02:01.948705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.242904ms","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:509"} {"level":"info","ts":"2026-05-09T13:02:01.948786Z","caller":"traceutil/trace.go:171","msg":"trace[27312876] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2271; }","duration":"132.342607ms","start":"2026-05-09T13:02:01.816428Z","end":"2026-05-09T13:02:01.94877Z","steps":["trace[27312876] 'range keys from in-memory index tree' (duration: 132.005669ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:02:02.38941Z","caller":"traceutil/trace.go:171","msg":"trace[331494612] linearizableReadLoop","detail":"{readStateIndex:2369; appliedIndex:2368; }","duration":"125.482049ms","start":"2026-05-09T13:02:02.263904Z","end":"2026-05-09T13:02:02.389386Z","steps":["trace[331494612] 'read index received' (duration: 125.291664ms)","trace[331494612] 'applied index is now lower than readState.Index' (duration: 189.325µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:02:02.389548Z","caller":"traceutil/trace.go:171","msg":"trace[1043339620] transaction","detail":"{read_only:false; response_revision:2272; number_of_response:1; }","duration":"436.617441ms","start":"2026-05-09T13:02:01.95292Z","end":"2026-05-09T13:02:02.389537Z","steps":["trace[1043339620] 'process raft request' (duration: 436.269061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:02:02.389672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:02:01.952896Z","time spent":"436.691433ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T13:02:02.389717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.790957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T13:02:02.389811Z","caller":"traceutil/trace.go:171","msg":"trace[424422983] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:2272; }","duration":"125.936381ms","start":"2026-05-09T13:02:02.263851Z","end":"2026-05-09T13:02:02.389788Z","steps":["trace[424422983] 'agreement among raft nodes before linearized reading' (duration: 125.782727ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:02:02.503718Z","caller":"traceutil/trace.go:171","msg":"trace[837205538] transaction","detail":"{read_only:false; response_revision:2273; number_of_response:1; }","duration":"112.495922ms","start":"2026-05-09T13:02:02.391141Z","end":"2026-05-09T13:02:02.503637Z","steps":["trace[837205538] 'process raft request' (duration: 80.51528ms)","trace[837205538] 'compare' (duration: 31.822138ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:02:02.546524Z","caller":"traceutil/trace.go:171","msg":"trace[1625399748] transaction","detail":"{read_only:false; response_revision:2274; number_of_response:1; }","duration":"151.434945ms","start":"2026-05-09T13:02:02.395045Z","end":"2026-05-09T13:02:02.54648Z","steps":["trace[1625399748] 'process raft request' (duration: 151.244569ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:02:02.54677Z","caller":"traceutil/trace.go:171","msg":"trace[1237386636] transaction","detail":"{read_only:false; response_revision:2275; number_of_response:1; }","duration":"151.554667ms","start":"2026-05-09T13:02:02.395183Z","end":"2026-05-09T13:02:02.546737Z","steps":["trace[1237386636] 'process raft request' (duration: 151.26048ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:02:04.675957Z","caller":"traceutil/trace.go:171","msg":"trace[474587200] transaction","detail":"{read_only:false; response_revision:2283; number_of_response:1; }","duration":"121.857702ms","start":"2026-05-09T13:02:04.554075Z","end":"2026-05-09T13:02:04.675933Z","steps":["trace[474587200] 'process raft request' (duration: 48.214809ms)","trace[474587200] 'compare' (duration: 73.53277ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:02:04.683468Z","caller":"traceutil/trace.go:171","msg":"trace[2076636312] linearizableReadLoop","detail":"{readStateIndex:2382; appliedIndex:2380; }","duration":"102.878396ms","start":"2026-05-09T13:02:04.58057Z","end":"2026-05-09T13:02:04.683448Z","steps":["trace[2076636312] 'read index received' (duration: 21.730441ms)","trace[2076636312] 'applied index is now lower than readState.Index' (duration: 81.146754ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:02:04.683504Z","caller":"traceutil/trace.go:171","msg":"trace[751666846] transaction","detail":"{read_only:false; response_revision:2284; number_of_response:1; }","duration":"129.293902ms","start":"2026-05-09T13:02:04.554184Z","end":"2026-05-09T13:02:04.683478Z","steps":["trace[751666846] 'process raft request' (duration: 129.171099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:02:04.68367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.097691ms","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-05-09T13:02:04.683713Z","caller":"traceutil/trace.go:171","msg":"trace[793282026] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2284; }","duration":"103.201964ms","start":"2026-05-09T13:02:04.580502Z","end":"2026-05-09T13:02:04.683704Z","steps":["trace[793282026] 'agreement among raft nodes before linearized reading' (duration: 103.03364ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:02:11.095633Z","caller":"traceutil/trace.go:171","msg":"trace[680663145] transaction","detail":"{read_only:false; response_revision:2318; number_of_response:1; }","duration":"368.286269ms","start":"2026-05-09T13:02:10.72732Z","end":"2026-05-09T13:02:11.095606Z","steps":["trace[680663145] 'process raft request' (duration: 368.110775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:02:11.095801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:02:10.727305Z","time spent":"368.413632ms","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T13:02:12.004293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.234875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T13:02:12.004389Z","caller":"traceutil/trace.go:171","msg":"trace[406531070] linearizableReadLoop","detail":"{readStateIndex:2418; appliedIndex:2416; }","duration":"1.229823184s","start":"2026-05-09T13:02:10.77455Z","end":"2026-05-09T13:02:12.004374Z","steps":["trace[406531070] 'read index received' (duration: 321.009112ms)","trace[406531070] 'applied index is now lower than readState.Index' (duration: 908.813082ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:02:12.004418Z","caller":"traceutil/trace.go:171","msg":"trace[935383631] transaction","detail":"{read_only:false; response_revision:2319; number_of_response:1; }","duration":"1.274825195s","start":"2026-05-09T13:02:10.729578Z","end":"2026-05-09T13:02:12.004403Z","steps":["trace[935383631] 'process raft request' (duration: 649.373094ms)","trace[935383631] 'compare' (duration: 624.971089ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:02:12.00449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:02:10.72956Z","time spent":"1.274897137s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T13:02:12.004553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.230009489s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-09T13:02:12.004592Z","caller":"traceutil/trace.go:171","msg":"trace[69731960] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2319; }","duration":"1.230085351s","start":"2026-05-09T13:02:10.774498Z","end":"2026-05-09T13:02:12.004583Z","steps":["trace[69731960] 'agreement among raft nodes before linearized reading' (duration: 1.229952778s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:02:12.004625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:02:10.774478Z","time spent":"1.230140082s","remote":"127.0.0.1:44646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-09T13:02:12.0356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.909341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T13:02:12.035656Z","caller":"traceutil/trace.go:171","msg":"trace[1125525434] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2320; }","duration":"310.031784ms","start":"2026-05-09T13:02:11.725612Z","end":"2026-05-09T13:02:12.035644Z","steps":["trace[1125525434] 'agreement among raft nodes before linearized reading' (duration: 309.895621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:02:12.035665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.78556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-09T13:02:12.035794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.216501498s","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-05-09T13:02:12.035825Z","caller":"traceutil/trace.go:171","msg":"trace[1725010443] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2320; }","duration":"110.977275ms","start":"2026-05-09T13:02:11.924836Z","end":"2026-05-09T13:02:12.035814Z","steps":["trace[1725010443] 'agreement among raft nodes before linearized reading' (duration: 110.77301ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:02:12.035861Z","caller":"traceutil/trace.go:171","msg":"trace[2006770684] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2320; }","duration":"1.216581801s","start":"2026-05-09T13:02:10.819257Z","end":"2026-05-09T13:02:12.035838Z","steps":["trace[2006770684] 'agreement among raft nodes before linearized reading' (duration: 1.216434287s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:02:12.035951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:02:10.81925Z","time spent":"1.216685813s","remote":"127.0.0.1:44646","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-05-09T13:02:12.035671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014835585s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T13:02:12.036162Z","caller":"traceutil/trace.go:171","msg":"trace[1923195602] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2320; }","duration":"1.015372818s","start":"2026-05-09T13:02:11.020781Z","end":"2026-05-09T13:02:12.036154Z","steps":["trace[1923195602] 'agreement among raft nodes before linearized reading' (duration: 1.014859266s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:02:12.036253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:02:11.020755Z","time spent":"1.01548634s","remote":"127.0.0.1:44522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-05-09T13:02:12.035693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:02:11.725587Z","time spent":"310.097196ms","remote":"127.0.0.1:44366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T13:02:12.035576Z","caller":"traceutil/trace.go:171","msg":"trace[84845351] transaction","detail":"{read_only:false; response_revision:2320; number_of_response:1; }","duration":"264.812039ms","start":"2026-05-09T13:02:11.770733Z","end":"2026-05-09T13:02:12.035545Z","steps":["trace[84845351] 'process raft request' (duration: 264.599884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:02:12.03571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.778254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T13:02:12.036688Z","caller":"traceutil/trace.go:171","msg":"trace[80436466] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:2320; }","duration":"829.789399ms","start":"2026-05-09T13:02:11.206881Z","end":"2026-05-09T13:02:12.03667Z","steps":["trace[80436466] 'agreement among raft nodes before linearized reading' (duration: 828.805705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:02:12.036761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:02:11.206856Z","time spent":"829.88233ms","remote":"127.0.0.1:44622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-05-09T13:02:12.035686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.217109784s","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-05-09T13:02:12.036837Z","caller":"traceutil/trace.go:171","msg":"trace[2008766139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2320; }","duration":"1.218330423s","start":"2026-05-09T13:02:10.818484Z","end":"2026-05-09T13:02:12.036814Z","steps":["trace[2008766139] 'agreement among raft nodes before linearized reading' (duration: 1.217011031s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:02:12.036927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:02:10.818461Z","time spent":"1.218438316s","remote":"127.0.0.1:44646","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-05-09T13:02:12.303328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.21578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T13:02:12.303659Z","caller":"traceutil/trace.go:171","msg":"trace[1467178753] linearizableReadLoop","detail":"{readStateIndex:2420; appliedIndex:2419; }","duration":"265.768681ms","start":"2026-05-09T13:02:12.037869Z","end":"2026-05-09T13:02:12.303638Z","steps":["trace[1467178753] 'read index received' (duration: 163.252133ms)","trace[1467178753] 'applied index is now lower than readState.Index' (duration: 102.514568ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:02:12.303809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.920624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T13:02:12.303794Z","caller":"traceutil/trace.go:171","msg":"trace[1606457262] transaction","detail":"{read_only:false; response_revision:2322; number_of_response:1; }","duration":"263.565717ms","start":"2026-05-09T13:02:12.04019Z","end":"2026-05-09T13:02:12.303756Z","steps":["trace[1606457262] 'process raft request' (duration: 263.346352ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:02:12.303852Z","caller":"traceutil/trace.go:171","msg":"trace[1836877103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2323; }","duration":"266.001847ms","start":"2026-05-09T13:02:12.037837Z","end":"2026-05-09T13:02:12.303839Z","steps":["trace[1836877103] 'agreement among raft nodes before linearized reading' (duration: 265.915575ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:02:12.303682Z","caller":"traceutil/trace.go:171","msg":"trace[1482796394] transaction","detail":"{read_only:false; response_revision:2323; number_of_response:1; }","duration":"263.406804ms","start":"2026-05-09T13:02:12.040254Z","end":"2026-05-09T13:02:12.303661Z","steps":["trace[1482796394] 'process raft request' (duration: 263.361422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:02:12.303976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.000374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T13:02:12.30404Z","caller":"traceutil/trace.go:171","msg":"trace[1969765928] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2323; }","duration":"263.090966ms","start":"2026-05-09T13:02:12.040933Z","end":"2026-05-09T13:02:12.304024Z","steps":["trace[1969765928] 'agreement among raft nodes before linearized reading' (duration: 263.001004ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:02:12.30364Z","caller":"traceutil/trace.go:171","msg":"trace[672560297] transaction","detail":"{read_only:false; response_revision:2321; number_of_response:1; }","duration":"295.601611ms","start":"2026-05-09T13:02:12.008002Z","end":"2026-05-09T13:02:12.303603Z","steps":["trace[672560297] 'process raft request' (duration: 193.025742ms)","trace[672560297] 'compare' (duration: 101.847871ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:02:12.639333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.574963ms","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:509"} {"level":"info","ts":"2026-05-09T13:02:12.639412Z","caller":"traceutil/trace.go:171","msg":"trace[1262575991] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2323; }","duration":"235.715936ms","start":"2026-05-09T13:02:12.403678Z","end":"2026-05-09T13:02:12.639394Z","steps":["trace[1262575991] 'range keys from in-memory index tree' (duration: 235.355438ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:02:12.780458Z","caller":"traceutil/trace.go:171","msg":"trace[1280644294] transaction","detail":"{read_only:false; response_revision:2324; number_of_response:1; }","duration":"136.687584ms","start":"2026-05-09T13:02:12.643736Z","end":"2026-05-09T13:02:12.780423Z","steps":["trace[1280644294] 'process raft request' (duration: 136.491049ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:02:12.899843Z","caller":"traceutil/trace.go:171","msg":"trace[337600362] transaction","detail":"{read_only:false; response_revision:2325; number_of_response:1; }","duration":"114.981552ms","start":"2026-05-09T13:02:12.784835Z","end":"2026-05-09T13:02:12.899816Z","steps":["trace[337600362] 'process raft request' (duration: 114.511221ms)"],"step_count":1}