{"level":"warn","ts":"2026-03-02T16:41:09.655904Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T16:41:09.656016Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.33:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.33:2380","--initial-cluster=instance=https://199.204.45.33:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.33:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.33: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-03-02T16:41:09.656095Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T16:41:09.656108Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-03-02T16:41:09.656138Z","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-03-02T16:41:09.657155Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"]} {"level":"info","ts":"2026-03-02T16:41:09.65727Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.33: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-03-02T16:41:09.661499Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.024335ms"} {"level":"info","ts":"2026-03-02T16:41:09.670685Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e5729930cf7e1f","cluster-id":"14aeb8c3ec66b749"} {"level":"info","ts":"2026-03-02T16:41:09.670764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=()"} {"level":"info","ts":"2026-03-02T16:41:09.670788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 0"} {"level":"info","ts":"2026-03-02T16:41:09.670798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e5729930cf7e1f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-02T16:41:09.670809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 1"} {"level":"info","ts":"2026-03-02T16:41:09.670853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"warn","ts":"2026-03-02T16:41:09.678367Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-02T16:41:09.683669Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-02T16:41:09.686625Z","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-03-02T16:41:09.689473Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e5729930cf7e1f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-02T16:41:09.694819Z","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-03-02T16:41:09.695044Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e5729930cf7e1f","initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-02T16:41:09.695075Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-02T16:41:09.695267Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-03-02T16:41:09.695286Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-03-02T16:41:09.696347Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e5729930cf7e1f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-02T16:41:09.696455Z","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-03-02T16:41:09.696483Z","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-03-02T16:41:09.696493Z","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-03-02T16:41:09.69714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"info","ts":"2026-03-02T16:41:09.697244Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","added-peer-id":"f1e5729930cf7e1f","added-peer-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-03-02T16:41:09.77121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f is starting a new election at term 1"} {"level":"info","ts":"2026-03-02T16:41:09.771354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became pre-candidate at term 1"} {"level":"info","ts":"2026-03-02T16:41:09.771436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgPreVoteResp from f1e5729930cf7e1f at term 1"} {"level":"info","ts":"2026-03-02T16:41:09.771491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became candidate at term 2"} {"level":"info","ts":"2026-03-02T16:41:09.77154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgVoteResp from f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-03-02T16:41:09.771582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became leader at term 2"} {"level":"info","ts":"2026-03-02T16:41:09.771626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e5729930cf7e1f elected leader f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-03-02T16:41:09.783027Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e5729930cf7e1f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.33:2379]}","request-path":"/0/members/f1e5729930cf7e1f/attributes","cluster-id":"14aeb8c3ec66b749","publish-timeout":"7s"} {"level":"info","ts":"2026-03-02T16:41:09.783138Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T16:41:09.783371Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T16:41:09.783041Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T16:41:09.784002Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-02T16:41:09.784159Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-02T16:41:09.784657Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T16:41:09.78498Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T16:41:09.785055Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T16:41:09.787145Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-02T16:41:09.797169Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.33:2379"} {"level":"info","ts":"2026-03-02T16:41:33.244553Z","caller":"traceutil/trace.go:171","msg":"trace[1579924780] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"117.947882ms","start":"2026-03-02T16:41:33.126575Z","end":"2026-03-02T16:41:33.244523Z","steps":["trace[1579924780] 'process raft request' (duration: 117.771657ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:41:38.563735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.475386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T16:41:38.563922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.686525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-03-02T16:41:38.564006Z","caller":"traceutil/trace.go:171","msg":"trace[1012308934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:463; }","duration":"297.829233ms","start":"2026-03-02T16:41:38.266149Z","end":"2026-03-02T16:41:38.563978Z","steps":["trace[1012308934] 'range keys from in-memory index tree' (duration: 297.336752ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:41:38.564053Z","caller":"traceutil/trace.go:171","msg":"trace[2030608714] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:463; }","duration":"373.870229ms","start":"2026-03-02T16:41:38.190153Z","end":"2026-03-02T16:41:38.564023Z","steps":["trace[2030608714] 'range keys from in-memory index tree' (duration: 373.44976ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:41:38.564236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:41:38.190137Z","time spent":"374.083005ms","remote":"127.0.0.1:59078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":561,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-03-02T16:41:38.564071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.918379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-03-02T16:41:38.564849Z","caller":"traceutil/trace.go:171","msg":"trace[1476467893] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:463; }","duration":"392.769269ms","start":"2026-03-02T16:41:38.172057Z","end":"2026-03-02T16:41:38.564827Z","steps":["trace[1476467893] 'range keys from in-memory index tree' (duration: 391.549589ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:41:38.565492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:41:38.172036Z","time spent":"393.422515ms","remote":"127.0.0.1:58976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329975,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-03-02T16:41:38.564066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.527181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-03-02T16:41:38.565902Z","caller":"traceutil/trace.go:171","msg":"trace[431870022] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:463; }","duration":"375.477688ms","start":"2026-03-02T16:41:38.190408Z","end":"2026-03-02T16:41:38.565886Z","steps":["trace[431870022] 'range keys from in-memory index tree' (duration: 373.256915ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:41:38.565941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:41:38.190379Z","time spent":"375.55098ms","remote":"127.0.0.1:59078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":543,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"info","ts":"2026-03-02T16:41:39.857405Z","caller":"traceutil/trace.go:171","msg":"trace[1108676116] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:479; }","duration":"125.403498ms","start":"2026-03-02T16:41:39.73197Z","end":"2026-03-02T16:41:39.857374Z","steps":["trace[1108676116] 'read index received' (duration: 119.868487ms)","trace[1108676116] 'applied index is now lower than readState.Index' (duration: 5.533731ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T16:41:39.857812Z","caller":"traceutil/trace.go:171","msg":"trace[1657784836] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"425.151278ms","start":"2026-03-02T16:41:39.432591Z","end":"2026-03-02T16:41:39.857742Z","steps":["trace[1657784836] 'process raft request' (duration: 419.256318ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:41:39.857914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.92676ms","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-03-02T16:41:39.857959Z","caller":"traceutil/trace.go:171","msg":"trace[634334661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:467; }","duration":"126.023223ms","start":"2026-03-02T16:41:39.731921Z","end":"2026-03-02T16:41:39.857944Z","steps":["trace[634334661] 'agreement among raft nodes before linearized reading' (duration: 125.848849ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:41:39.858648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:41:39.432579Z","time spent":"425.358863ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T16:41:41.792345Z","caller":"traceutil/trace.go:171","msg":"trace[402699424] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"406.052814ms","start":"2026-03-02T16:41:41.386267Z","end":"2026-03-02T16:41:41.792319Z","steps":["trace[402699424] 'process raft request' (duration: 405.87493ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:41:41.792533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:41:41.386237Z","time spent":"406.195278ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T16:41:41.99054Z","caller":"traceutil/trace.go:171","msg":"trace[538408739] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"123.378039ms","start":"2026-03-02T16:41:41.867122Z","end":"2026-03-02T16:41:41.9905Z","steps":["trace[538408739] 'process raft request' (duration: 80.941862ms)","trace[538408739] 'compare' (duration: 42.09315ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T16:41:41.993309Z","caller":"traceutil/trace.go:171","msg":"trace[444891861] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:484; }","duration":"123.284867ms","start":"2026-03-02T16:41:41.869991Z","end":"2026-03-02T16:41:41.993276Z","steps":["trace[444891861] 'read index received' (duration: 78.106255ms)","trace[444891861] 'applied index is now lower than readState.Index' (duration: 45.174352ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T16:41:41.993397Z","caller":"traceutil/trace.go:171","msg":"trace[827249236] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"125.147121ms","start":"2026-03-02T16:41:41.868228Z","end":"2026-03-02T16:41:41.993376Z","steps":["trace[827249236] 'process raft request' (duration: 124.922277ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:41:41.993466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.450451ms","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-03-02T16:41:41.993512Z","caller":"traceutil/trace.go:171","msg":"trace[836462198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:473; }","duration":"123.547364ms","start":"2026-03-02T16:41:41.869954Z","end":"2026-03-02T16:41:41.993502Z","steps":["trace[836462198] 'agreement among raft nodes before linearized reading' (duration: 123.441071ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:41:48.5162Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154850315676239,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-02T16:41:48.939135Z","caller":"traceutil/trace.go:171","msg":"trace[1759139453] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"970.264035ms","start":"2026-03-02T16:41:47.968837Z","end":"2026-03-02T16:41:48.939101Z","steps":["trace[1759139453] 'process raft request' (duration: 970.026571ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:41:48.939334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:41:47.968792Z","time spent":"970.437619ms","remote":"127.0.0.1:59052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T16:41:48.964026Z","caller":"traceutil/trace.go:171","msg":"trace[1042346110] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:503; }","duration":"948.374327ms","start":"2026-03-02T16:41:48.015625Z","end":"2026-03-02T16:41:48.964Z","steps":["trace[1042346110] 'read index received' (duration: 923.194019ms)","trace[1042346110] 'applied index is now lower than readState.Index' (duration: 25.178288ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T16:41:48.964326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.412714ms","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-03-02T16:41:48.964375Z","caller":"traceutil/trace.go:171","msg":"trace[563852168] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:490; }","duration":"942.539887ms","start":"2026-03-02T16:41:48.021822Z","end":"2026-03-02T16:41:48.964362Z","steps":["trace[563852168] 'agreement among raft nodes before linearized reading' (duration: 942.324481ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:41:48.964332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.372912ms","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-03-02T16:41:48.964369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.711904ms","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-03-02T16:41:48.964444Z","caller":"traceutil/trace.go:171","msg":"trace[1713684635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:490; }","duration":"931.525725ms","start":"2026-03-02T16:41:48.032892Z","end":"2026-03-02T16:41:48.964417Z","steps":["trace[1713684635] 'agreement among raft nodes before linearized reading' (duration: 931.297309ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:41:48.964498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:41:48.032869Z","time spent":"931.609918ms","remote":"127.0.0.1:59134","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-03-02T16:41:48.964421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:41:48.02181Z","time spent":"942.601789ms","remote":"127.0.0.1:59134","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-03-02T16:41:48.964502Z","caller":"traceutil/trace.go:171","msg":"trace[1393208400] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:490; }","duration":"948.914028ms","start":"2026-03-02T16:41:48.015561Z","end":"2026-03-02T16:41:48.964475Z","steps":["trace[1393208400] 'agreement among raft nodes before linearized reading' (duration: 948.556241ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:41:48.964597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:41:48.015533Z","time spent":"949.052632ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-02T16:41:54.249396Z","caller":"traceutil/trace.go:171","msg":"trace[1938494668] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"109.005158ms","start":"2026-03-02T16:41:54.140344Z","end":"2026-03-02T16:41:54.249349Z","steps":["trace[1938494668] 'process raft request' (duration: 108.472325ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:42:01.440935Z","caller":"traceutil/trace.go:171","msg":"trace[1306793247] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"174.817122ms","start":"2026-03-02T16:42:01.265959Z","end":"2026-03-02T16:42:01.440776Z","steps":["trace[1306793247] 'read index received' (duration: 173.801428ms)","trace[1306793247] 'applied index is now lower than readState.Index' (duration: 1.013584ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T16:42:01.441089Z","caller":"traceutil/trace.go:171","msg":"trace[1003671612] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"246.418413ms","start":"2026-03-02T16:42:01.194426Z","end":"2026-03-02T16:42:01.440845Z","steps":["trace[1003671612] 'process raft request' (duration: 245.374498ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:42:01.44226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.262747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T16:42:01.442615Z","caller":"traceutil/trace.go:171","msg":"trace[687172922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"176.441181ms","start":"2026-03-02T16:42:01.265906Z","end":"2026-03-02T16:42:01.442347Z","steps":["trace[687172922] 'agreement among raft nodes before linearized reading' (duration: 174.990667ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:42:02.434276Z","caller":"traceutil/trace.go:171","msg":"trace[1589564511] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"292.58274ms","start":"2026-03-02T16:42:02.14166Z","end":"2026-03-02T16:42:02.434243Z","steps":["trace[1589564511] 'process raft request' (duration: 290.643104ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:42:02.437507Z","caller":"traceutil/trace.go:171","msg":"trace[2404466] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"295.269544ms","start":"2026-03-02T16:42:02.142223Z","end":"2026-03-02T16:42:02.437493Z","steps":["trace[2404466] 'process raft request' (duration: 295.059039ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:42:02.437706Z","caller":"traceutil/trace.go:171","msg":"trace[1120224155] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:668; }","duration":"289.67302ms","start":"2026-03-02T16:42:02.147992Z","end":"2026-03-02T16:42:02.437665Z","steps":["trace[1120224155] 'read index received' (duration: 284.328653ms)","trace[1120224155] 'applied index is now lower than readState.Index' (duration: 5.341737ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T16:42:02.437905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.299204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T16:42:02.437943Z","caller":"traceutil/trace.go:171","msg":"trace[1251579344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"170.381186ms","start":"2026-03-02T16:42:02.267548Z","end":"2026-03-02T16:42:02.437929Z","steps":["trace[1251579344] 'agreement among raft nodes before linearized reading' (duration: 170.286344ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:42:02.43795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.933136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4228"} {"level":"info","ts":"2026-03-02T16:42:02.437827Z","caller":"traceutil/trace.go:171","msg":"trace[696178292] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"295.380456ms","start":"2026-03-02T16:42:02.142375Z","end":"2026-03-02T16:42:02.437755Z","steps":["trace[696178292] 'process raft request' (duration: 295.039908ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:42:02.438Z","caller":"traceutil/trace.go:171","msg":"trace[437937061] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:653; }","duration":"290.017539ms","start":"2026-03-02T16:42:02.147967Z","end":"2026-03-02T16:42:02.437985Z","steps":["trace[437937061] 'agreement among raft nodes before linearized reading' (duration: 289.839184ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:42:03.543811Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154850315676550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T16:42:03.818701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13970005s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T16:42:03.818825Z","caller":"traceutil/trace.go:171","msg":"trace[850049090] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"1.347970806s","start":"2026-03-02T16:42:02.470835Z","end":"2026-03-02T16:42:03.818806Z","steps":["trace[850049090] 'process raft request' (duration: 207.872828ms)","trace[850049090] 'compare' (duration: 1.139560706s)"],"step_count":2} {"level":"warn","ts":"2026-03-02T16:42:03.818886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:42:02.470801Z","time spent":"1.348057758s","remote":"127.0.0.1:59324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4204,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T16:42:03.820989Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.060733154s","expected-duration":"1s"} {"level":"info","ts":"2026-03-02T16:42:03.821686Z","caller":"traceutil/trace.go:171","msg":"trace[1555225203] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"1.061522092s","start":"2026-03-02T16:42:02.760129Z","end":"2026-03-02T16:42:03.821651Z","steps":["trace[1555225203] 'process raft request' (duration: 1.061281626s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:42:03.82188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:42:02.760097Z","time spent":"1.061670345s","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T16:42:03.826557Z","caller":"traceutil/trace.go:171","msg":"trace[723918831] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:675; }","duration":"783.115551ms","start":"2026-03-02T16:42:03.043422Z","end":"2026-03-02T16:42:03.826538Z","steps":["trace[723918831] 'read index received' (duration: 778.184554ms)","trace[723918831] 'applied index is now lower than readState.Index' (duration: 4.929487ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T16:42:03.826629Z","caller":"traceutil/trace.go:171","msg":"trace[1535059795] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"717.39121ms","start":"2026-03-02T16:42:03.109206Z","end":"2026-03-02T16:42:03.826597Z","steps":["trace[1535059795] 'process raft request' (duration: 717.219246ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:42:03.826736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:42:03.109163Z","time spent":"717.523903ms","remote":"127.0.0.1:59140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1450,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T16:42:03.826763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.89266ms","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-03-02T16:42:03.82682Z","caller":"traceutil/trace.go:171","msg":"trace[1958166474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:659; }","duration":"770.981323ms","start":"2026-03-02T16:42:03.055814Z","end":"2026-03-02T16:42:03.826795Z","steps":["trace[1958166474] 'agreement among raft nodes before linearized reading' (duration: 770.816789ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:42:03.826797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.359077ms","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-03-02T16:42:03.826855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:42:03.055802Z","time spent":"771.045354ms","remote":"127.0.0.1:59134","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-03-02T16:42:03.826869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.764042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T16:42:03.826886Z","caller":"traceutil/trace.go:171","msg":"trace[429853077] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:659; }","duration":"783.50702ms","start":"2026-03-02T16:42:03.043357Z","end":"2026-03-02T16:42:03.826864Z","steps":["trace[429853077] 'agreement among raft nodes before linearized reading' (duration: 783.261644ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:42:03.826976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:42:03.043323Z","time spent":"783.642593ms","remote":"127.0.0.1:59134","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-03-02T16:42:03.826906Z","caller":"traceutil/trace.go:171","msg":"trace[545607885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"558.863724ms","start":"2026-03-02T16:42:03.268029Z","end":"2026-03-02T16:42:03.826893Z","steps":["trace[545607885] 'agreement among raft nodes before linearized reading' (duration: 558.806243ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:42:03.827037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:42:03.267999Z","time spent":"559.029647ms","remote":"127.0.0.1:58896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T16:42:03.827048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.339923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.33\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-03-02T16:42:03.827082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.054353ms","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-03-02T16:42:03.827088Z","caller":"traceutil/trace.go:171","msg":"trace[2145545709] range","detail":"{range_begin:/registry/masterleases/199.204.45.33; range_end:; response_count:1; response_revision:659; }","duration":"572.411975ms","start":"2026-03-02T16:42:03.254664Z","end":"2026-03-02T16:42:03.827076Z","steps":["trace[2145545709] 'agreement among raft nodes before linearized reading' (duration: 572.313063ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:42:03.827117Z","caller":"traceutil/trace.go:171","msg":"trace[228921630] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:659; }","duration":"771.134405ms","start":"2026-03-02T16:42:03.055974Z","end":"2026-03-02T16:42:03.827108Z","steps":["trace[228921630] 'agreement among raft nodes before linearized reading' (duration: 770.997872ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:42:03.827133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:42:03.254643Z","time spent":"572.484567ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.33\" "} {"level":"warn","ts":"2026-03-02T16:42:03.827179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:42:03.055943Z","time spent":"771.229008ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-02T16:42:08.628678Z","caller":"traceutil/trace.go:171","msg":"trace[1185357325] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"142.887724ms","start":"2026-03-02T16:42:08.485754Z","end":"2026-03-02T16:42:08.628642Z","steps":["trace[1185357325] 'process raft request' (duration: 142.667619ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:42:10.443349Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154850315676604,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-02T16:42:10.559573Z","caller":"traceutil/trace.go:171","msg":"trace[2039824934] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"699.843283ms","start":"2026-03-02T16:42:09.859702Z","end":"2026-03-02T16:42:10.559546Z","steps":["trace[2039824934] 'process raft request' (duration: 699.650038ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:42:10.559752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:42:09.859687Z","time spent":"699.967285ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T16:42:10.586729Z","caller":"traceutil/trace.go:171","msg":"trace[1154723849] linearizableReadLoop","detail":"{readStateIndex:700; appliedIndex:697; }","duration":"644.1567ms","start":"2026-03-02T16:42:09.942545Z","end":"2026-03-02T16:42:10.586702Z","steps":["trace[1154723849] 'read index received' (duration: 616.863142ms)","trace[1154723849] 'applied index is now lower than readState.Index' (duration: 27.292478ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T16:42:10.586774Z","caller":"traceutil/trace.go:171","msg":"trace[1616220967] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"726.980096ms","start":"2026-03-02T16:42:09.859757Z","end":"2026-03-02T16:42:10.586737Z","steps":["trace[1616220967] 'process raft request' (duration: 726.769491ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:42:10.586813Z","caller":"traceutil/trace.go:171","msg":"trace[1633144231] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"726.454365ms","start":"2026-03-02T16:42:09.860311Z","end":"2026-03-02T16:42:10.586765Z","steps":["trace[1633144231] 'process raft request' (duration: 726.357142ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:42:10.586879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:42:09.859738Z","time spent":"727.083129ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T16:42:10.587015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.426137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-02T16:42:10.58702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:42:09.860285Z","time spent":"726.612359ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T16:42:10.587048Z","caller":"traceutil/trace.go:171","msg":"trace[1799748869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:680; }","duration":"644.514918ms","start":"2026-03-02T16:42:09.942526Z","end":"2026-03-02T16:42:10.587041Z","steps":["trace[1799748869] 'agreement among raft nodes before linearized reading' (duration: 644.274563ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:42:10.587078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:42:09.942518Z","time spent":"644.554539ms","remote":"127.0.0.1:59134","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-03-02T16:42:33.596365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.145286ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T16:42:33.596525Z","caller":"traceutil/trace.go:171","msg":"trace[913947170] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:776; }","duration":"309.44353ms","start":"2026-03-02T16:42:33.287058Z","end":"2026-03-02T16:42:33.596502Z","steps":["trace[913947170] 'read index received' (duration: 132.102909ms)","trace[913947170] 'applied index is now lower than readState.Index' (duration: 177.338781ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T16:42:33.596598Z","caller":"traceutil/trace.go:171","msg":"trace[792936916] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"309.818909ms","start":"2026-03-02T16:42:33.286731Z","end":"2026-03-02T16:42:33.59655Z","steps":["trace[792936916] 'process raft request' (duration: 132.363135ms)","trace[792936916] 'compare' (duration: 176.973752ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T16:42:33.596699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.669995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T16:42:33.596779Z","caller":"traceutil/trace.go:171","msg":"trace[713766546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:751; }","duration":"309.791908ms","start":"2026-03-02T16:42:33.286968Z","end":"2026-03-02T16:42:33.59676Z","steps":["trace[713766546] 'agreement among raft nodes before linearized reading' (duration: 309.628434ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:42:33.5968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.165285ms","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":"warn","ts":"2026-03-02T16:42:33.596848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:42:33.286913Z","time spent":"309.918361ms","remote":"127.0.0.1:58886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-02T16:42:33.596891Z","caller":"traceutil/trace.go:171","msg":"trace[1451564770] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:751; }","duration":"288.321408ms","start":"2026-03-02T16:42:33.308548Z","end":"2026-03-02T16:42:33.59687Z","steps":["trace[1451564770] 'agreement among raft nodes before linearized reading' (duration: 288.104263ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:42:33.596818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:42:33.286712Z","time spent":"309.994012ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T16:42:34.825479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.282801ms","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-03-02T16:42:34.825588Z","caller":"traceutil/trace.go:171","msg":"trace[922519956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:752; }","duration":"126.437725ms","start":"2026-03-02T16:42:34.699125Z","end":"2026-03-02T16:42:34.825563Z","steps":["trace[922519956] 'range keys from in-memory index tree' (duration: 126.106697ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:42:34.825628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.832681ms","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-03-02T16:42:34.825732Z","caller":"traceutil/trace.go:171","msg":"trace[895654646] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:752; }","duration":"106.964244ms","start":"2026-03-02T16:42:34.718737Z","end":"2026-03-02T16:42:34.825701Z","steps":["trace[895654646] 'range keys from in-memory index tree' (duration: 106.653027ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:42:34.826058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.426049ms","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-03-02T16:42:34.826108Z","caller":"traceutil/trace.go:171","msg":"trace[515930951] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:752; }","duration":"149.527661ms","start":"2026-03-02T16:42:34.676569Z","end":"2026-03-02T16:42:34.826096Z","steps":["trace[515930951] 'range keys from in-memory index tree' (duration: 149.296466ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:42:35.143723Z","caller":"traceutil/trace.go:171","msg":"trace[1785970624] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"312.43424ms","start":"2026-03-02T16:42:34.831256Z","end":"2026-03-02T16:42:35.14369Z","steps":["trace[1785970624] 'process raft request' (duration: 312.145493ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:42:35.143931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:42:34.831228Z","time spent":"312.578332ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T16:42:35.261544Z","caller":"traceutil/trace.go:171","msg":"trace[427523156] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:778; }","duration":"356.044552ms","start":"2026-03-02T16:42:34.905471Z","end":"2026-03-02T16:42:35.261516Z","steps":["trace[427523156] 'read index received' (duration: 238.047057ms)","trace[427523156] 'applied index is now lower than readState.Index' (duration: 117.996045ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T16:42:35.261729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.229167ms","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-03-02T16:42:35.26178Z","caller":"traceutil/trace.go:171","msg":"trace[210439299] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:757; }","duration":"356.34833ms","start":"2026-03-02T16:42:34.905415Z","end":"2026-03-02T16:42:35.261763Z","steps":["trace[210439299] 'agreement among raft nodes before linearized reading' (duration: 356.204546ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:42:35.26174Z","caller":"traceutil/trace.go:171","msg":"trace[1902218040] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"429.984113ms","start":"2026-03-02T16:42:34.831704Z","end":"2026-03-02T16:42:35.261688Z","steps":["trace[1902218040] 'process raft request' (duration: 427.671018ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:42:35.261852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:42:34.90539Z","time spent":"356.442312ms","remote":"127.0.0.1:59082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-03-02T16:42:35.261968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:42:34.831682Z","time spent":"430.167947ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T16:42:35.262049Z","caller":"traceutil/trace.go:171","msg":"trace[980455523] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"430.134706ms","start":"2026-03-02T16:42:34.831897Z","end":"2026-03-02T16:42:35.262031Z","steps":["trace[980455523] 'process raft request' (duration: 429.46683ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:42:35.262129Z","caller":"traceutil/trace.go:171","msg":"trace[2084175392] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"429.967543ms","start":"2026-03-02T16:42:34.832144Z","end":"2026-03-02T16:42:35.262111Z","steps":["trace[2084175392] 'process raft request' (duration: 429.255996ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:42:35.262177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:42:34.831891Z","time spent":"430.206818ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T16:42:35.262205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:42:34.832138Z","time spent":"430.028155ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T16:42:35.262057Z","caller":"traceutil/trace.go:171","msg":"trace[1034927273] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"429.969293ms","start":"2026-03-02T16:42:34.83178Z","end":"2026-03-02T16:42:35.261749Z","steps":["trace[1034927273] 'process raft request' (duration: 429.538532ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:42:35.262829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:42:34.831776Z","time spent":"430.667919ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T16:43:03.863535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.192357ms","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-03-02T16:43:03.863645Z","caller":"traceutil/trace.go:171","msg":"trace[1085881472] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:882; }","duration":"348.32472ms","start":"2026-03-02T16:43:03.515297Z","end":"2026-03-02T16:43:03.863622Z","steps":["trace[1085881472] 'range keys from in-memory index tree' (duration: 347.976271ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:43:03.863656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.871099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T16:43:03.863699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:43:03.51523Z","time spent":"348.454573ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-02T16:43:03.863751Z","caller":"traceutil/trace.go:171","msg":"trace[268040241] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:882; }","duration":"147.054403ms","start":"2026-03-02T16:43:03.716673Z","end":"2026-03-02T16:43:03.863727Z","steps":["trace[268040241] 'count revisions from in-memory index tree' (duration: 146.663283ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:43:03.863795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.193368ms","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-03-02T16:43:03.863846Z","caller":"traceutil/trace.go:171","msg":"trace[46571992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:882; }","duration":"351.29446ms","start":"2026-03-02T16:43:03.512533Z","end":"2026-03-02T16:43:03.863828Z","steps":["trace[46571992] 'range keys from in-memory index tree' (duration: 350.955162ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:43:03.863827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.786649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-02T16:43:03.863827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.984228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-02T16:43:03.863885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:43:03.512509Z","time spent":"351.366332ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-02T16:43:03.863916Z","caller":"traceutil/trace.go:171","msg":"trace[762389595] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:882; }","duration":"364.924832ms","start":"2026-03-02T16:43:03.498966Z","end":"2026-03-02T16:43:03.863891Z","steps":["trace[762389595] 'range keys from in-memory index tree' (duration: 364.571035ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:43:03.863917Z","caller":"traceutil/trace.go:171","msg":"trace[743121119] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:882; }","duration":"358.095252ms","start":"2026-03-02T16:43:03.505803Z","end":"2026-03-02T16:43:03.863898Z","steps":["trace[743121119] 'range keys from in-memory index tree' (duration: 357.795794ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:43:03.863964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:43:03.498939Z","time spent":"365.016095ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-02T16:43:03.863965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:43:03.505793Z","time spent":"358.162673ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-02T16:43:05.50157Z","caller":"traceutil/trace.go:171","msg":"trace[656102131] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:937; }","duration":"129.377454ms","start":"2026-03-02T16:43:05.372172Z","end":"2026-03-02T16:43:05.501549Z","steps":["trace[656102131] 'read index received' (duration: 128.551595ms)","trace[656102131] 'applied index is now lower than readState.Index' (duration: 824.819µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T16:43:05.501662Z","caller":"traceutil/trace.go:171","msg":"trace[43023031] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"138.328036ms","start":"2026-03-02T16:43:05.363298Z","end":"2026-03-02T16:43:05.501626Z","steps":["trace[43023031] 'process raft request' (duration: 137.465856ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:43:05.501754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.567408ms","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-03-02T16:43:05.501783Z","caller":"traceutil/trace.go:171","msg":"trace[1195867877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:904; }","duration":"129.614699ms","start":"2026-03-02T16:43:05.372162Z","end":"2026-03-02T16:43:05.501777Z","steps":["trace[1195867877] 'agreement among raft nodes before linearized reading' (duration: 129.474686ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:43:07.938614Z","caller":"traceutil/trace.go:171","msg":"trace[557391053] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:954; }","duration":"256.983406ms","start":"2026-03-02T16:43:07.681594Z","end":"2026-03-02T16:43:07.938578Z","steps":["trace[557391053] 'read index received' (duration: 256.74356ms)","trace[557391053] 'applied index is now lower than readState.Index' (duration: 238.476µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T16:43:07.938654Z","caller":"traceutil/trace.go:171","msg":"trace[500343499] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"407.729688ms","start":"2026-03-02T16:43:07.53089Z","end":"2026-03-02T16:43:07.93862Z","steps":["trace[500343499] 'process raft request' (duration: 407.452921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:43:07.938802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.174081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-02T16:43:07.938847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:43:07.53087Z","time spent":"407.872491ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T16:43:07.938901Z","caller":"traceutil/trace.go:171","msg":"trace[1966405476] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:921; }","duration":"257.333895ms","start":"2026-03-02T16:43:07.681532Z","end":"2026-03-02T16:43:07.938866Z","steps":["trace[1966405476] 'agreement among raft nodes before linearized reading' (duration: 257.173271ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:43:08.055884Z","caller":"traceutil/trace.go:171","msg":"trace[2141271210] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"112.370082ms","start":"2026-03-02T16:43:07.943481Z","end":"2026-03-02T16:43:08.055851Z","steps":["trace[2141271210] 'process raft request' (duration: 111.026929ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:43:08.302862Z","caller":"traceutil/trace.go:171","msg":"trace[515885757] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"242.702739ms","start":"2026-03-02T16:43:08.060123Z","end":"2026-03-02T16:43:08.302826Z","steps":["trace[515885757] 'process raft request' (duration: 226.297671ms)","trace[515885757] 'compare' (duration: 16.132532ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T16:43:08.340596Z","caller":"traceutil/trace.go:171","msg":"trace[953265585] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"280.235957ms","start":"2026-03-02T16:43:08.060271Z","end":"2026-03-02T16:43:08.340507Z","steps":["trace[953265585] 'process raft request' (duration: 279.957351ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:43:08.341149Z","caller":"traceutil/trace.go:171","msg":"trace[1095236308] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"278.861374ms","start":"2026-03-02T16:43:08.062271Z","end":"2026-03-02T16:43:08.341132Z","steps":["trace[1095236308] 'process raft request' (duration: 278.172028ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:43:10.190144Z","caller":"traceutil/trace.go:171","msg":"trace[372531574] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"173.911739ms","start":"2026-03-02T16:43:10.016194Z","end":"2026-03-02T16:43:10.190106Z","steps":["trace[372531574] 'process raft request' (duration: 173.704944ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:43:10.440179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.776906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-02T16:43:10.440436Z","caller":"traceutil/trace.go:171","msg":"trace[257711907] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:962; }","duration":"179.562973ms","start":"2026-03-02T16:43:10.260852Z","end":"2026-03-02T16:43:10.440401Z","steps":["trace[257711907] 'read index received' (duration: 21.454479ms)","trace[257711907] 'applied index is now lower than readState.Index' (duration: 158.093473ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T16:43:10.440439Z","caller":"traceutil/trace.go:171","msg":"trace[962784417] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"314.649062ms","start":"2026-03-02T16:43:10.125752Z","end":"2026-03-02T16:43:10.440401Z","steps":["trace[962784417] 'process raft request' (duration: 156.553938ms)","trace[962784417] 'compare' (duration: 157.550501ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T16:43:10.44064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:43:10.125726Z","time spent":"314.803455ms","remote":"127.0.0.1:58962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":779,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T16:43:10.440656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.774678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T16:43:10.440742Z","caller":"traceutil/trace.go:171","msg":"trace[1061599417] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:929; }","duration":"179.927692ms","start":"2026-03-02T16:43:10.260795Z","end":"2026-03-02T16:43:10.440723Z","steps":["trace[1061599417] 'agreement among raft nodes before linearized reading' (duration: 179.749858ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:43:10.440856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.474906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T16:43:10.440897Z","caller":"traceutil/trace.go:171","msg":"trace[2072744156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"175.530737ms","start":"2026-03-02T16:43:10.265353Z","end":"2026-03-02T16:43:10.440884Z","steps":["trace[2072744156] 'agreement among raft nodes before linearized reading' (duration: 175.456625ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:43:10.440949Z","caller":"traceutil/trace.go:171","msg":"trace[402276316] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"235.321023ms","start":"2026-03-02T16:43:10.205619Z","end":"2026-03-02T16:43:10.44094Z","steps":["trace[402276316] 'process raft request' (duration: 234.710078ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:43:10.441244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.356632ms","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-03-02T16:43:10.441276Z","caller":"traceutil/trace.go:171","msg":"trace[1109639376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:929; }","duration":"134.439054ms","start":"2026-03-02T16:43:10.306828Z","end":"2026-03-02T16:43:10.441267Z","steps":["trace[1109639376] 'agreement among raft nodes before linearized reading' (duration: 134.27956ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:43:10.815502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.568205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-02T16:43:10.815764Z","caller":"traceutil/trace.go:171","msg":"trace[650432607] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"309.744875ms","start":"2026-03-02T16:43:10.506004Z","end":"2026-03-02T16:43:10.815749Z","steps":["trace[650432607] 'process raft request' (duration: 309.685124ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:43:10.815822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:43:10.505987Z","time spent":"309.816708ms","remote":"127.0.0.1:58962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":779,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T16:43:10.81589Z","caller":"traceutil/trace.go:171","msg":"trace[1667821544] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"369.644745ms","start":"2026-03-02T16:43:10.446202Z","end":"2026-03-02T16:43:10.815846Z","steps":["trace[1667821544] 'process raft request' (duration: 164.66279ms)","trace[1667821544] 'compare' (duration: 204.342539ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T16:43:10.815983Z","caller":"traceutil/trace.go:171","msg":"trace[1534239424] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"369.629865ms","start":"2026-03-02T16:43:10.446346Z","end":"2026-03-02T16:43:10.815976Z","steps":["trace[1534239424] 'process raft request' (duration: 369.244006ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:43:10.816036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:43:10.446318Z","time spent":"369.683535ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T16:43:10.816081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:43:10.446188Z","time spent":"369.803548ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T16:43:10.816096Z","caller":"traceutil/trace.go:171","msg":"trace[502590201] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"369.126962ms","start":"2026-03-02T16:43:10.446901Z","end":"2026-03-02T16:43:10.816028Z","steps":["trace[502590201] 'process raft request' (duration: 368.756333ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:43:10.817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:43:10.446866Z","time spent":"369.425539ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T16:43:11.404319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.7284ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-02T16:43:11.404604Z","caller":"traceutil/trace.go:171","msg":"trace[175830738] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"570.478092ms","start":"2026-03-02T16:43:10.834097Z","end":"2026-03-02T16:43:11.404575Z","steps":["trace[175830738] 'process raft request' (duration: 570.38802ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:43:11.404703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:43:10.83407Z","time spent":"570.593004ms","remote":"127.0.0.1:58962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":868,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T16:43:11.404549Z","caller":"traceutil/trace.go:171","msg":"trace[2058665231] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"584.438232ms","start":"2026-03-02T16:43:10.820058Z","end":"2026-03-02T16:43:11.404496Z","steps":["trace[2058665231] 'process raft request' (duration: 261.730408ms)","trace[2058665231] 'compare' (duration: 319.846536ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T16:43:11.404944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.019134ms","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:507"} {"level":"info","ts":"2026-03-02T16:43:11.405013Z","caller":"traceutil/trace.go:171","msg":"trace[461616556] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:936; }","duration":"571.22807ms","start":"2026-03-02T16:43:10.833771Z","end":"2026-03-02T16:43:11.404999Z","steps":["trace[461616556] 'agreement among raft nodes before linearized reading' (duration: 570.917942ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:43:11.405003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:43:10.82004Z","time spent":"584.840802ms","remote":"127.0.0.1:58962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":842,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T16:43:11.40505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:43:10.833743Z","time spent":"571.298351ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-02T16:43:11.404635Z","caller":"traceutil/trace.go:171","msg":"trace[814068354] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:969; }","duration":"570.735698ms","start":"2026-03-02T16:43:10.833836Z","end":"2026-03-02T16:43:11.404571Z","steps":["trace[814068354] 'read index received' (duration: 247.901981ms)","trace[814068354] 'applied index is now lower than readState.Index' (duration: 322.830727ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T16:43:11.405626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.463441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T16:43:11.405686Z","caller":"traceutil/trace.go:171","msg":"trace[1194509624] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:936; }","duration":"467.537333ms","start":"2026-03-02T16:43:10.938131Z","end":"2026-03-02T16:43:11.405669Z","steps":["trace[1194509624] 'agreement among raft nodes before linearized reading' (duration: 467.41938ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:43:11.405735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:43:10.938054Z","time spent":"467.670556ms","remote":"127.0.0.1:59012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-03-02T16:43:11.405842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.598799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T16:43:11.405874Z","caller":"traceutil/trace.go:171","msg":"trace[903808078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:936; }","duration":"137.680631ms","start":"2026-03-02T16:43:11.268186Z","end":"2026-03-02T16:43:11.405867Z","steps":["trace[903808078] 'agreement among raft nodes before linearized reading' (duration: 137.63537ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:43:11.748789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.586838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T16:43:11.748886Z","caller":"traceutil/trace.go:171","msg":"trace[1267581296] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"262.998549ms","start":"2026-03-02T16:43:11.485872Z","end":"2026-03-02T16:43:11.748871Z","steps":["trace[1267581296] 'process raft request' (duration: 52.249807ms)","trace[1267581296] 'compare' (duration: 210.385734ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T16:43:22.42617Z","caller":"traceutil/trace.go:171","msg":"trace[497955060] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"106.690306ms","start":"2026-03-02T16:43:22.319444Z","end":"2026-03-02T16:43:22.426134Z","steps":["trace[497955060] 'process raft request' (duration: 106.00076ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:43:22.768001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.963002ms","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-03-02T16:43:22.76811Z","caller":"traceutil/trace.go:171","msg":"trace[699482401] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1003; }","duration":"210.180718ms","start":"2026-03-02T16:43:22.5579Z","end":"2026-03-02T16:43:22.768081Z","steps":["trace[699482401] 'range keys from in-memory index tree' (duration: 209.719547ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:43:22.768233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.503488ms","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-03-02T16:43:22.76837Z","caller":"traceutil/trace.go:171","msg":"trace[953280360] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1003; }","duration":"262.720974ms","start":"2026-03-02T16:43:22.50562Z","end":"2026-03-02T16:43:22.768341Z","steps":["trace[953280360] 'range keys from in-memory index tree' (duration: 262.238112ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:43:23.166819Z","caller":"traceutil/trace.go:171","msg":"trace[1702053124] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"393.820537ms","start":"2026-03-02T16:43:22.772966Z","end":"2026-03-02T16:43:23.166787Z","steps":["trace[1702053124] 'process raft request' (duration: 393.603412ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:43:23.167071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:43:22.772942Z","time spent":"394.008651ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T16:43:23.272727Z","caller":"traceutil/trace.go:171","msg":"trace[741723488] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"499.51136ms","start":"2026-03-02T16:43:22.773171Z","end":"2026-03-02T16:43:23.272683Z","steps":["trace[741723488] 'process raft request' (duration: 417.497299ms)","trace[741723488] 'compare' (duration: 81.721135ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T16:43:23.272955Z","caller":"traceutil/trace.go:171","msg":"trace[1811916598] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"499.411507ms","start":"2026-03-02T16:43:22.773529Z","end":"2026-03-02T16:43:23.272941Z","steps":["trace[1811916598] 'process raft request' (duration: 499.072289ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:43:23.27304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.059532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-02T16:43:23.273085Z","caller":"traceutil/trace.go:171","msg":"trace[1886052394] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1006; }","duration":"456.151433ms","start":"2026-03-02T16:43:22.816922Z","end":"2026-03-02T16:43:23.273073Z","steps":["trace[1886052394] 'agreement among raft nodes before linearized reading' (duration: 455.987739ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:43:23.273075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:43:22.77352Z","time spent":"499.466369ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T16:43:23.273108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:43:22.816902Z","time spent":"456.199015ms","remote":"127.0.0.1:59288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":30,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-03-02T16:43:23.273161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:43:22.773133Z","time spent":"499.825388ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T16:43:23.272857Z","caller":"traceutil/trace.go:171","msg":"trace[634595125] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1040; }","duration":"455.875948ms","start":"2026-03-02T16:43:22.816958Z","end":"2026-03-02T16:43:23.272834Z","steps":["trace[634595125] 'read index received' (duration: 349.674323ms)","trace[634595125] 'applied index is now lower than readState.Index' (duration: 106.199745ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T16:43:23.273467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.423529ms","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-03-02T16:43:23.273504Z","caller":"traceutil/trace.go:171","msg":"trace[332289150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1006; }","duration":"396.49375ms","start":"2026-03-02T16:43:22.876998Z","end":"2026-03-02T16:43:23.273492Z","steps":["trace[332289150] 'agreement among raft nodes before linearized reading' (duration: 396.363797ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:43:23.273527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:43:22.876977Z","time spent":"396.543891ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-02T16:43:23.273905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.076598ms","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-03-02T16:43:23.274004Z","caller":"traceutil/trace.go:171","msg":"trace[32940927] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1006; }","duration":"387.212621ms","start":"2026-03-02T16:43:22.886777Z","end":"2026-03-02T16:43:23.273989Z","steps":["trace[32940927] 'agreement among raft nodes before linearized reading' (duration: 387.020357ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:43:23.274104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:43:22.886761Z","time spent":"387.331574ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-02T16:44:17.281367Z","caller":"traceutil/trace.go:171","msg":"trace[766403729] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"138.837001ms","start":"2026-03-02T16:44:17.142487Z","end":"2026-03-02T16:44:17.281324Z","steps":["trace[766403729] 'process raft request' (duration: 84.959332ms)","trace[766403729] 'compare' (duration: 53.669994ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T16:44:45.963916Z","caller":"traceutil/trace.go:171","msg":"trace[1175492977] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"111.984661ms","start":"2026-03-02T16:44:45.851888Z","end":"2026-03-02T16:44:45.963873Z","steps":["trace[1175492977] 'process raft request' (duration: 111.751196ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:44:48.1851Z","caller":"traceutil/trace.go:171","msg":"trace[1119138389] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"102.880255ms","start":"2026-03-02T16:44:48.082183Z","end":"2026-03-02T16:44:48.185064Z","steps":["trace[1119138389] 'process raft request' (duration: 100.694372ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:45:23.934534Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154850315678638,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T16:45:24.435441Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154850315678638,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T16:45:24.936422Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154850315678638,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T16:45:25.434591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000324817s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-02T16:45:25.434725Z","caller":"traceutil/trace.go:171","msg":"trace[2023061652] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000715015s","start":"2026-03-02T16:45:23.433987Z","end":"2026-03-02T16:45:25.434702Z","steps":["trace[2023061652] 'agreement among raft nodes before linearized reading' (duration: 2.000290797s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:45:25.434834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:23.433966Z","time spent":"2.000805737s","remote":"127.0.0.1:58896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/02 16:45:25 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-02T16:45:25.437491Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154850315678638,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T16:45:25.75041Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.318388414s","expected-duration":"1s"} {"level":"info","ts":"2026-03-02T16:45:25.750778Z","caller":"traceutil/trace.go:171","msg":"trace[1841553597] linearizableReadLoop","detail":"{readStateIndex:1556; appliedIndex:1555; }","duration":"2.316710081s","start":"2026-03-02T16:45:23.434037Z","end":"2026-03-02T16:45:25.750747Z","steps":["trace[1841553597] 'read index received' (duration: 2.316525767s)","trace[1841553597] 'applied index is now lower than readState.Index' (duration: 182.524µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T16:45:25.750794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:23.431875Z","time spent":"2.318915215s","remote":"127.0.0.1:58910","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-02T16:45:25.751065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.970779495s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-02T16:45:25.751102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.590628046s","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-03-02T16:45:25.751118Z","caller":"traceutil/trace.go:171","msg":"trace[1848230897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1494; }","duration":"1.970902058s","start":"2026-03-02T16:45:23.780204Z","end":"2026-03-02T16:45:25.751106Z","steps":["trace[1848230897] 'agreement among raft nodes before linearized reading' (duration: 1.970666213s)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:45:25.75114Z","caller":"traceutil/trace.go:171","msg":"trace[656093713] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1494; }","duration":"1.590691827s","start":"2026-03-02T16:45:24.160437Z","end":"2026-03-02T16:45:25.751128Z","steps":["trace[656093713] 'agreement among raft nodes before linearized reading' (duration: 1.590581835s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:45:25.751154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:23.780183Z","time spent":"1.970964109s","remote":"127.0.0.1:59134","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-03-02T16:45:25.751174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:24.160422Z","time spent":"1.590741788s","remote":"127.0.0.1:59134","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-03-02T16:45:25.751163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.380110704s","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-03-02T16:45:25.751263Z","caller":"traceutil/trace.go:171","msg":"trace[254452135] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1494; }","duration":"1.380246437s","start":"2026-03-02T16:45:24.37099Z","end":"2026-03-02T16:45:25.751237Z","steps":["trace[254452135] 'agreement among raft nodes before linearized reading' (duration: 1.379960922s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:45:25.751305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.38964967s","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-03-02T16:45:25.751351Z","caller":"traceutil/trace.go:171","msg":"trace[1460119802] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1494; }","duration":"1.38971284s","start":"2026-03-02T16:45:24.36163Z","end":"2026-03-02T16:45:25.751343Z","steps":["trace[1460119802] 'agreement among raft nodes before linearized reading' (duration: 1.389619549s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:45:25.751391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:24.36162Z","time spent":"1.389757702s","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-02T16:45:25.751405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.947334571s","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:534"} {"level":"info","ts":"2026-03-02T16:45:25.75144Z","caller":"traceutil/trace.go:171","msg":"trace[679784087] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1494; }","duration":"1.947402842s","start":"2026-03-02T16:45:23.804024Z","end":"2026-03-02T16:45:25.751427Z","steps":["trace[679784087] 'agreement among raft nodes before linearized reading' (duration: 1.94731648s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:45:25.751476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:23.804007Z","time spent":"1.947463143s","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-02T16:45:25.7515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.929920794s","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":"warn","ts":"2026-03-02T16:45:25.751319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:24.370978Z","time spent":"1.380330719s","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-02T16:45:25.751525Z","caller":"traceutil/trace.go:171","msg":"trace[199196935] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1494; }","duration":"1.929994745s","start":"2026-03-02T16:45:23.821521Z","end":"2026-03-02T16:45:25.751516Z","steps":["trace[199196935] 'agreement among raft nodes before linearized reading' (duration: 1.929661338s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:45:25.751548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:23.821501Z","time spent":"1.930041976s","remote":"127.0.0.1:59300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-03-02T16:45:25.751622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.194688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-03-02T16:45:25.751656Z","caller":"traceutil/trace.go:171","msg":"trace[382913411] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1494; }","duration":"808.2826ms","start":"2026-03-02T16:45:24.943366Z","end":"2026-03-02T16:45:25.751648Z","steps":["trace[382913411] 'agreement among raft nodes before linearized reading' (duration: 808.182278ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:45:25.751681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:24.943346Z","time spent":"808.32983ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-02T16:45:25.7517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.529038452s","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-03-02T16:45:25.75179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.346416391s","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-03-02T16:45:25.751807Z","caller":"traceutil/trace.go:171","msg":"trace[1329539871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1494; }","duration":"1.346444612s","start":"2026-03-02T16:45:24.405357Z","end":"2026-03-02T16:45:25.751801Z","steps":["trace[1329539871] 'agreement among raft nodes before linearized reading' (duration: 1.34635509s)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:45:25.751806Z","caller":"traceutil/trace.go:171","msg":"trace[1152585787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1494; }","duration":"1.529106043s","start":"2026-03-02T16:45:24.222624Z","end":"2026-03-02T16:45:25.75173Z","steps":["trace[1152585787] 'agreement among raft nodes before linearized reading' (duration: 1.52896119s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:45:25.751821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:24.405351Z","time spent":"1.346467502s","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-02T16:45:25.751806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.283491702s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-02T16:45:25.751841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:24.222612Z","time spent":"1.529221106s","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-02T16:45:25.751875Z","caller":"traceutil/trace.go:171","msg":"trace[1224922958] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1494; }","duration":"1.283605865s","start":"2026-03-02T16:45:24.468245Z","end":"2026-03-02T16:45:25.751851Z","steps":["trace[1224922958] 'agreement among raft nodes before linearized reading' (duration: 1.283461372s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:45:25.751878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.257961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T16:45:25.751914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:24.468227Z","time spent":"1.283675716s","remote":"127.0.0.1:56392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-03-02T16:45:25.751921Z","caller":"traceutil/trace.go:171","msg":"trace[2017492924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1494; }","duration":"317.333943ms","start":"2026-03-02T16:45:25.434575Z","end":"2026-03-02T16:45:25.751909Z","steps":["trace[2017492924] 'agreement among raft nodes before linearized reading' (duration: 317.266731ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:45:25.751956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:25.434557Z","time spent":"317.393353ms","remote":"127.0.0.1:58886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T16:45:29.059926Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154850315678673,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T16:45:29.561045Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154850315678673,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T16:45:30.062107Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154850315678673,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T16:45:30.26838Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.49825834s","expected-duration":"1s"} {"level":"info","ts":"2026-03-02T16:45:30.268871Z","caller":"traceutil/trace.go:171","msg":"trace[1861472881] transaction","detail":"{read_only:false; response_revision:1507; number_of_response:1; }","duration":"2.498816252s","start":"2026-03-02T16:45:27.770027Z","end":"2026-03-02T16:45:30.268843Z","steps":["trace[1861472881] 'process raft request' (duration: 2.498620708s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:45:30.269049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:27.770012Z","time spent":"2.498946204s","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T16:45:30.271671Z","caller":"traceutil/trace.go:171","msg":"trace[118492777] transaction","detail":"{read_only:false; response_revision:1510; number_of_response:1; }","duration":"2.498458875s","start":"2026-03-02T16:45:27.773187Z","end":"2026-03-02T16:45:30.271646Z","steps":["trace[118492777] 'process raft request' (duration: 2.498383023s)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:45:30.271672Z","caller":"traceutil/trace.go:171","msg":"trace[163780789] transaction","detail":"{read_only:false; response_revision:1508; number_of_response:1; }","duration":"2.499159339s","start":"2026-03-02T16:45:27.772479Z","end":"2026-03-02T16:45:30.271639Z","steps":["trace[163780789] 'process raft request' (duration: 2.498926184s)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:45:30.271777Z","caller":"traceutil/trace.go:171","msg":"trace[1126439037] transaction","detail":"{read_only:false; response_revision:1509; number_of_response:1; }","duration":"2.498972595s","start":"2026-03-02T16:45:27.772767Z","end":"2026-03-02T16:45:30.27174Z","steps":["trace[1126439037] 'process raft request' (duration: 2.498765861s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:45:30.271808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:27.773177Z","time spent":"2.498548326s","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T16:45:30.271862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:27.772465Z","time spent":"2.499307691s","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T16:45:30.271932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:27.772743Z","time spent":"2.499099047s","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T16:45:30.271999Z","caller":"traceutil/trace.go:171","msg":"trace[448715507] transaction","detail":"{read_only:false; response_revision:1512; number_of_response:1; }","duration":"2.49662947s","start":"2026-03-02T16:45:27.775355Z","end":"2026-03-02T16:45:30.271985Z","steps":["trace[448715507] 'process raft request' (duration: 2.496398115s)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:45:30.271985Z","caller":"traceutil/trace.go:171","msg":"trace[270235932] transaction","detail":"{read_only:false; response_revision:1511; number_of_response:1; }","duration":"2.497910364s","start":"2026-03-02T16:45:27.774052Z","end":"2026-03-02T16:45:30.271963Z","steps":["trace[270235932] 'process raft request' (duration: 2.497550207s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:45:30.272053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:27.775344Z","time spent":"2.49667943s","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T16:45:30.272082Z","caller":"traceutil/trace.go:171","msg":"trace[1595452760] transaction","detail":"{read_only:false; response_revision:1513; number_of_response:1; }","duration":"1.750706278s","start":"2026-03-02T16:45:28.521363Z","end":"2026-03-02T16:45:30.272069Z","steps":["trace[1595452760] 'process raft request' (duration: 1.750486394s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:45:30.272085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:27.774035Z","time spent":"2.498004167s","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T16:45:30.272142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:28.521344Z","time spent":"1.750766458s","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T16:45:30.276721Z","caller":"traceutil/trace.go:171","msg":"trace[1730343914] linearizableReadLoop","detail":"{readStateIndex:1578; appliedIndex:1569; }","duration":"1.717146947s","start":"2026-03-02T16:45:28.559562Z","end":"2026-03-02T16:45:30.276709Z","steps":["trace[1730343914] 'read index received' (duration: 1.709120411s)","trace[1730343914] 'applied index is now lower than readState.Index' (duration: 8.025726ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T16:45:30.276927Z","caller":"traceutil/trace.go:171","msg":"trace[1602038016] transaction","detail":"{read_only:false; response_revision:1514; number_of_response:1; }","duration":"748.133822ms","start":"2026-03-02T16:45:29.528786Z","end":"2026-03-02T16:45:30.27692Z","steps":["trace[1602038016] 'process raft request' (duration: 747.661184ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:45:30.276976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:29.528754Z","time spent":"748.197124ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T16:45:30.277086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.717523544s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-02T16:45:30.277114Z","caller":"traceutil/trace.go:171","msg":"trace[779574032] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1514; }","duration":"1.717581926s","start":"2026-03-02T16:45:28.559525Z","end":"2026-03-02T16:45:30.277107Z","steps":["trace[779574032] 'agreement among raft nodes before linearized reading' (duration: 1.717532965s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:45:30.277135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:28.559511Z","time spent":"1.717618506s","remote":"127.0.0.1:59140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-03-02T16:45:30.277221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010565141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T16:45:30.277286Z","caller":"traceutil/trace.go:171","msg":"trace[892033751] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1514; }","duration":"1.010653222s","start":"2026-03-02T16:45:29.266614Z","end":"2026-03-02T16:45:30.277267Z","steps":["trace[892033751] 'agreement among raft nodes before linearized reading' (duration: 1.01056209s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:45:30.277321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:29.2666Z","time spent":"1.010714414s","remote":"127.0.0.1:58896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T16:45:30.277375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.885983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T16:45:30.277409Z","caller":"traceutil/trace.go:171","msg":"trace[1646255500] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1514; }","duration":"170.942044ms","start":"2026-03-02T16:45:30.106456Z","end":"2026-03-02T16:45:30.277398Z","steps":["trace[1646255500] 'agreement among raft nodes before linearized reading' (duration: 170.896463ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:45:30.277435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.930018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-02T16:45:30.277511Z","caller":"traceutil/trace.go:171","msg":"trace[1449129176] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1514; }","duration":"620.074461ms","start":"2026-03-02T16:45:29.65742Z","end":"2026-03-02T16:45:30.277495Z","steps":["trace[1449129176] 'agreement among raft nodes before linearized reading' (duration: 619.912007ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:45:30.277552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:29.657408Z","time spent":"620.137042ms","remote":"127.0.0.1:59350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-03-02T16:45:30.27782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.47149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-02T16:45:30.278494Z","caller":"traceutil/trace.go:171","msg":"trace[752090226] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1514; }","duration":"863.199684ms","start":"2026-03-02T16:45:29.415279Z","end":"2026-03-02T16:45:30.278479Z","steps":["trace[752090226] 'agreement among raft nodes before linearized reading' (duration: 862.222535ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:45:30.278693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:29.415255Z","time spent":"863.310887ms","remote":"127.0.0.1:59078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-03-02T16:45:57.994812Z","caller":"traceutil/trace.go:171","msg":"trace[1243240109] linearizableReadLoop","detail":"{readStateIndex:1692; appliedIndex:1691; }","duration":"361.038941ms","start":"2026-03-02T16:45:57.633752Z","end":"2026-03-02T16:45:57.994791Z","steps":["trace[1243240109] 'read index received' (duration: 360.106703ms)","trace[1243240109] 'applied index is now lower than readState.Index' (duration: 931.228µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T16:45:57.99497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.188303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T16:45:57.994995Z","caller":"traceutil/trace.go:171","msg":"trace[1658845896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1623; }","duration":"361.321535ms","start":"2026-03-02T16:45:57.633665Z","end":"2026-03-02T16:45:57.994987Z","steps":["trace[1658845896] 'agreement among raft nodes before linearized reading' (duration: 361.215154ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:45:57.994956Z","caller":"traceutil/trace.go:171","msg":"trace[1801666294] transaction","detail":"{read_only:false; response_revision:1623; number_of_response:1; }","duration":"467.754084ms","start":"2026-03-02T16:45:57.527115Z","end":"2026-03-02T16:45:57.994869Z","steps":["trace[1801666294] 'process raft request' (duration: 466.854166ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:45:57.995018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:57.633622Z","time spent":"361.391667ms","remote":"127.0.0.1:58886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T16:45:57.99511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:45:57.527109Z","time spent":"467.922047ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T16:46:01.565961Z","caller":"traceutil/trace.go:171","msg":"trace[1117027882] linearizableReadLoop","detail":"{readStateIndex:1709; appliedIndex:1708; }","duration":"297.065827ms","start":"2026-03-02T16:46:01.268869Z","end":"2026-03-02T16:46:01.565935Z","steps":["trace[1117027882] 'read index received' (duration: 296.830522ms)","trace[1117027882] 'applied index is now lower than readState.Index' (duration: 233.885µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T16:46:01.566333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.445654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T16:46:01.566371Z","caller":"traceutil/trace.go:171","msg":"trace[394933606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1639; }","duration":"297.537586ms","start":"2026-03-02T16:46:01.26882Z","end":"2026-03-02T16:46:01.566357Z","steps":["trace[394933606] 'agreement among raft nodes before linearized reading' (duration: 297.23439ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:46:01.566396Z","caller":"traceutil/trace.go:171","msg":"trace[1898050082] transaction","detail":"{read_only:false; response_revision:1639; number_of_response:1; }","duration":"413.933549ms","start":"2026-03-02T16:46:01.152397Z","end":"2026-03-02T16:46:01.56633Z","steps":["trace[1898050082] 'process raft request' (duration: 413.364847ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T16:46:01.566674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T16:46:01.152363Z","time spent":"414.159643ms","remote":"127.0.0.1:59134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T16:46:31.00764Z","caller":"traceutil/trace.go:171","msg":"trace[718959910] transaction","detail":"{read_only:false; response_revision:1766; number_of_response:1; }","duration":"105.635482ms","start":"2026-03-02T16:46:30.90198Z","end":"2026-03-02T16:46:31.007615Z","steps":["trace[718959910] 'process raft request' (duration: 79.367957ms)","trace[718959910] 'compare' (duration: 26.073291ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T16:46:31.008076Z","caller":"traceutil/trace.go:171","msg":"trace[2145488414] linearizableReadLoop","detail":"{readStateIndex:1844; appliedIndex:1841; }","duration":"105.439928ms","start":"2026-03-02T16:46:30.902624Z","end":"2026-03-02T16:46:31.008063Z","steps":["trace[2145488414] 'read index received' (duration: 28.124302ms)","trace[2145488414] 'applied index is now lower than readState.Index' (duration: 77.314816ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T16:46:31.008201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.571611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1948"} {"level":"info","ts":"2026-03-02T16:46:31.008232Z","caller":"traceutil/trace.go:171","msg":"trace[1088246078] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1768; }","duration":"105.656182ms","start":"2026-03-02T16:46:30.902566Z","end":"2026-03-02T16:46:31.008222Z","steps":["trace[1088246078] 'agreement among raft nodes before linearized reading' (duration: 105.55032ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:46:31.008462Z","caller":"traceutil/trace.go:171","msg":"trace[1147516651] transaction","detail":"{read_only:false; response_revision:1767; number_of_response:1; }","duration":"106.377266ms","start":"2026-03-02T16:46:30.902071Z","end":"2026-03-02T16:46:31.008448Z","steps":["trace[1147516651] 'process raft request' (duration: 105.490639ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T16:46:31.008493Z","caller":"traceutil/trace.go:171","msg":"trace[418560055] transaction","detail":"{read_only:false; response_revision:1768; number_of_response:1; }","duration":"105.100391ms","start":"2026-03-02T16:46:30.903368Z","end":"2026-03-02T16:46:31.008469Z","steps":["trace[418560055] 'process raft request' (duration: 104.650972ms)"],"step_count":1}