{"level":"warn","ts":"2026-02-27T20:28:54.313436Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-27T20:28:54.31351Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113: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.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113: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-02-27T20:28:54.313571Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-27T20:28:54.313582Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-02-27T20:28:54.313602Z","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-02-27T20:28:54.314433Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-02-27T20:28:54.314583Z","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.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113: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-02-27T20:28:54.318623Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.732337ms"} {"level":"info","ts":"2026-02-27T20:28:54.325308Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-02-27T20:28:54.325518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-02-27T20:28:54.325571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-02-27T20:28:54.325591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-27T20:28:54.325613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-02-27T20:28:54.325695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-02-27T20:28:54.332271Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-27T20:28:54.334947Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-27T20:28:54.336618Z","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-02-27T20:28:54.339138Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-27T20:28:54.340002Z","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-02-27T20:28:54.340077Z","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-02-27T20:28:54.340089Z","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-02-27T20:28:54.340198Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-27T20:28:54.341311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-02-27T20:28:54.341428Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-02-27T20:28:54.342757Z","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-02-27T20:28:54.342824Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-02-27T20:28:54.342849Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-02-27T20:28:54.342968Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-27T20:28:54.343034Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-27T20:28:55.226513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-02-27T20:28:55.226571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-02-27T20:28:55.226608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-02-27T20:28:55.226627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-02-27T20:28:55.226639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-02-27T20:28:55.226662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-02-27T20:28:55.226675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-02-27T20:28:55.227484Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-02-27T20:28:55.227496Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T20:28:55.227584Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T20:28:55.227692Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-27T20:28:55.227729Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-27T20:28:55.227553Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:28:55.22913Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:28:55.229301Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:28:55.229391Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:28:55.230325Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-02-27T20:28:55.231215Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-27T20:29:04.53788Z","caller":"traceutil/trace.go:171","msg":"trace[91347026] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"137.483342ms","start":"2026-02-27T20:29:04.400374Z","end":"2026-02-27T20:29:04.537857Z","steps":["trace[91347026] 'process raft request' (duration: 137.310728ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:04.562097Z","caller":"traceutil/trace.go:171","msg":"trace[909269548] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"125.184606ms","start":"2026-02-27T20:29:04.436884Z","end":"2026-02-27T20:29:04.562069Z","steps":["trace[909269548] 'process raft request' (duration: 125.059454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:04.760079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.777493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-27T20:29:04.760161Z","caller":"traceutil/trace.go:171","msg":"trace[449905484] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:341; }","duration":"123.920617ms","start":"2026-02-27T20:29:04.636223Z","end":"2026-02-27T20:29:04.760143Z","steps":["trace[449905484] 'range keys from in-memory index tree' (duration: 123.679571ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:04.762672Z","caller":"traceutil/trace.go:171","msg":"trace[263058979] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"116.662106ms","start":"2026-02-27T20:29:04.645983Z","end":"2026-02-27T20:29:04.762645Z","steps":["trace[263058979] 'process raft request' (duration: 116.467452ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:06.381867Z","caller":"traceutil/trace.go:171","msg":"trace[1961589612] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"112.379167ms","start":"2026-02-27T20:29:06.269469Z","end":"2026-02-27T20:29:06.381848Z","steps":["trace[1961589612] 'process raft request' (duration: 106.4509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:19.123532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.44901ms","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-02-27T20:29:19.123656Z","caller":"traceutil/trace.go:171","msg":"trace[393147851] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:459; }","duration":"276.618154ms","start":"2026-02-27T20:29:18.84701Z","end":"2026-02-27T20:29:19.123628Z","steps":["trace[393147851] 'range keys from in-memory index tree' (duration: 276.298617ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:26.797818Z","caller":"traceutil/trace.go:171","msg":"trace[1913953590] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"239.195181ms","start":"2026-02-27T20:29:26.558595Z","end":"2026-02-27T20:29:26.79779Z","steps":["trace[1913953590] 'process raft request' (duration: 239.056107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:27.790513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.161351ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:29:27.790608Z","caller":"traceutil/trace.go:171","msg":"trace[604389630] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:515; }","duration":"115.262734ms","start":"2026-02-27T20:29:27.675329Z","end":"2026-02-27T20:29:27.790592Z","steps":["trace[604389630] 'range keys from in-memory index tree' (duration: 115.147611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:28.800507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.19499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:29:28.800613Z","caller":"traceutil/trace.go:171","msg":"trace[1566085900] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"661.438623ms","start":"2026-02-27T20:29:28.139157Z","end":"2026-02-27T20:29:28.800596Z","steps":["trace[1566085900] 'process raft request' (duration: 117.594198ms)","trace[1566085900] 'compare' (duration: 543.075187ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:29:28.800681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:28.139138Z","time spent":"661.512275ms","remote":"127.0.0.1:37628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:29:28.929693Z","caller":"traceutil/trace.go:171","msg":"trace[1037237108] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:534; }","duration":"124.97553ms","start":"2026-02-27T20:29:28.804698Z","end":"2026-02-27T20:29:28.929674Z","steps":["trace[1037237108] 'read index received' (duration: 98.036233ms)","trace[1037237108] 'applied index is now lower than readState.Index' (duration: 26.938427ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:29:28.929812Z","caller":"traceutil/trace.go:171","msg":"trace[1706075363] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"125.310907ms","start":"2026-02-27T20:29:28.80447Z","end":"2026-02-27T20:29:28.92978Z","steps":["trace[1706075363] 'process raft request' (duration: 98.240738ms)","trace[1706075363] 'compare' (duration: 26.822344ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:29:28.9299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.186075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-27T20:29:28.929961Z","caller":"traceutil/trace.go:171","msg":"trace[936293245] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:518; }","duration":"125.311058ms","start":"2026-02-27T20:29:28.804636Z","end":"2026-02-27T20:29:28.929948Z","steps":["trace[936293245] 'agreement among raft nodes before linearized reading' (duration: 125.125914ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:35.056065Z","caller":"traceutil/trace.go:171","msg":"trace[848995216] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"100.581852ms","start":"2026-02-27T20:29:34.95546Z","end":"2026-02-27T20:29:35.056042Z","steps":["trace[848995216] 'process raft request' (duration: 100.432939ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:35.418372Z","caller":"traceutil/trace.go:171","msg":"trace[1405071731] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:552; }","duration":"176.577034ms","start":"2026-02-27T20:29:35.241776Z","end":"2026-02-27T20:29:35.418353Z","steps":["trace[1405071731] 'read index received' (duration: 176.42255ms)","trace[1405071731] 'applied index is now lower than readState.Index' (duration: 153.754µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:29:35.418686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.881641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:29:35.418817Z","caller":"traceutil/trace.go:171","msg":"trace[2123708999] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"198.467703ms","start":"2026-02-27T20:29:35.220287Z","end":"2026-02-27T20:29:35.418755Z","steps":["trace[2123708999] 'process raft request' (duration: 197.957841ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:35.418805Z","caller":"traceutil/trace.go:171","msg":"trace[101273769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"177.048295ms","start":"2026-02-27T20:29:35.241737Z","end":"2026-02-27T20:29:35.418785Z","steps":["trace[101273769] 'agreement among raft nodes before linearized reading' (duration: 176.84702ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:36.036493Z","caller":"traceutil/trace.go:171","msg":"trace[1618387631] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"613.066099ms","start":"2026-02-27T20:29:35.423402Z","end":"2026-02-27T20:29:36.036468Z","steps":["trace[1618387631] 'process raft request' (duration: 607.606532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:36.036652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:35.423381Z","time spent":"613.197833ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:29:36.040228Z","caller":"traceutil/trace.go:171","msg":"trace[1895104149] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"616.516041ms","start":"2026-02-27T20:29:35.423689Z","end":"2026-02-27T20:29:36.040205Z","steps":["trace[1895104149] 'process raft request' (duration: 616.244534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:36.040483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:35.42367Z","time spent":"616.618523ms","remote":"127.0.0.1:37856","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-02-27T20:29:37.052896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.676319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:29:37.052973Z","caller":"traceutil/trace.go:171","msg":"trace[463017487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"810.796032ms","start":"2026-02-27T20:29:36.242158Z","end":"2026-02-27T20:29:37.052954Z","steps":["trace[463017487] 'range keys from in-memory index tree' (duration: 810.556846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:37.053012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:36.242109Z","time spent":"810.893734ms","remote":"127.0.0.1:37602","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-02-27T20:29:37.561315Z","caller":"traceutil/trace.go:171","msg":"trace[109975329] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"498.417657ms","start":"2026-02-27T20:29:37.062867Z","end":"2026-02-27T20:29:37.561284Z","steps":["trace[109975329] 'process raft request' (duration: 498.271653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:37.561463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:37.06285Z","time spent":"498.536041ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:29:39.677365Z","caller":"traceutil/trace.go:171","msg":"trace[829213886] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:587; }","duration":"112.232544ms","start":"2026-02-27T20:29:39.565111Z","end":"2026-02-27T20:29:39.677343Z","steps":["trace[829213886] 'read index received' (duration: 112.077791ms)","trace[829213886] 'applied index is now lower than readState.Index' (duration: 153.773µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:29:39.677478Z","caller":"traceutil/trace.go:171","msg":"trace[506827613] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"188.759257ms","start":"2026-02-27T20:29:39.488676Z","end":"2026-02-27T20:29:39.677435Z","steps":["trace[506827613] 'process raft request' (duration: 188.557172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:39.677598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.47979ms","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-02-27T20:29:39.677642Z","caller":"traceutil/trace.go:171","msg":"trace[2037008880] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:569; }","duration":"112.581962ms","start":"2026-02-27T20:29:39.565048Z","end":"2026-02-27T20:29:39.67763Z","steps":["trace[2037008880] 'agreement among raft nodes before linearized reading' (duration: 112.390708ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:45.647477Z","caller":"traceutil/trace.go:171","msg":"trace[1469951869] transaction","detail":"{read_only:false; number_of_response:0; response_revision:643; }","duration":"224.243472ms","start":"2026-02-27T20:29:45.423204Z","end":"2026-02-27T20:29:45.647447Z","steps":["trace[1469951869] 'process raft request' (duration: 224.172051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:45.647551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.132133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1668"} {"level":"info","ts":"2026-02-27T20:29:45.647586Z","caller":"traceutil/trace.go:171","msg":"trace[1435916883] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:643; }","duration":"222.195115ms","start":"2026-02-27T20:29:45.425384Z","end":"2026-02-27T20:29:45.647579Z","steps":["trace[1435916883] 'agreement among raft nodes before linearized reading' (duration: 222.127033ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:45.647469Z","caller":"traceutil/trace.go:171","msg":"trace[761745396] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"222.046092ms","start":"2026-02-27T20:29:45.425404Z","end":"2026-02-27T20:29:45.64745Z","steps":["trace[761745396] 'read index received' (duration: 221.9843ms)","trace[761745396] 'applied index is now lower than readState.Index' (duration: 48.351µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:29:45.701149Z","caller":"traceutil/trace.go:171","msg":"trace[1173805389] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"273.450529ms","start":"2026-02-27T20:29:45.427673Z","end":"2026-02-27T20:29:45.701124Z","steps":["trace[1173805389] 'process raft request' (duration: 273.279725ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:45.701242Z","caller":"traceutil/trace.go:171","msg":"trace[1489540306] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"176.413078ms","start":"2026-02-27T20:29:45.524792Z","end":"2026-02-27T20:29:45.701205Z","steps":["trace[1489540306] 'process raft request' (duration: 176.260495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:48.400213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.045017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:29:48.400304Z","caller":"traceutil/trace.go:171","msg":"trace[1238641848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"160.15165ms","start":"2026-02-27T20:29:48.240127Z","end":"2026-02-27T20:29:48.400279Z","steps":["trace[1238641848] 'range keys from in-memory index tree' (duration: 159.973016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:48.40036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.284289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tgdlc\" ","response":"range_response_count:1 size:18713"} {"level":"info","ts":"2026-02-27T20:29:48.400438Z","caller":"traceutil/trace.go:171","msg":"trace[1131976038] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tgdlc; range_end:; response_count:1; response_revision:663; }","duration":"171.413192ms","start":"2026-02-27T20:29:48.229007Z","end":"2026-02-27T20:29:48.40042Z","steps":["trace[1131976038] 'range keys from in-memory index tree' (duration: 171.117406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:48.400688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.544904ms","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-02-27T20:29:48.400726Z","caller":"traceutil/trace.go:171","msg":"trace[163645569] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:663; }","duration":"222.598135ms","start":"2026-02-27T20:29:48.178119Z","end":"2026-02-27T20:29:48.400717Z","steps":["trace[163645569] 'range keys from in-memory index tree' (duration: 222.442382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:48.400899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.059559ms","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-02-27T20:29:48.40092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.527883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-27T20:29:48.400936Z","caller":"traceutil/trace.go:171","msg":"trace[1098691405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:663; }","duration":"218.10814ms","start":"2026-02-27T20:29:48.182818Z","end":"2026-02-27T20:29:48.400926Z","steps":["trace[1098691405] 'range keys from in-memory index tree' (duration: 217.993077ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:48.400945Z","caller":"traceutil/trace.go:171","msg":"trace[1733262391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:663; }","duration":"228.581454ms","start":"2026-02-27T20:29:48.172355Z","end":"2026-02-27T20:29:48.400936Z","steps":["trace[1733262391] 'range keys from in-memory index tree' (duration: 228.425451ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:48.878726Z","caller":"traceutil/trace.go:171","msg":"trace[845582873] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"473.477778ms","start":"2026-02-27T20:29:48.405114Z","end":"2026-02-27T20:29:48.878592Z","steps":["trace[845582873] 'process raft request' (duration: 459.380559ms)","trace[845582873] 'compare' (duration: 13.889964ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:29:48.87883Z","caller":"traceutil/trace.go:171","msg":"trace[15020617] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"473.56157ms","start":"2026-02-27T20:29:48.40524Z","end":"2026-02-27T20:29:48.878801Z","steps":["trace[15020617] 'process raft request' (duration: 473.304653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:48.878885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:48.405093Z","time spent":"473.715114ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:29:48.87903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:48.405194Z","time spent":"473.708824ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:29:48.884782Z","caller":"traceutil/trace.go:171","msg":"trace[1371024538] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"461.717474ms","start":"2026-02-27T20:29:48.423047Z","end":"2026-02-27T20:29:48.884764Z","steps":["trace[1371024538] 'process raft request' (duration: 461.609422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:48.884896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:48.423031Z","time spent":"461.808456ms","remote":"127.0.0.1:37772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18698,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:29:53.013305Z","caller":"traceutil/trace.go:171","msg":"trace[1154207619] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"119.105724ms","start":"2026-02-27T20:29:52.894177Z","end":"2026-02-27T20:29:53.013283Z","steps":["trace[1154207619] 'process raft request' (duration: 118.989371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:54.590167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.466089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-27T20:29:54.590607Z","caller":"traceutil/trace.go:171","msg":"trace[1474556919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:730; }","duration":"112.96282ms","start":"2026-02-27T20:29:54.477622Z","end":"2026-02-27T20:29:54.590585Z","steps":["trace[1474556919] 'range keys from in-memory index tree' (duration: 112.189703ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:55.17106Z","caller":"traceutil/trace.go:171","msg":"trace[2084838606] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:754; }","duration":"176.733376ms","start":"2026-02-27T20:29:54.994303Z","end":"2026-02-27T20:29:55.171036Z","steps":["trace[2084838606] 'read index received' (duration: 176.653305ms)","trace[2084838606] 'applied index is now lower than readState.Index' (duration: 79.081µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:29:55.171183Z","caller":"traceutil/trace.go:171","msg":"trace[564286769] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"575.155056ms","start":"2026-02-27T20:29:54.595996Z","end":"2026-02-27T20:29:55.171151Z","steps":["trace[564286769] 'process raft request' (duration: 574.90227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:55.171274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.952291ms","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-02-27T20:29:55.171275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.498788ms","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-02-27T20:29:55.171312Z","caller":"traceutil/trace.go:171","msg":"trace[1745377242] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:731; }","duration":"177.066863ms","start":"2026-02-27T20:29:54.994232Z","end":"2026-02-27T20:29:55.171299Z","steps":["trace[1745377242] 'agreement among raft nodes before linearized reading' (duration: 176.93172ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:55.171328Z","caller":"traceutil/trace.go:171","msg":"trace[1153163610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:731; }","duration":"154.595901ms","start":"2026-02-27T20:29:55.01672Z","end":"2026-02-27T20:29:55.171316Z","steps":["trace[1153163610] 'agreement among raft nodes before linearized reading' (duration: 154.454898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:55.17134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:54.595979Z","time spent":"575.280949ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:29:55.454242Z","caller":"traceutil/trace.go:171","msg":"trace[1767173763] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"279.601093ms","start":"2026-02-27T20:29:55.174618Z","end":"2026-02-27T20:29:55.454219Z","steps":["trace[1767173763] 'process raft request' (duration: 279.454489ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:55.547235Z","caller":"traceutil/trace.go:171","msg":"trace[2062256343] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:755; }","duration":"306.215552ms","start":"2026-02-27T20:29:55.240996Z","end":"2026-02-27T20:29:55.547212Z","steps":["trace[2062256343] 'read index received' (duration: 213.196635ms)","trace[2062256343] 'applied index is now lower than readState.Index' (duration: 93.018167ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:29:55.547321Z","caller":"traceutil/trace.go:171","msg":"trace[1323128287] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"372.337102ms","start":"2026-02-27T20:29:55.174977Z","end":"2026-02-27T20:29:55.547314Z","steps":["trace[1323128287] 'process raft request' (duration: 369.538047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:55.547397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:55.174957Z","time spent":"372.382004ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:29:55.547537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.545369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:29:55.547562Z","caller":"traceutil/trace.go:171","msg":"trace[206798373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:733; }","duration":"306.594921ms","start":"2026-02-27T20:29:55.240958Z","end":"2026-02-27T20:29:55.547553Z","steps":["trace[206798373] 'agreement among raft nodes before linearized reading' (duration: 306.521759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:55.547584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:55.240944Z","time spent":"306.634751ms","remote":"127.0.0.1:37602","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-02-27T20:29:55.547682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.453711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-27T20:29:55.547767Z","caller":"traceutil/trace.go:171","msg":"trace[1311912814] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:733; }","duration":"243.587584ms","start":"2026-02-27T20:29:55.304157Z","end":"2026-02-27T20:29:55.547744Z","steps":["trace[1311912814] 'agreement among raft nodes before linearized reading' (duration: 243.349999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:56.237609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.793547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:29:56.237698Z","caller":"traceutil/trace.go:171","msg":"trace[1189176021] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:757; }","duration":"380.200734ms","start":"2026-02-27T20:29:55.857483Z","end":"2026-02-27T20:29:56.237684Z","steps":["trace[1189176021] 'read index received' (duration: 58.991µs)","trace[1189176021] 'applied index is now lower than readState.Index' (duration: 380.137923ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:29:56.237739Z","caller":"traceutil/trace.go:171","msg":"trace[1881500712] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"686.493709ms","start":"2026-02-27T20:29:55.551218Z","end":"2026-02-27T20:29:56.237712Z","steps":["trace[1881500712] 'process raft request' (duration: 114.516797ms)","trace[1881500712] 'compare' (duration: 571.4829ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:29:56.237819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.354248ms","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-02-27T20:29:56.237839Z","caller":"traceutil/trace.go:171","msg":"trace[2008222235] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:734; }","duration":"380.41879ms","start":"2026-02-27T20:29:55.857414Z","end":"2026-02-27T20:29:56.237832Z","steps":["trace[2008222235] 'agreement among raft nodes before linearized reading' (duration: 380.306617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:56.237862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:55.857382Z","time spent":"380.474771ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T20:29:56.237896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:55.551199Z","time spent":"686.623272ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:29:57.772039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.801643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-27T20:29:57.772108Z","caller":"traceutil/trace.go:171","msg":"trace[731123578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:737; }","duration":"220.913716ms","start":"2026-02-27T20:29:57.551178Z","end":"2026-02-27T20:29:57.772092Z","steps":["trace[731123578] 'range keys from in-memory index tree' (duration: 220.612158ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:57.901114Z","caller":"traceutil/trace.go:171","msg":"trace[2100622106] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"125.517554ms","start":"2026-02-27T20:29:57.775334Z","end":"2026-02-27T20:29:57.900852Z","steps":["trace[2100622106] 'process raft request' (duration: 125.36982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:58.743041Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763703310859043,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:29:59.299095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.230715594s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-27T20:29:59.299167Z","caller":"traceutil/trace.go:171","msg":"trace[448507271] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:738; }","duration":"1.230831997s","start":"2026-02-27T20:29:58.068318Z","end":"2026-02-27T20:29:59.29915Z","steps":["trace[448507271] 'range keys from in-memory index tree' (duration: 1.230526539s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:59.299206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.179501ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:29:59.299317Z","caller":"traceutil/trace.go:171","msg":"trace[1810822579] linearizableReadLoop","detail":"{readStateIndex:763; appliedIndex:762; }","duration":"1.056967917s","start":"2026-02-27T20:29:58.242333Z","end":"2026-02-27T20:29:59.299301Z","steps":["trace[1810822579] 'read index received' (duration: 707.483629ms)","trace[1810822579] 'applied index is now lower than readState.Index' (duration: 349.482868ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:29:59.29935Z","caller":"traceutil/trace.go:171","msg":"trace[716490282] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"1.222585544s","start":"2026-02-27T20:29:58.07674Z","end":"2026-02-27T20:29:59.299326Z","steps":["trace[716490282] 'process raft request' (duration: 873.208418ms)","trace[716490282] 'compare' (duration: 348.955406ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:29:59.299462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:58.076723Z","time spent":"1.222672707s","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:29:59.299482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05709331s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:29:59.299511Z","caller":"traceutil/trace.go:171","msg":"trace[290171747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:739; }","duration":"1.057260374s","start":"2026-02-27T20:29:58.242242Z","end":"2026-02-27T20:29:59.299502Z","steps":["trace[290171747] 'agreement among raft nodes before linearized reading' (duration: 1.057139021s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:59.299545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:58.242205Z","time spent":"1.057332216s","remote":"127.0.0.1:37602","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-02-27T20:29:59.299847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.456408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:29:59.299892Z","caller":"traceutil/trace.go:171","msg":"trace[1712336737] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:739; }","duration":"622.539719ms","start":"2026-02-27T20:29:58.677341Z","end":"2026-02-27T20:29:59.299881Z","steps":["trace[1712336737] 'agreement among raft nodes before linearized reading' (duration: 622.472007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:59.299205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:58.068299Z","time spent":"1.230897538s","remote":"127.0.0.1:37628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"warn","ts":"2026-02-27T20:29:59.299923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:58.677314Z","time spent":"622.601152ms","remote":"127.0.0.1:37984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-27T20:29:59.299934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054022879s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-27T20:29:59.299957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.096975ms","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-02-27T20:29:59.300024Z","caller":"traceutil/trace.go:171","msg":"trace[167572137] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:739; }","duration":"1.054174004s","start":"2026-02-27T20:29:58.245826Z","end":"2026-02-27T20:29:59.3Z","steps":["trace[167572137] 'agreement among raft nodes before linearized reading' (duration: 1.053925497s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:59.299957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.199604ms","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-02-27T20:29:59.300072Z","caller":"traceutil/trace.go:171","msg":"trace[585826947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:739; }","duration":"118.338787ms","start":"2026-02-27T20:29:59.181723Z","end":"2026-02-27T20:29:59.300062Z","steps":["trace[585826947] 'agreement among raft nodes before linearized reading' (duration: 118.09903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:59.300083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:58.245806Z","time spent":"1.054262395s","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-27T20:29:59.300031Z","caller":"traceutil/trace.go:171","msg":"trace[1452783712] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:739; }","duration":"970.166277ms","start":"2026-02-27T20:29:58.329841Z","end":"2026-02-27T20:29:59.300008Z","steps":["trace[1452783712] 'agreement among raft nodes before linearized reading' (duration: 970.021132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:59.300269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:58.329768Z","time spent":"970.489044ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T20:29:59.620084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.822967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:29:59.620296Z","caller":"traceutil/trace.go:171","msg":"trace[517629246] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"223.838423ms","start":"2026-02-27T20:29:59.396445Z","end":"2026-02-27T20:29:59.620284Z","steps":["trace[517629246] 'process raft request' (duration: 223.809242ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:59.620515Z","caller":"traceutil/trace.go:171","msg":"trace[129573176] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"316.42332ms","start":"2026-02-27T20:29:59.304084Z","end":"2026-02-27T20:29:59.620508Z","steps":["trace[129573176] 'process raft request' (duration: 173.116763ms)","trace[129573176] 'compare' (duration: 142.660352ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:29:59.620567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:59.304062Z","time spent":"316.472741ms","remote":"127.0.0.1:37856","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-02-27T20:29:59.620559Z","caller":"traceutil/trace.go:171","msg":"trace[1133932070] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:764; }","duration":"305.69745ms","start":"2026-02-27T20:29:59.314828Z","end":"2026-02-27T20:29:59.620526Z","steps":["trace[1133932070] 'read index received' (duration: 80.829643ms)","trace[1133932070] 'applied index is now lower than readState.Index' (duration: 224.864256ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:29:59.620636Z","caller":"traceutil/trace.go:171","msg":"trace[321248205] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"316.538723ms","start":"2026-02-27T20:29:59.304084Z","end":"2026-02-27T20:29:59.620623Z","steps":["trace[321248205] 'process raft request' (duration: 316.082132ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:59.620722Z","caller":"traceutil/trace.go:171","msg":"trace[481339836] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"298.151624ms","start":"2026-02-27T20:29:59.322554Z","end":"2026-02-27T20:29:59.620705Z","steps":["trace[481339836] 'process raft request' (duration: 297.670093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:59.620733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:59.30406Z","time spent":"316.610084ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:29:59.620856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.026987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:29:59.62088Z","caller":"traceutil/trace.go:171","msg":"trace[1046509114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:743; }","duration":"306.086658ms","start":"2026-02-27T20:29:59.314786Z","end":"2026-02-27T20:29:59.620873Z","steps":["trace[1046509114] 'agreement among raft nodes before linearized reading' (duration: 306.037257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:29:59.620899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:29:59.314733Z","time spent":"306.16189ms","remote":"127.0.0.1:37602","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-02-27T20:29:59.819817Z","caller":"traceutil/trace.go:171","msg":"trace[1055677078] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:768; }","duration":"193.803374ms","start":"2026-02-27T20:29:59.625992Z","end":"2026-02-27T20:29:59.819795Z","steps":["trace[1055677078] 'read index received' (duration: 152.854969ms)","trace[1055677078] 'applied index is now lower than readState.Index' (duration: 40.947745ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:29:59.819925Z","caller":"traceutil/trace.go:171","msg":"trace[245872813] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"194.52173ms","start":"2026-02-27T20:29:59.625369Z","end":"2026-02-27T20:29:59.81989Z","steps":["trace[245872813] 'process raft request' (duration: 153.487044ms)","trace[245872813] 'compare' (duration: 40.856952ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:29:59.819958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.948167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-27T20:29:59.820089Z","caller":"traceutil/trace.go:171","msg":"trace[1243571777] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:744; }","duration":"194.101471ms","start":"2026-02-27T20:29:59.625969Z","end":"2026-02-27T20:29:59.820071Z","steps":["trace[1243571777] 'agreement among raft nodes before linearized reading' (duration: 193.903726ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:30:16.133238Z","caller":"traceutil/trace.go:171","msg":"trace[2027028363] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:822; }","duration":"152.368858ms","start":"2026-02-27T20:30:15.980845Z","end":"2026-02-27T20:30:16.133214Z","steps":["trace[2027028363] 'read index received' (duration: 152.115302ms)","trace[2027028363] 'applied index is now lower than readState.Index' (duration: 252.616µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:30:16.133359Z","caller":"traceutil/trace.go:171","msg":"trace[1601821731] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"216.865938ms","start":"2026-02-27T20:30:15.916455Z","end":"2026-02-27T20:30:16.13332Z","steps":["trace[1601821731] 'process raft request' (duration: 216.617372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:16.133677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.803009ms","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-02-27T20:30:16.133745Z","caller":"traceutil/trace.go:171","msg":"trace[682761433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:794; }","duration":"152.925532ms","start":"2026-02-27T20:30:15.980805Z","end":"2026-02-27T20:30:16.133731Z","steps":["trace[682761433] 'agreement among raft nodes before linearized reading' (duration: 152.534942ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:30:18.276611Z","caller":"traceutil/trace.go:171","msg":"trace[14233086] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"113.990462ms","start":"2026-02-27T20:30:18.1626Z","end":"2026-02-27T20:30:18.276591Z","steps":["trace[14233086] 'process raft request' (duration: 113.944501ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:30:18.276649Z","caller":"traceutil/trace.go:171","msg":"trace[883486097] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"114.042203ms","start":"2026-02-27T20:30:18.162593Z","end":"2026-02-27T20:30:18.276635Z","steps":["trace[883486097] 'process raft request' (duration: 113.794126ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:30:19.873269Z","caller":"traceutil/trace.go:171","msg":"trace[1560615800] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:831; }","duration":"111.005161ms","start":"2026-02-27T20:30:19.762214Z","end":"2026-02-27T20:30:19.873219Z","steps":["trace[1560615800] 'read index received' (duration: 47.359831ms)","trace[1560615800] 'applied index is now lower than readState.Index' (duration: 63.64392ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:30:19.873336Z","caller":"traceutil/trace.go:171","msg":"trace[585729415] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"109.969397ms","start":"2026-02-27T20:30:19.763342Z","end":"2026-02-27T20:30:19.873311Z","steps":["trace[585729415] 'process raft request' (duration: 109.832513ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:19.873568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.330888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-27T20:30:19.87369Z","caller":"traceutil/trace.go:171","msg":"trace[435102692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:804; }","duration":"111.481712ms","start":"2026-02-27T20:30:19.762178Z","end":"2026-02-27T20:30:19.87366Z","steps":["trace[435102692] 'agreement among raft nodes before linearized reading' (duration: 111.174815ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:30:19.873701Z","caller":"traceutil/trace.go:171","msg":"trace[1468394694] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"133.812606ms","start":"2026-02-27T20:30:19.739444Z","end":"2026-02-27T20:30:19.873257Z","steps":["trace[1468394694] 'process raft request' (duration: 133.495568ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:30:19.992159Z","caller":"traceutil/trace.go:171","msg":"trace[1709398961] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"112.611839ms","start":"2026-02-27T20:30:19.879519Z","end":"2026-02-27T20:30:19.99213Z","steps":["trace[1709398961] 'process raft request' (duration: 88.724828ms)","trace[1709398961] 'compare' (duration: 23.765848ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:30:20.630765Z","caller":"traceutil/trace.go:171","msg":"trace[174087467] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"347.356335ms","start":"2026-02-27T20:30:20.283379Z","end":"2026-02-27T20:30:20.630735Z","steps":["trace[174087467] 'process raft request' (duration: 347.184021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:20.63117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:30:20.283355Z","time spent":"347.495057ms","remote":"127.0.0.1:37856","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-02-27T20:30:20.875682Z","caller":"traceutil/trace.go:171","msg":"trace[276701896] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"592.164389ms","start":"2026-02-27T20:30:20.28349Z","end":"2026-02-27T20:30:20.875654Z","steps":["trace[276701896] 'process raft request' (duration: 563.71829ms)","trace[276701896] 'compare' (duration: 28.331727ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:30:20.875846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:30:20.283462Z","time spent":"592.298703ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:30:21.990923Z","caller":"traceutil/trace.go:171","msg":"trace[944779158] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:838; }","duration":"110.662632ms","start":"2026-02-27T20:30:21.88023Z","end":"2026-02-27T20:30:21.990893Z","steps":["trace[944779158] 'read index received' (duration: 110.462477ms)","trace[944779158] 'applied index is now lower than readState.Index' (duration: 199.015µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:30:21.991016Z","caller":"traceutil/trace.go:171","msg":"trace[1995968565] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"169.248621ms","start":"2026-02-27T20:30:21.821727Z","end":"2026-02-27T20:30:21.990976Z","steps":["trace[1995968565] 'process raft request' (duration: 168.936634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:21.991652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.3998ms","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-02-27T20:30:21.99195Z","caller":"traceutil/trace.go:171","msg":"trace[578332928] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:809; }","duration":"111.604485ms","start":"2026-02-27T20:30:21.880124Z","end":"2026-02-27T20:30:21.991729Z","steps":["trace[578332928] 'agreement among raft nodes before linearized reading' (duration: 110.882337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:22.711767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.762763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:30:22.711895Z","caller":"traceutil/trace.go:171","msg":"trace[491437523] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:839; }","duration":"716.177202ms","start":"2026-02-27T20:30:21.995694Z","end":"2026-02-27T20:30:22.711871Z","steps":["trace[491437523] 'read index received' (duration: 466.099481ms)","trace[491437523] 'applied index is now lower than readState.Index' (duration: 250.075821ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:30:22.711977Z","caller":"traceutil/trace.go:171","msg":"trace[1596482140] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"716.53128ms","start":"2026-02-27T20:30:21.995421Z","end":"2026-02-27T20:30:22.711952Z","steps":["trace[1596482140] 'process raft request' (duration: 466.473139ms)","trace[1596482140] 'compare' (duration: 249.380665ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:30:22.712105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:30:21.995398Z","time spent":"716.655693ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:30:22.712136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.437488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-27T20:30:22.712183Z","caller":"traceutil/trace.go:171","msg":"trace[1228515526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:810; }","duration":"716.51973ms","start":"2026-02-27T20:30:21.995649Z","end":"2026-02-27T20:30:22.712169Z","steps":["trace[1228515526] 'agreement among raft nodes before linearized reading' (duration: 716.341476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:22.712242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:30:21.995632Z","time spent":"716.591311ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-27T20:30:22.712586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.521781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:30:22.712626Z","caller":"traceutil/trace.go:171","msg":"trace[469714774] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:810; }","duration":"144.606353ms","start":"2026-02-27T20:30:22.568009Z","end":"2026-02-27T20:30:22.712615Z","steps":["trace[469714774] 'agreement among raft nodes before linearized reading' (duration: 144.539672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:22.712737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.134742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:30:22.713379Z","caller":"traceutil/trace.go:171","msg":"trace[1567958300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:810; }","duration":"469.988482ms","start":"2026-02-27T20:30:22.243378Z","end":"2026-02-27T20:30:22.713366Z","steps":["trace[1567958300] 'agreement among raft nodes before linearized reading' (duration: 469.120062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:22.713429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:30:22.243356Z","time spent":"470.062553ms","remote":"127.0.0.1:37602","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-02-27T20:30:23.380445Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763703310859260,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:30:23.881132Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763703310859260,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:30:24.381741Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763703310859260,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:30:24.574791Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.014320571s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-27T20:30:24.876707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.888065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:30:24.876922Z","caller":"traceutil/trace.go:171","msg":"trace[266801673] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"2.160387167s","start":"2026-02-27T20:30:22.716446Z","end":"2026-02-27T20:30:24.876833Z","steps":["trace[266801673] 'process raft request' (duration: 1.858706363s)","trace[266801673] 'compare' (duration: 300.483695ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:30:24.877204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:30:22.716423Z","time spent":"2.160618383s","remote":"127.0.0.1:37856","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-02-27T20:30:25.036037Z","caller":"traceutil/trace.go:171","msg":"trace[325748311] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"2.319325291s","start":"2026-02-27T20:30:22.716691Z","end":"2026-02-27T20:30:25.036016Z","steps":["trace[325748311] 'process raft request' (duration: 2.319155467s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:25.03618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:30:22.716674Z","time spent":"2.319418873s","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:30:25.064667Z","caller":"traceutil/trace.go:171","msg":"trace[127419075] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:840; }","duration":"2.184587555s","start":"2026-02-27T20:30:22.880034Z","end":"2026-02-27T20:30:25.064622Z","steps":["trace[127419075] 'read index received' (duration: 1.695091557s)","trace[127419075] 'applied index is now lower than readState.Index' (duration: 489.492198ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:30:25.064892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.184843381s","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-02-27T20:30:25.064997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.928061ms","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-02-27T20:30:25.064987Z","caller":"traceutil/trace.go:171","msg":"trace[1590148500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:812; }","duration":"2.184981724s","start":"2026-02-27T20:30:22.879977Z","end":"2026-02-27T20:30:25.064959Z","steps":["trace[1590148500] 'agreement among raft nodes before linearized reading' (duration: 2.184743049s)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:30:25.065037Z","caller":"traceutil/trace.go:171","msg":"trace[58827371] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:812; }","duration":"345.996922ms","start":"2026-02-27T20:30:24.719031Z","end":"2026-02-27T20:30:25.065028Z","steps":["trace[58827371] 'agreement among raft nodes before linearized reading' (duration: 345.9065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:25.065059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:30:24.719011Z","time spent":"346.042864ms","remote":"127.0.0.1:37856","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-02-27T20:30:25.065061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:30:22.879959Z","time spent":"2.185087586s","remote":"127.0.0.1:37856","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-02-27T20:30:25.06511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.125142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T20:30:25.065141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.462008ms","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-02-27T20:30:25.065176Z","caller":"traceutil/trace.go:171","msg":"trace[1358036820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:812; }","duration":"822.209223ms","start":"2026-02-27T20:30:24.242956Z","end":"2026-02-27T20:30:25.065165Z","steps":["trace[1358036820] 'agreement among raft nodes before linearized reading' (duration: 822.121101ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:30:25.065196Z","caller":"traceutil/trace.go:171","msg":"trace[465651651] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:812; }","duration":"484.55781ms","start":"2026-02-27T20:30:24.580622Z","end":"2026-02-27T20:30:25.06518Z","steps":["trace[465651651] 'agreement among raft nodes before linearized reading' (duration: 484.459408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:25.065204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:30:24.242944Z","time spent":"822.250344ms","remote":"127.0.0.1:37602","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-02-27T20:30:25.065238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:30:24.580603Z","time spent":"484.622602ms","remote":"127.0.0.1:38070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-27T20:30:25.065393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070046471s","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-02-27T20:30:25.065459Z","caller":"traceutil/trace.go:171","msg":"trace[1623298612] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:812; }","duration":"1.070189145s","start":"2026-02-27T20:30:23.995251Z","end":"2026-02-27T20:30:25.06544Z","steps":["trace[1623298612] 'agreement among raft nodes before linearized reading' (duration: 1.069625221s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:25.065576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:30:23.995228Z","time spent":"1.070333587s","remote":"127.0.0.1:37856","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-02-27T20:30:34.045407Z","caller":"traceutil/trace.go:171","msg":"trace[1377022714] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"132.103646ms","start":"2026-02-27T20:30:33.913257Z","end":"2026-02-27T20:30:34.045361Z","steps":["trace[1377022714] 'process raft request' (duration: 69.888809ms)","trace[1377022714] 'compare' (duration: 62.038701ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:30:34.746047Z","caller":"traceutil/trace.go:171","msg":"trace[2067265440] linearizableReadLoop","detail":"{readStateIndex:899; appliedIndex:897; }","duration":"185.735916ms","start":"2026-02-27T20:30:34.560285Z","end":"2026-02-27T20:30:34.746021Z","steps":["trace[2067265440] 'read index received' (duration: 134.348178ms)","trace[2067265440] 'applied index is now lower than readState.Index' (duration: 51.386798ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:30:34.746103Z","caller":"traceutil/trace.go:171","msg":"trace[301183349] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"238.868185ms","start":"2026-02-27T20:30:34.507188Z","end":"2026-02-27T20:30:34.746056Z","steps":["trace[301183349] 'process raft request' (duration: 238.775433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:34.746226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.91297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:30:34.746231Z","caller":"traceutil/trace.go:171","msg":"trace[446203420] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"243.193787ms","start":"2026-02-27T20:30:34.503003Z","end":"2026-02-27T20:30:34.746197Z","steps":["trace[446203420] 'process raft request' (duration: 191.590854ms)","trace[446203420] 'compare' (duration: 51.159393ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:30:34.746379Z","caller":"traceutil/trace.go:171","msg":"trace[1426807571] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:865; }","duration":"186.058164ms","start":"2026-02-27T20:30:34.560244Z","end":"2026-02-27T20:30:34.746302Z","steps":["trace[1426807571] 'agreement among raft nodes before linearized reading' (duration: 185.8811ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:30:34.924991Z","caller":"traceutil/trace.go:171","msg":"trace[1905245337] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"175.328926ms","start":"2026-02-27T20:30:34.74963Z","end":"2026-02-27T20:30:34.924959Z","steps":["trace[1905245337] 'process raft request' (duration: 129.275477ms)","trace[1905245337] 'compare' (duration: 45.912655ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:30:34.948656Z","caller":"traceutil/trace.go:171","msg":"trace[1456132619] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"190.307625ms","start":"2026-02-27T20:30:34.758318Z","end":"2026-02-27T20:30:34.948625Z","steps":["trace[1456132619] 'process raft request' (duration: 190.177641ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:30:34.948888Z","caller":"traceutil/trace.go:171","msg":"trace[1809938020] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"184.080096ms","start":"2026-02-27T20:30:34.764775Z","end":"2026-02-27T20:30:34.948855Z","steps":["trace[1809938020] 'process raft request' (duration: 183.785629ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:30:34.949016Z","caller":"traceutil/trace.go:171","msg":"trace[422651487] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"196.328808ms","start":"2026-02-27T20:30:34.752624Z","end":"2026-02-27T20:30:34.948953Z","steps":["trace[422651487] 'process raft request' (duration: 195.813276ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:30:37.291973Z","caller":"traceutil/trace.go:171","msg":"trace[995450887] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"305.868234ms","start":"2026-02-27T20:30:36.986082Z","end":"2026-02-27T20:30:37.29195Z","steps":["trace[995450887] 'process raft request' (duration: 305.759702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:37.292098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:30:36.986073Z","time spent":"305.966446ms","remote":"127.0.0.1:37856","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-02-27T20:30:37.29248Z","caller":"traceutil/trace.go:171","msg":"trace[997027525] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:931; }","duration":"207.987008ms","start":"2026-02-27T20:30:37.084455Z","end":"2026-02-27T20:30:37.292442Z","steps":["trace[997027525] 'read index received' (duration: 207.979127ms)","trace[997027525] 'applied index is now lower than readState.Index' (duration: 6.391µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:30:37.292565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.11734ms","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-02-27T20:30:37.292588Z","caller":"traceutil/trace.go:171","msg":"trace[1882021171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:896; }","duration":"208.174382ms","start":"2026-02-27T20:30:37.084407Z","end":"2026-02-27T20:30:37.292581Z","steps":["trace[1882021171] 'agreement among raft nodes before linearized reading' (duration: 208.09984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:37.292707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.649622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-27T20:30:37.292741Z","caller":"traceutil/trace.go:171","msg":"trace[1826068431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:896; }","duration":"133.726993ms","start":"2026-02-27T20:30:37.159002Z","end":"2026-02-27T20:30:37.292729Z","steps":["trace[1826068431] 'agreement among raft nodes before linearized reading' (duration: 133.56953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:37.292853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.436787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-27T20:30:37.29287Z","caller":"traceutil/trace.go:171","msg":"trace[213602670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:896; }","duration":"133.454428ms","start":"2026-02-27T20:30:37.159411Z","end":"2026-02-27T20:30:37.292865Z","steps":["trace[213602670] 'agreement among raft nodes before linearized reading' (duration: 133.412227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:37.292925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.814586ms","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-02-27T20:30:37.292936Z","caller":"traceutil/trace.go:171","msg":"trace[2038101667] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:896; }","duration":"133.855196ms","start":"2026-02-27T20:30:37.159078Z","end":"2026-02-27T20:30:37.292933Z","steps":["trace[2038101667] 'agreement among raft nodes before linearized reading' (duration: 133.822185ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:30:39.693269Z","caller":"traceutil/trace.go:171","msg":"trace[2069464735] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:937; }","duration":"389.666175ms","start":"2026-02-27T20:30:39.303574Z","end":"2026-02-27T20:30:39.69324Z","steps":["trace[2069464735] 'read index received' (duration: 389.48973ms)","trace[2069464735] 'applied index is now lower than readState.Index' (duration: 175.145µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:30:39.693403Z","caller":"traceutil/trace.go:171","msg":"trace[1373910802] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"394.549901ms","start":"2026-02-27T20:30:39.298841Z","end":"2026-02-27T20:30:39.693391Z","steps":["trace[1373910802] 'process raft request' (duration: 394.188543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:39.693509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:30:39.298817Z","time spent":"394.617723ms","remote":"127.0.0.1:37856","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-02-27T20:30:39.693858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.139586ms","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-02-27T20:30:39.693905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.208067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-27T20:30:39.693943Z","caller":"traceutil/trace.go:171","msg":"trace[1592253160] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:902; }","duration":"390.243238ms","start":"2026-02-27T20:30:39.303686Z","end":"2026-02-27T20:30:39.693929Z","steps":["trace[1592253160] 'agreement among raft nodes before linearized reading' (duration: 390.120155ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:30:39.693943Z","caller":"traceutil/trace.go:171","msg":"trace[2007971871] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:902; }","duration":"390.251849ms","start":"2026-02-27T20:30:39.303661Z","end":"2026-02-27T20:30:39.693913Z","steps":["trace[2007971871] 'agreement among raft nodes before linearized reading' (duration: 389.955052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:39.693972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:30:39.303673Z","time spent":"390.29218ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-27T20:30:39.693985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:30:39.303641Z","time spent":"390.33456ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T20:30:39.694935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.348804ms","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-02-27T20:30:39.696185Z","caller":"traceutil/trace.go:171","msg":"trace[703317004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:902; }","duration":"391.963168ms","start":"2026-02-27T20:30:39.303547Z","end":"2026-02-27T20:30:39.69551Z","steps":["trace[703317004] 'agreement among raft nodes before linearized reading' (duration: 389.92435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:39.696232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.549123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-27T20:30:39.69626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:30:39.303534Z","time spent":"392.707847ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T20:30:39.696308Z","caller":"traceutil/trace.go:171","msg":"trace[1225778046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:902; }","duration":"392.626465ms","start":"2026-02-27T20:30:39.303652Z","end":"2026-02-27T20:30:39.696279Z","steps":["trace[1225778046] 'agreement among raft nodes before linearized reading' (duration: 389.931521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:39.696351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:30:39.303627Z","time spent":"392.715288ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-27T20:30:45.982021Z","caller":"traceutil/trace.go:171","msg":"trace[754089765] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"239.12223ms","start":"2026-02-27T20:30:45.742879Z","end":"2026-02-27T20:30:45.982001Z","steps":["trace[754089765] 'read index received' (duration: 238.921735ms)","trace[754089765] 'applied index is now lower than readState.Index' (duration: 199.585µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:30:45.982365Z","caller":"traceutil/trace.go:171","msg":"trace[2090900267] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"241.190579ms","start":"2026-02-27T20:30:45.741162Z","end":"2026-02-27T20:30:45.982353Z","steps":["trace[2090900267] 'process raft request' (duration: 240.691726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:45.982638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.740215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-27T20:30:45.982672Z","caller":"traceutil/trace.go:171","msg":"trace[1987812658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:919; }","duration":"239.809876ms","start":"2026-02-27T20:30:45.742851Z","end":"2026-02-27T20:30:45.982661Z","steps":["trace[1987812658] 'agreement among raft nodes before linearized reading' (duration: 239.683553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:45.982871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.924563ms","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-02-27T20:30:45.982898Z","caller":"traceutil/trace.go:171","msg":"trace[2098836853] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:919; }","duration":"235.964245ms","start":"2026-02-27T20:30:45.746926Z","end":"2026-02-27T20:30:45.98289Z","steps":["trace[2098836853] 'agreement among raft nodes before linearized reading' (duration: 235.886393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:45.983118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.732692ms","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-02-27T20:30:45.983144Z","caller":"traceutil/trace.go:171","msg":"trace[1926055535] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:919; }","duration":"174.781873ms","start":"2026-02-27T20:30:45.808355Z","end":"2026-02-27T20:30:45.983137Z","steps":["trace[1926055535] 'agreement among raft nodes before linearized reading' (duration: 174.705502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:45.983284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.261412ms","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-02-27T20:30:45.983328Z","caller":"traceutil/trace.go:171","msg":"trace[198124253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:919; }","duration":"236.325183ms","start":"2026-02-27T20:30:45.746989Z","end":"2026-02-27T20:30:45.983314Z","steps":["trace[198124253] 'agreement among raft nodes before linearized reading' (duration: 236.2026ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:30:54.377099Z","caller":"traceutil/trace.go:171","msg":"trace[270676057] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"228.717701ms","start":"2026-02-27T20:30:54.148369Z","end":"2026-02-27T20:30:54.377087Z","steps":["trace[270676057] 'read index received' (duration: 228.599358ms)","trace[270676057] 'applied index is now lower than readState.Index' (duration: 117.923µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:30:54.377248Z","caller":"traceutil/trace.go:171","msg":"trace[1282547034] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"282.599858ms","start":"2026-02-27T20:30:54.094641Z","end":"2026-02-27T20:30:54.377241Z","steps":["trace[1282547034] 'process raft request' (duration: 282.360992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:54.377391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.016258ms","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-02-27T20:30:54.377414Z","caller":"traceutil/trace.go:171","msg":"trace[1365284951] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:942; }","duration":"229.069779ms","start":"2026-02-27T20:30:54.148338Z","end":"2026-02-27T20:30:54.377408Z","steps":["trace[1365284951] 'agreement among raft nodes before linearized reading' (duration: 228.998327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:54.377488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.74073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:30:54.377504Z","caller":"traceutil/trace.go:171","msg":"trace[569357408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:942; }","duration":"135.795792ms","start":"2026-02-27T20:30:54.241704Z","end":"2026-02-27T20:30:54.3775Z","steps":["trace[569357408] 'agreement among raft nodes before linearized reading' (duration: 135.770141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:54.377677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.454731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-27T20:30:54.377696Z","caller":"traceutil/trace.go:171","msg":"trace[208605] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:942; }","duration":"129.499892ms","start":"2026-02-27T20:30:54.248192Z","end":"2026-02-27T20:30:54.377692Z","steps":["trace[208605] 'agreement among raft nodes before linearized reading' (duration: 129.454911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:54.377776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.82171ms","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-02-27T20:30:54.377795Z","caller":"traceutil/trace.go:171","msg":"trace[1959389094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:942; }","duration":"129.876332ms","start":"2026-02-27T20:30:54.247915Z","end":"2026-02-27T20:30:54.377791Z","steps":["trace[1959389094] 'agreement among raft nodes before linearized reading' (duration: 129.834621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:54.377868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.109207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-27T20:30:54.377884Z","caller":"traceutil/trace.go:171","msg":"trace[969666246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:942; }","duration":"130.169089ms","start":"2026-02-27T20:30:54.247711Z","end":"2026-02-27T20:30:54.37788Z","steps":["trace[969666246] 'agreement among raft nodes before linearized reading' (duration: 130.131278ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:30:56.675639Z","caller":"traceutil/trace.go:171","msg":"trace[1171831245] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:999; }","duration":"293.406926ms","start":"2026-02-27T20:30:56.38221Z","end":"2026-02-27T20:30:56.675617Z","steps":["trace[1171831245] 'read index received' (duration: 293.197021ms)","trace[1171831245] 'applied index is now lower than readState.Index' (duration: 209.025µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:30:56.676013Z","caller":"traceutil/trace.go:171","msg":"trace[1584572935] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"427.205961ms","start":"2026-02-27T20:30:56.248795Z","end":"2026-02-27T20:30:56.676001Z","steps":["trace[1584572935] 'process raft request' (duration: 426.70547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:56.676122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:30:56.248784Z","time spent":"427.268983ms","remote":"127.0.0.1:37766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5310,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:30:56.67638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.174135ms","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-02-27T20:30:56.676426Z","caller":"traceutil/trace.go:171","msg":"trace[972970920] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:961; }","duration":"289.285067ms","start":"2026-02-27T20:30:56.387129Z","end":"2026-02-27T20:30:56.676414Z","steps":["trace[972970920] 'agreement among raft nodes before linearized reading' (duration: 289.134874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:56.676563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.354498ms","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-02-27T20:30:56.676575Z","caller":"traceutil/trace.go:171","msg":"trace[1779414394] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:961; }","duration":"294.42252ms","start":"2026-02-27T20:30:56.382149Z","end":"2026-02-27T20:30:56.676571Z","steps":["trace[1779414394] 'agreement among raft nodes before linearized reading' (duration: 294.385919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:56.676665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.600183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-htpz9\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-02-27T20:30:56.676679Z","caller":"traceutil/trace.go:171","msg":"trace[988718493] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-htpz9; range_end:; response_count:1; response_revision:961; }","duration":"162.646433ms","start":"2026-02-27T20:30:56.514027Z","end":"2026-02-27T20:30:56.676674Z","steps":["trace[988718493] 'agreement among raft nodes before linearized reading' (duration: 162.597412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:56.676765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.686445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:30:56.676784Z","caller":"traceutil/trace.go:171","msg":"trace[1276485769] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:961; }","duration":"268.709246ms","start":"2026-02-27T20:30:56.408071Z","end":"2026-02-27T20:30:56.676781Z","steps":["trace[1276485769] 'agreement among raft nodes before linearized reading' (duration: 268.678295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:56.676925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.164291ms","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-02-27T20:30:56.676975Z","caller":"traceutil/trace.go:171","msg":"trace[772001859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:961; }","duration":"288.251223ms","start":"2026-02-27T20:30:56.388707Z","end":"2026-02-27T20:30:56.676958Z","steps":["trace[772001859] 'agreement among raft nodes before linearized reading' (duration: 288.103939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:56.677089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.009787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-27T20:30:56.677105Z","caller":"traceutil/trace.go:171","msg":"trace[1239929520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:961; }","duration":"288.036398ms","start":"2026-02-27T20:30:56.389065Z","end":"2026-02-27T20:30:56.677101Z","steps":["trace[1239929520] 'agreement among raft nodes before linearized reading' (duration: 287.992977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:56.677178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.11759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-27T20:30:56.677209Z","caller":"traceutil/trace.go:171","msg":"trace[1333492131] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:961; }","duration":"288.190622ms","start":"2026-02-27T20:30:56.389009Z","end":"2026-02-27T20:30:56.6772Z","steps":["trace[1333492131] 'agreement among raft nodes before linearized reading' (duration: 288.099299ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:30:58.381289Z","caller":"traceutil/trace.go:171","msg":"trace[1884606384] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"302.092623ms","start":"2026-02-27T20:30:58.079174Z","end":"2026-02-27T20:30:58.381267Z","steps":["trace[1884606384] 'process raft request' (duration: 292.34647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:58.381471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:30:58.079156Z","time spent":"302.200036ms","remote":"127.0.0.1:37628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:31:01.286932Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763703310859643,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:31:01.786885Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.295101064s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T20:31:01.7874Z","caller":"traceutil/trace.go:171","msg":"trace[1301028159] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1024; }","duration":"1.000997613s","start":"2026-02-27T20:31:00.786378Z","end":"2026-02-27T20:31:01.787376Z","steps":["trace[1301028159] 'read index received' (duration: 1.000628845s)","trace[1301028159] 'applied index is now lower than readState.Index' (duration: 367.638µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:31:01.787579Z","caller":"traceutil/trace.go:171","msg":"trace[2024110489] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"1.295902574s","start":"2026-02-27T20:31:00.491645Z","end":"2026-02-27T20:31:01.787547Z","steps":["trace[2024110489] 'process raft request' (duration: 1.295484684s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:01.787769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:00.49163Z","time spent":"1.296042367s","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:31:01.787814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001383893s","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-02-27T20:31:01.787902Z","caller":"traceutil/trace.go:171","msg":"trace[504172754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:985; }","duration":"1.001532006s","start":"2026-02-27T20:31:00.786348Z","end":"2026-02-27T20:31:01.78788Z","steps":["trace[504172754] 'agreement among raft nodes before linearized reading' (duration: 1.001233899s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:01.787949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:00.786333Z","time spent":"1.001606569s","remote":"127.0.0.1:37856","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-02-27T20:31:01.787964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001207059s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-27T20:31:01.788061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.296332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-27T20:31:01.788064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.223279ms","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-02-27T20:31:01.788104Z","caller":"traceutil/trace.go:171","msg":"trace[1924076739] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:985; }","duration":"424.375744ms","start":"2026-02-27T20:31:01.36371Z","end":"2026-02-27T20:31:01.788086Z","steps":["trace[1924076739] 'agreement among raft nodes before linearized reading' (duration: 424.325503ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:01.788107Z","caller":"traceutil/trace.go:171","msg":"trace[1871113295] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:985; }","duration":"996.369062ms","start":"2026-02-27T20:31:00.791724Z","end":"2026-02-27T20:31:01.788093Z","steps":["trace[1871113295] 'agreement among raft nodes before linearized reading' (duration: 996.27866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:01.788134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:01.363684Z","time spent":"424.443545ms","remote":"127.0.0.1:37756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-27T20:31:01.788142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:00.791704Z","time spent":"996.429554ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-27T20:31:01.788058Z","caller":"traceutil/trace.go:171","msg":"trace[261623811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:985; }","duration":"1.001339662s","start":"2026-02-27T20:31:00.7867Z","end":"2026-02-27T20:31:01.78804Z","steps":["trace[261623811] 'agreement among raft nodes before linearized reading' (duration: 1.001099106s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:01.788224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.762756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-27T20:31:01.788232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:00.786695Z","time spent":"1.001525346s","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-27T20:31:01.787964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.373657ms","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-02-27T20:31:01.78793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.169982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:31:01.789515Z","caller":"traceutil/trace.go:171","msg":"trace[857073496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:985; }","duration":"547.799021ms","start":"2026-02-27T20:31:01.241704Z","end":"2026-02-27T20:31:01.789503Z","steps":["trace[857073496] 'agreement among raft nodes before linearized reading' (duration: 546.133631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:01.789559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:01.241689Z","time spent":"547.852761ms","remote":"127.0.0.1:37602","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-02-27T20:31:01.789457Z","caller":"traceutil/trace.go:171","msg":"trace[1121057462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:985; }","duration":"998.81495ms","start":"2026-02-27T20:31:00.790557Z","end":"2026-02-27T20:31:01.789372Z","steps":["trace[1121057462] 'agreement among raft nodes before linearized reading' (duration: 997.297705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:01.790003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:00.790551Z","time spent":"999.438895ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-27T20:31:01.79041Z","caller":"traceutil/trace.go:171","msg":"trace[322136739] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:985; }","duration":"998.138085ms","start":"2026-02-27T20:31:00.790392Z","end":"2026-02-27T20:31:01.78853Z","steps":["trace[322136739] 'agreement among raft nodes before linearized reading' (duration: 997.689354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:01.790456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:00.790386Z","time spent":"1.000057451s","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T20:31:02.244645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.029946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:31:02.244705Z","caller":"traceutil/trace.go:171","msg":"trace[1608945329] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"452.29455ms","start":"2026-02-27T20:31:01.7924Z","end":"2026-02-27T20:31:02.244695Z","steps":["trace[1608945329] 'process raft request' (duration: 309.159492ms)","trace[1608945329] 'compare' (duration: 142.78023ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:31:02.244752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:01.792377Z","time spent":"452.360632ms","remote":"127.0.0.1:37856","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-02-27T20:31:02.260914Z","caller":"traceutil/trace.go:171","msg":"trace[982256286] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"465.883775ms","start":"2026-02-27T20:31:01.795008Z","end":"2026-02-27T20:31:02.260892Z","steps":["trace[982256286] 'process raft request' (duration: 465.853794ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:02.260972Z","caller":"traceutil/trace.go:171","msg":"trace[2061075091] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"466.335366ms","start":"2026-02-27T20:31:01.794614Z","end":"2026-02-27T20:31:02.260949Z","steps":["trace[2061075091] 'process raft request' (duration: 466.200532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:02.261011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:01.794987Z","time spent":"465.972828ms","remote":"127.0.0.1:37856","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-02-27T20:31:02.261077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:01.794608Z","time spent":"466.419207ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:31:02.26105Z","caller":"traceutil/trace.go:171","msg":"trace[1589588827] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"466.048949ms","start":"2026-02-27T20:31:01.794963Z","end":"2026-02-27T20:31:02.261012Z","steps":["trace[1589588827] 'process raft request' (duration: 465.877015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:02.261263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:01.794957Z","time spent":"466.209163ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:31:02.261558Z","caller":"traceutil/trace.go:171","msg":"trace[1782915547] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"467.900892ms","start":"2026-02-27T20:31:01.793632Z","end":"2026-02-27T20:31:02.261533Z","steps":["trace[1782915547] 'process raft request' (duration: 467.102562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:02.261792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:01.793619Z","time spent":"468.088237ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:31:02.515865Z","caller":"traceutil/trace.go:171","msg":"trace[177953241] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"297.508855ms","start":"2026-02-27T20:31:02.218337Z","end":"2026-02-27T20:31:02.515845Z","steps":["trace[177953241] 'process raft request' (duration: 297.32691ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:02.515995Z","caller":"traceutil/trace.go:171","msg":"trace[1322452842] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1026; }","duration":"273.610653ms","start":"2026-02-27T20:31:02.242366Z","end":"2026-02-27T20:31:02.515976Z","steps":["trace[1322452842] 'read index received' (duration: 18.406148ms)","trace[1322452842] 'applied index is now lower than readState.Index' (duration: 255.203585ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:31:02.516079Z","caller":"traceutil/trace.go:171","msg":"trace[1216268246] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"294.960483ms","start":"2026-02-27T20:31:02.221113Z","end":"2026-02-27T20:31:02.516074Z","steps":["trace[1216268246] 'process raft request' (duration: 294.685397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:02.516161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.783408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:31:02.516212Z","caller":"traceutil/trace.go:171","msg":"trace[868577820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:992; }","duration":"273.874079ms","start":"2026-02-27T20:31:02.242325Z","end":"2026-02-27T20:31:02.516199Z","steps":["trace[868577820] 'agreement among raft nodes before linearized reading' (duration: 273.788487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:02.516212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.951403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T20:31:02.516293Z","caller":"traceutil/trace.go:171","msg":"trace[1875482384] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:992; }","duration":"251.084696ms","start":"2026-02-27T20:31:02.265191Z","end":"2026-02-27T20:31:02.516275Z","steps":["trace[1875482384] 'agreement among raft nodes before linearized reading' (duration: 250.925642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:02.86395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.449012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-27T20:31:02.864207Z","caller":"traceutil/trace.go:171","msg":"trace[1265406603] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:992; }","duration":"199.726189ms","start":"2026-02-27T20:31:02.664413Z","end":"2026-02-27T20:31:02.864139Z","steps":["trace[1265406603] 'range keys from in-memory index tree' (duration: 199.276939ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:03.006584Z","caller":"traceutil/trace.go:171","msg":"trace[1175287012] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"137.949045ms","start":"2026-02-27T20:31:02.868612Z","end":"2026-02-27T20:31:03.006561Z","steps":["trace[1175287012] 'process raft request' (duration: 137.77115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:03.3844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.229806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:31:03.384478Z","caller":"traceutil/trace.go:171","msg":"trace[268348927] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:993; }","duration":"142.353899ms","start":"2026-02-27T20:31:03.242112Z","end":"2026-02-27T20:31:03.384466Z","steps":["trace[268348927] 'range keys from in-memory index tree' (duration: 142.139204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:03.63739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.203468ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-27T20:31:04.286522Z","caller":"traceutil/trace.go:171","msg":"trace[125555660] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1034; }","duration":"313.657819ms","start":"2026-02-27T20:31:03.972839Z","end":"2026-02-27T20:31:04.286496Z","steps":["trace[125555660] 'read index received' (duration: 313.512016ms)","trace[125555660] 'applied index is now lower than readState.Index' (duration: 144.713µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:31:04.286898Z","caller":"traceutil/trace.go:171","msg":"trace[1231919517] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"393.844944ms","start":"2026-02-27T20:31:03.89304Z","end":"2026-02-27T20:31:04.286885Z","steps":["trace[1231919517] 'process raft request' (duration: 393.317892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:04.286988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:03.893025Z","time spent":"393.907757ms","remote":"127.0.0.1:37682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":884,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T20:31:04.287631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.772065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:31:04.287733Z","caller":"traceutil/trace.go:171","msg":"trace[825510968] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:994; }","duration":"314.917159ms","start":"2026-02-27T20:31:03.97279Z","end":"2026-02-27T20:31:04.287707Z","steps":["trace[825510968] 'agreement among raft nodes before linearized reading' (duration: 314.779576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:04.287794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:03.972738Z","time spent":"315.040353ms","remote":"127.0.0.1:38094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-27T20:31:05.483828Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.190730502s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-27T20:31:05.660963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.892524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:31:05.661065Z","caller":"traceutil/trace.go:171","msg":"trace[550300544] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"1.368045377s","start":"2026-02-27T20:31:04.293Z","end":"2026-02-27T20:31:05.661046Z","steps":["trace[550300544] 'process raft request' (duration: 1.190992669s)","trace[550300544] 'compare' (duration: 176.653758ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:31:05.661244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:04.292974Z","time spent":"1.368162859s","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:31:05.895761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.076932ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:31:05.896132Z","caller":"traceutil/trace.go:171","msg":"trace[2011260079] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1035; }","duration":"828.460263ms","start":"2026-02-27T20:31:05.067654Z","end":"2026-02-27T20:31:05.896114Z","steps":["trace[2011260079] 'read index received' (duration: 416.505796ms)","trace[2011260079] 'applied index is now lower than readState.Index' (duration: 411.953217ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:31:05.896166Z","caller":"traceutil/trace.go:171","msg":"trace[1574352540] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"1.602107365s","start":"2026-02-27T20:31:04.294023Z","end":"2026-02-27T20:31:05.896131Z","steps":["trace[1574352540] 'process raft request' (duration: 1.467585573s)","trace[1574352540] 'compare' (duration: 133.959239ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:31:05.896265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.601456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T20:31:05.896337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:04.294011Z","time spent":"1.602239479s","remote":"127.0.0.1:37856","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-02-27T20:31:05.896331Z","caller":"traceutil/trace.go:171","msg":"trace[734862701] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:999; }","duration":"828.700648ms","start":"2026-02-27T20:31:05.067619Z","end":"2026-02-27T20:31:05.896319Z","steps":["trace[734862701] 'agreement among raft nodes before linearized reading' (duration: 828.557634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:05.896396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:05.067603Z","time spent":"828.78178ms","remote":"127.0.0.1:37818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-02-27T20:31:05.896511Z","caller":"traceutil/trace.go:171","msg":"trace[980637384] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"1.602312511s","start":"2026-02-27T20:31:04.294188Z","end":"2026-02-27T20:31:05.896501Z","steps":["trace[980637384] 'process raft request' (duration: 1.601748108s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:05.896569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:04.294183Z","time spent":"1.602357392s","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:31:05.896647Z","caller":"traceutil/trace.go:171","msg":"trace[705606167] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"1.601316316s","start":"2026-02-27T20:31:04.295322Z","end":"2026-02-27T20:31:05.896638Z","steps":["trace[705606167] 'process raft request' (duration: 1.600746013s)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:05.896649Z","caller":"traceutil/trace.go:171","msg":"trace[1377784894] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"1.602549516s","start":"2026-02-27T20:31:04.294078Z","end":"2026-02-27T20:31:05.896627Z","steps":["trace[1377784894] 'process raft request' (duration: 1.601773908s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:05.896697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:04.295297Z","time spent":"1.601375908s","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:31:05.896774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:04.294073Z","time spent":"1.602633888s","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:31:07.932019Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763703310859672,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:31:08.432968Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763703310859672,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:31:08.734867Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.618290912s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T20:31:08.735259Z","caller":"traceutil/trace.go:171","msg":"trace[921205906] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"1.304268294s","start":"2026-02-27T20:31:07.430972Z","end":"2026-02-27T20:31:08.73524Z","steps":["trace[921205906] 'read index received' (duration: 1.304044909s)","trace[921205906] 'applied index is now lower than readState.Index' (duration: 220.895µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:31:08.735438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.304432028s","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-02-27T20:31:08.735469Z","caller":"traceutil/trace.go:171","msg":"trace[1122398007] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1000; }","duration":"1.304521771s","start":"2026-02-27T20:31:07.430938Z","end":"2026-02-27T20:31:08.73546Z","steps":["trace[1122398007] 'agreement among raft nodes before linearized reading' (duration: 1.304413018s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:08.735496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:07.430923Z","time spent":"1.304566591s","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-27T20:31:08.735689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070082882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-27T20:31:08.735784Z","caller":"traceutil/trace.go:171","msg":"trace[1556866844] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1000; }","duration":"1.070231976s","start":"2026-02-27T20:31:07.665537Z","end":"2026-02-27T20:31:08.735769Z","steps":["trace[1556866844] 'agreement among raft nodes before linearized reading' (duration: 1.069914958s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:08.735881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.198919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-27T20:31:08.735976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:07.665521Z","time spent":"1.070384549s","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-27T20:31:08.736663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.10206ms","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-02-27T20:31:08.736794Z","caller":"traceutil/trace.go:171","msg":"trace[1880254961] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1000; }","duration":"835.295306ms","start":"2026-02-27T20:31:07.901486Z","end":"2026-02-27T20:31:08.736781Z","steps":["trace[1880254961] 'agreement among raft nodes before linearized reading' (duration: 834.998279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:08.736893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:07.901482Z","time spent":"835.398958ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T20:31:08.737174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.274009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-27T20:31:08.737252Z","caller":"traceutil/trace.go:171","msg":"trace[769554878] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:1000; }","duration":"666.430993ms","start":"2026-02-27T20:31:08.070804Z","end":"2026-02-27T20:31:08.737235Z","steps":["trace[769554878] 'agreement among raft nodes before linearized reading' (duration: 666.202258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:08.737302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:08.070784Z","time spent":"666.508856ms","remote":"127.0.0.1:37628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"warn","ts":"2026-02-27T20:31:08.73739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.044373ms","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-02-27T20:31:08.737463Z","caller":"traceutil/trace.go:171","msg":"trace[962392612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1000; }","duration":"836.127306ms","start":"2026-02-27T20:31:07.901324Z","end":"2026-02-27T20:31:08.737451Z","steps":["trace[962392612] 'agreement among raft nodes before linearized reading' (duration: 835.89566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:08.737544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.200464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:31:08.737592Z","caller":"traceutil/trace.go:171","msg":"trace[62479141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1000; }","duration":"495.321918ms","start":"2026-02-27T20:31:08.242259Z","end":"2026-02-27T20:31:08.737581Z","steps":["trace[62479141] 'agreement among raft nodes before linearized reading' (duration: 495.253306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:08.737611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:07.90132Z","time spent":"836.221438ms","remote":"127.0.0.1:37856","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-02-27T20:31:08.737624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:08.242226Z","time spent":"495.390789ms","remote":"127.0.0.1:37602","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-02-27T20:31:08.737844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.525506ms","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-02-27T20:31:08.737905Z","caller":"traceutil/trace.go:171","msg":"trace[1685983732] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1000; }","duration":"836.608347ms","start":"2026-02-27T20:31:07.901284Z","end":"2026-02-27T20:31:08.737892Z","steps":["trace[1685983732] 'agreement among raft nodes before linearized reading' (duration: 836.491014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:08.737942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:07.90127Z","time spent":"836.664999ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T20:31:08.738042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.721874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-27T20:31:08.738124Z","caller":"traceutil/trace.go:171","msg":"trace[460042393] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1000; }","duration":"726.857977ms","start":"2026-02-27T20:31:08.011247Z","end":"2026-02-27T20:31:08.738105Z","steps":["trace[460042393] 'agreement among raft nodes before linearized reading' (duration: 726.56014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:08.738187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:08.011221Z","time spent":"726.95652ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-27T20:31:08.735902Z","caller":"traceutil/trace.go:171","msg":"trace[1079990847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1000; }","duration":"834.22918ms","start":"2026-02-27T20:31:07.901667Z","end":"2026-02-27T20:31:08.735896Z","steps":["trace[1079990847] 'agreement among raft nodes before linearized reading' (duration: 834.139188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:08.738707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:07.901663Z","time spent":"837.027808ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-27T20:31:08.735338Z","caller":"traceutil/trace.go:171","msg":"trace[256159591] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"1.618835646s","start":"2026-02-27T20:31:07.116471Z","end":"2026-02-27T20:31:08.735306Z","steps":["trace[256159591] 'process raft request' (duration: 1.618667232s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:08.740363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:07.116449Z","time spent":"1.623829544s","remote":"127.0.0.1:37682","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-02-27T20:31:09.593712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.117256ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-27T20:31:09.593826Z","caller":"traceutil/trace.go:171","msg":"trace[1439493596] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"853.130261ms","start":"2026-02-27T20:31:08.740677Z","end":"2026-02-27T20:31:09.593807Z","steps":["trace[1439493596] 'read index received' (duration: 209.877852ms)","trace[1439493596] 'applied index is now lower than readState.Index' (duration: 643.250689ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:31:09.593865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:08.740462Z","time spent":"853.394767ms","remote":"127.0.0.1:37628","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-27T20:31:09.593916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.230493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:31:09.593943Z","caller":"traceutil/trace.go:171","msg":"trace[1172650350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1000; }","duration":"853.286214ms","start":"2026-02-27T20:31:08.740648Z","end":"2026-02-27T20:31:09.593934Z","steps":["trace[1172650350] 'agreement among raft nodes before linearized reading' (duration: 853.228183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:09.593974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:08.740632Z","time spent":"853.335246ms","remote":"127.0.0.1:37600","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-02-27T20:31:10.09289Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.142405573s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T20:31:10.108973Z","caller":"traceutil/trace.go:171","msg":"trace[273236418] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"1.365024573s","start":"2026-02-27T20:31:08.743925Z","end":"2026-02-27T20:31:10.10895Z","steps":["trace[273236418] 'process raft request' (duration: 1.364937771s)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:10.10914Z","caller":"traceutil/trace.go:171","msg":"trace[627881282] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"1.365150437s","start":"2026-02-27T20:31:08.743973Z","end":"2026-02-27T20:31:10.109124Z","steps":["trace[627881282] 'process raft request' (duration: 1.364944731s)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:10.109155Z","caller":"traceutil/trace.go:171","msg":"trace[1090593351] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"1.365096576s","start":"2026-02-27T20:31:08.744033Z","end":"2026-02-27T20:31:10.10913Z","steps":["trace[1090593351] 'process raft request' (duration: 1.364986403s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:10.109191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:08.743919Z","time spent":"1.365196477s","remote":"127.0.0.1:37856","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-02-27T20:31:10.109266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:08.743968Z","time spent":"1.365219148s","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:31:10.109307Z","caller":"traceutil/trace.go:171","msg":"trace[1679854355] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"1.364590454s","start":"2026-02-27T20:31:08.744708Z","end":"2026-02-27T20:31:10.109299Z","steps":["trace[1679854355] 'process raft request' (duration: 1.364385799s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:10.109299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:08.744016Z","time spent":"1.365212818s","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:31:10.109359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:08.744693Z","time spent":"1.364639475s","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:31:10.109036Z","caller":"traceutil/trace.go:171","msg":"trace[184577318] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"1.365663209s","start":"2026-02-27T20:31:08.743346Z","end":"2026-02-27T20:31:10.109009Z","steps":["trace[184577318] 'process raft request' (duration: 1.349912408s)","trace[184577318] 'compare' (duration: 15.441573ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:31:10.10943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:08.743335Z","time spent":"1.366067199s","remote":"127.0.0.1:37856","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-02-27T20:31:10.10961Z","caller":"traceutil/trace.go:171","msg":"trace[707813460] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"1.364314908s","start":"2026-02-27T20:31:08.745287Z","end":"2026-02-27T20:31:10.109602Z","steps":["trace[707813460] 'process raft request' (duration: 1.363957019s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:10.109659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:08.745277Z","time spent":"1.364358529s","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:31:10.109659Z","caller":"traceutil/trace.go:171","msg":"trace[1261954054] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"514.455514ms","start":"2026-02-27T20:31:09.595191Z","end":"2026-02-27T20:31:10.109647Z","steps":["trace[1261954054] 'process raft request' (duration: 514.404032ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:10.109716Z","caller":"traceutil/trace.go:171","msg":"trace[661611578] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1042; }","duration":"515.857468ms","start":"2026-02-27T20:31:09.593852Z","end":"2026-02-27T20:31:10.109709Z","steps":["trace[661611578] 'read index received' (duration: 500.17426ms)","trace[661611578] 'applied index is now lower than readState.Index' (duration: 15.682568ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:31:10.109721Z","caller":"traceutil/trace.go:171","msg":"trace[1903725541] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"1.34178496s","start":"2026-02-27T20:31:08.767925Z","end":"2026-02-27T20:31:10.10971Z","steps":["trace[1903725541] 'process raft request' (duration: 1.341572135s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:10.109728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:09.595167Z","time spent":"514.526126ms","remote":"127.0.0.1:37628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:31:10.109781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:08.7679Z","time spent":"1.341848541s","remote":"127.0.0.1:37682","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-02-27T20:31:10.10981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.109677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T20:31:10.109837Z","caller":"traceutil/trace.go:171","msg":"trace[1183555343] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1008; }","duration":"834.169498ms","start":"2026-02-27T20:31:09.27566Z","end":"2026-02-27T20:31:10.109829Z","steps":["trace[1183555343] 'agreement among raft nodes before linearized reading' (duration: 834.104677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:10.109861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:09.27564Z","time spent":"834.21279ms","remote":"127.0.0.1:52454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-02-27T20:31:10.109973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.264649308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:31:10.11Z","caller":"traceutil/trace.go:171","msg":"trace[711330474] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1008; }","duration":"1.264708449s","start":"2026-02-27T20:31:08.845282Z","end":"2026-02-27T20:31:10.10999Z","steps":["trace[711330474] 'agreement among raft nodes before linearized reading' (duration: 1.264674758s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:10.110016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:08.845264Z","time spent":"1.264747491s","remote":"127.0.0.1:54964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-27T20:31:10.110026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.718714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:31:10.110066Z","caller":"traceutil/trace.go:171","msg":"trace[1617609132] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1008; }","duration":"507.806636ms","start":"2026-02-27T20:31:09.60225Z","end":"2026-02-27T20:31:10.110057Z","steps":["trace[1617609132] 'agreement among raft nodes before linearized reading' (duration: 507.749795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:10.110101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:09.602228Z","time spent":"507.865458ms","remote":"127.0.0.1:37602","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-02-27T20:31:10.110094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.36814141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:31:10.11014Z","caller":"traceutil/trace.go:171","msg":"trace[1733540919] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:1008; }","duration":"1.368221072s","start":"2026-02-27T20:31:08.741906Z","end":"2026-02-27T20:31:10.110127Z","steps":["trace[1733540919] 'agreement among raft nodes before linearized reading' (duration: 1.36815165s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:10.110183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:08.741887Z","time spent":"1.368287803s","remote":"127.0.0.1:37690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-02-27T20:31:10.431319Z","caller":"traceutil/trace.go:171","msg":"trace[129303869] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1050; }","duration":"316.466697ms","start":"2026-02-27T20:31:10.114831Z","end":"2026-02-27T20:31:10.431297Z","steps":["trace[129303869] 'read index received' (duration: 266.591198ms)","trace[129303869] 'applied index is now lower than readState.Index' (duration: 49.874598ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:31:10.431389Z","caller":"traceutil/trace.go:171","msg":"trace[1427032998] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"317.313778ms","start":"2026-02-27T20:31:10.114018Z","end":"2026-02-27T20:31:10.431331Z","steps":["trace[1427032998] 'process raft request' (duration: 267.391569ms)","trace[1427032998] 'compare' (duration: 49.717774ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:31:10.431424Z","caller":"traceutil/trace.go:171","msg":"trace[569605008] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"317.214436ms","start":"2026-02-27T20:31:10.114181Z","end":"2026-02-27T20:31:10.431396Z","steps":["trace[569605008] 'process raft request' (duration: 317.073012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:10.431632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:10.114175Z","time spent":"317.36186ms","remote":"127.0.0.1:37682","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-02-27T20:31:10.431681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:10.114005Z","time spent":"317.477092ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T20:31:10.431682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.829086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-27T20:31:10.431843Z","caller":"traceutil/trace.go:171","msg":"trace[1322516511] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1010; }","duration":"317.020001ms","start":"2026-02-27T20:31:10.11481Z","end":"2026-02-27T20:31:10.43183Z","steps":["trace[1322516511] 'agreement among raft nodes before linearized reading' (duration: 316.57428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:10.432045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:10.114805Z","time spent":"317.150994ms","remote":"127.0.0.1:37628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-02-27T20:31:11.278718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.721493ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:31:11.27888Z","caller":"traceutil/trace.go:171","msg":"trace[1769646292] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1052; }","duration":"216.810144ms","start":"2026-02-27T20:31:11.062055Z","end":"2026-02-27T20:31:11.278865Z","steps":["trace[1769646292] 'read index received' (duration: 62.322µs)","trace[1769646292] 'applied index is now lower than readState.Index' (duration: 216.746872ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:31:11.278959Z","caller":"traceutil/trace.go:171","msg":"trace[636352505] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"841.394746ms","start":"2026-02-27T20:31:10.437551Z","end":"2026-02-27T20:31:11.278946Z","steps":["trace[636352505] 'process raft request' (duration: 235.388746ms)","trace[636352505] 'compare' (duration: 605.60531ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:31:11.279075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:10.437535Z","time spent":"841.445727ms","remote":"127.0.0.1:37682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":869,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T20:31:11.279184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.119631ms","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":"info","ts":"2026-02-27T20:31:11.279246Z","caller":"traceutil/trace.go:171","msg":"trace[2132985439] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1012; }","duration":"217.238705ms","start":"2026-02-27T20:31:11.061993Z","end":"2026-02-27T20:31:11.279231Z","steps":["trace[2132985439] 'agreement among raft nodes before linearized reading' (duration: 217.091541ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:11.27924Z","caller":"traceutil/trace.go:171","msg":"trace[788198403] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"839.25523ms","start":"2026-02-27T20:31:10.43995Z","end":"2026-02-27T20:31:11.279206Z","steps":["trace[788198403] 'process raft request' (duration: 838.85999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:11.27937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:10.439925Z","time spent":"839.372534ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:31:12.408106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.412761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-27T20:31:12.40819Z","caller":"traceutil/trace.go:171","msg":"trace[1816058778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1012; }","duration":"293.518183ms","start":"2026-02-27T20:31:12.114654Z","end":"2026-02-27T20:31:12.408172Z","steps":["trace[1816058778] 'range keys from in-memory index tree' (duration: 293.252167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:12.408396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.833047ms","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-02-27T20:31:12.408472Z","caller":"traceutil/trace.go:171","msg":"trace[1153133412] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1012; }","duration":"293.031332ms","start":"2026-02-27T20:31:12.115423Z","end":"2026-02-27T20:31:12.408455Z","steps":["trace[1153133412] 'range keys from in-memory index tree' (duration: 292.659043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:12.408612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.461107ms","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-02-27T20:31:12.40868Z","caller":"traceutil/trace.go:171","msg":"trace[787619466] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1012; }","duration":"292.552399ms","start":"2026-02-27T20:31:12.11611Z","end":"2026-02-27T20:31:12.408663Z","steps":["trace[787619466] 'range keys from in-memory index tree' (duration: 292.328933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:12.408664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.056748ms","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-02-27T20:31:12.408741Z","caller":"traceutil/trace.go:171","msg":"trace[981611399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1012; }","duration":"294.196711ms","start":"2026-02-27T20:31:12.114525Z","end":"2026-02-27T20:31:12.408721Z","steps":["trace[981611399] 'range keys from in-memory index tree' (duration: 293.887713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:12.408927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.858706ms","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-02-27T20:31:12.408971Z","caller":"traceutil/trace.go:171","msg":"trace[711341916] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1012; }","duration":"292.924449ms","start":"2026-02-27T20:31:12.116038Z","end":"2026-02-27T20:31:12.408962Z","steps":["trace[711341916] 'range keys from in-memory index tree' (duration: 292.690452ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:12.686894Z","caller":"traceutil/trace.go:171","msg":"trace[964151209] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1054; }","duration":"443.6315ms","start":"2026-02-27T20:31:12.243241Z","end":"2026-02-27T20:31:12.686872Z","steps":["trace[964151209] 'read index received' (duration: 443.528398ms)","trace[964151209] 'applied index is now lower than readState.Index' (duration: 101.942µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:31:12.686958Z","caller":"traceutil/trace.go:171","msg":"trace[1481541924] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"536.904912ms","start":"2026-02-27T20:31:12.150021Z","end":"2026-02-27T20:31:12.686926Z","steps":["trace[1481541924] 'process raft request' (duration: 536.723917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:12.687003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.753493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:31:12.687035Z","caller":"traceutil/trace.go:171","msg":"trace[1457973231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1013; }","duration":"443.842615ms","start":"2026-02-27T20:31:12.243183Z","end":"2026-02-27T20:31:12.687025Z","steps":["trace[1457973231] 'agreement among raft nodes before linearized reading' (duration: 443.777403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:12.687067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:12.243163Z","time spent":"443.896987ms","remote":"127.0.0.1:37602","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-02-27T20:31:12.687114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:12.149982Z","time spent":"537.047285ms","remote":"127.0.0.1:37856","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-02-27T20:31:12.699306Z","caller":"traceutil/trace.go:171","msg":"trace[1331503919] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"285.796377ms","start":"2026-02-27T20:31:12.413492Z","end":"2026-02-27T20:31:12.699288Z","steps":["trace[1331503919] 'process raft request' (duration: 285.51544ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:12.699368Z","caller":"traceutil/trace.go:171","msg":"trace[19644740] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"286.585938ms","start":"2026-02-27T20:31:12.412764Z","end":"2026-02-27T20:31:12.69935Z","steps":["trace[19644740] 'process raft request' (duration: 286.075745ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:12.699451Z","caller":"traceutil/trace.go:171","msg":"trace[601049823] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"286.373722ms","start":"2026-02-27T20:31:12.413071Z","end":"2026-02-27T20:31:12.699445Z","steps":["trace[601049823] 'process raft request' (duration: 285.91097ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:12.699329Z","caller":"traceutil/trace.go:171","msg":"trace[1448116127] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"285.296114ms","start":"2026-02-27T20:31:12.41402Z","end":"2026-02-27T20:31:12.699316Z","steps":["trace[1448116127] 'process raft request' (duration: 285.005897ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:12.699855Z","caller":"traceutil/trace.go:171","msg":"trace[1785213710] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"285.339085ms","start":"2026-02-27T20:31:12.414251Z","end":"2026-02-27T20:31:12.69959Z","steps":["trace[1785213710] 'process raft request' (duration: 284.795051ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:15.871709Z","caller":"traceutil/trace.go:171","msg":"trace[146453536] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1083; }","duration":"362.876036ms","start":"2026-02-27T20:31:15.50881Z","end":"2026-02-27T20:31:15.871686Z","steps":["trace[146453536] 'read index received' (duration: 361.181114ms)","trace[146453536] 'applied index is now lower than readState.Index' (duration: 1.693262ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:31:15.871893Z","caller":"traceutil/trace.go:171","msg":"trace[673504610] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"384.034845ms","start":"2026-02-27T20:31:15.487666Z","end":"2026-02-27T20:31:15.8717Z","steps":["trace[673504610] 'process raft request' (duration: 382.399124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:15.872032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:15.487644Z","time spent":"384.315262ms","remote":"127.0.0.1:37682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":805,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T20:31:15.87253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.701487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:31:15.872598Z","caller":"traceutil/trace.go:171","msg":"trace[77909954] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1041; }","duration":"363.81514ms","start":"2026-02-27T20:31:15.508765Z","end":"2026-02-27T20:31:15.87258Z","steps":["trace[77909954] 'agreement among raft nodes before linearized reading' (duration: 363.01357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:15.872648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:31:15.508746Z","time spent":"363.888982ms","remote":"127.0.0.1:37856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-27T20:31:20.233662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.360806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-27T20:31:20.233761Z","caller":"traceutil/trace.go:171","msg":"trace[595094991] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1066; }","duration":"110.5118ms","start":"2026-02-27T20:31:20.123223Z","end":"2026-02-27T20:31:20.233735Z","steps":["trace[595094991] 'range keys from in-memory index tree' (duration: 110.13589ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:20.955647Z","caller":"traceutil/trace.go:171","msg":"trace[1746044939] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"186.608966ms","start":"2026-02-27T20:31:20.769Z","end":"2026-02-27T20:31:20.955609Z","steps":["trace[1746044939] 'process raft request' (duration: 186.333549ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:21.094628Z","caller":"traceutil/trace.go:171","msg":"trace[465365577] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1113; }","duration":"127.314587ms","start":"2026-02-27T20:31:20.967291Z","end":"2026-02-27T20:31:21.094605Z","steps":["trace[465365577] 'read index received' (duration: 33.598844ms)","trace[465365577] 'applied index is now lower than readState.Index' (duration: 93.714813ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:31:21.094758Z","caller":"traceutil/trace.go:171","msg":"trace[127872175] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"133.402202ms","start":"2026-02-27T20:31:20.961343Z","end":"2026-02-27T20:31:21.094745Z","steps":["trace[127872175] 'process raft request' (duration: 87.987146ms)","trace[127872175] 'compare' (duration: 45.19012ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:31:21.094972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.655547ms","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-02-27T20:31:21.095033Z","caller":"traceutil/trace.go:171","msg":"trace[1517435152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1071; }","duration":"127.751499ms","start":"2026-02-27T20:31:20.967268Z","end":"2026-02-27T20:31:21.095019Z","steps":["trace[1517435152] 'agreement among raft nodes before linearized reading' (duration: 127.581204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:31:21.095051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.015121ms","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-02-27T20:31:21.095167Z","caller":"traceutil/trace.go:171","msg":"trace[532663570] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1071; }","duration":"127.161464ms","start":"2026-02-27T20:31:20.967981Z","end":"2026-02-27T20:31:21.095142Z","steps":["trace[532663570] 'agreement among raft nodes before linearized reading' (duration: 126.841786ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:31:41.183667Z","caller":"traceutil/trace.go:171","msg":"trace[1406977278] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"130.94974ms","start":"2026-02-27T20:31:41.052692Z","end":"2026-02-27T20:31:41.183642Z","steps":["trace[1406977278] 'process raft request' (duration: 130.527579ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:32:19.209018Z","caller":"traceutil/trace.go:171","msg":"trace[954551108] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"122.824893ms","start":"2026-02-27T20:32:19.086167Z","end":"2026-02-27T20:32:19.208992Z","steps":["trace[954551108] 'process raft request' (duration: 122.670769ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:32:19.562849Z","caller":"traceutil/trace.go:171","msg":"trace[1538136981] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"158.479071ms","start":"2026-02-27T20:32:19.404343Z","end":"2026-02-27T20:32:19.562822Z","steps":["trace[1538136981] 'process raft request' (duration: 158.299547ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:33:28.215958Z","caller":"traceutil/trace.go:171","msg":"trace[1833199591] transaction","detail":"{read_only:false; response_revision:1589; number_of_response:1; }","duration":"121.035753ms","start":"2026-02-27T20:33:28.09489Z","end":"2026-02-27T20:33:28.215926Z","steps":["trace[1833199591] 'process raft request' (duration: 66.344835ms)","trace[1833199591] 'compare' (duration: 54.440922ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:33:28.216063Z","caller":"traceutil/trace.go:171","msg":"trace[1471054864] transaction","detail":"{read_only:false; response_revision:1590; number_of_response:1; }","duration":"120.722174ms","start":"2026-02-27T20:33:28.095309Z","end":"2026-02-27T20:33:28.216031Z","steps":["trace[1471054864] 'process raft request' (duration: 120.502129ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:33:28.216116Z","caller":"traceutil/trace.go:171","msg":"trace[559087969] transaction","detail":"{read_only:false; response_revision:1591; number_of_response:1; }","duration":"120.706984ms","start":"2026-02-27T20:33:28.095372Z","end":"2026-02-27T20:33:28.216079Z","steps":["trace[559087969] 'process raft request' (duration: 120.479149ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:34:25.532243Z","caller":"traceutil/trace.go:171","msg":"trace[1146941555] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"239.517705ms","start":"2026-02-27T20:34:25.292699Z","end":"2026-02-27T20:34:25.532216Z","steps":["trace[1146941555] 'process raft request' (duration: 239.30575ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:34:26.889545Z","caller":"traceutil/trace.go:171","msg":"trace[568279358] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1939; }","duration":"216.715142ms","start":"2026-02-27T20:34:26.672786Z","end":"2026-02-27T20:34:26.889501Z","steps":["trace[568279358] 'read index received' (duration: 216.477057ms)","trace[568279358] 'applied index is now lower than readState.Index' (duration: 237.205µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:34:26.88974Z","caller":"traceutil/trace.go:171","msg":"trace[1008583122] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"224.479487ms","start":"2026-02-27T20:34:26.665236Z","end":"2026-02-27T20:34:26.889715Z","steps":["trace[1008583122] 'process raft request' (duration: 224.126829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:34:26.889877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.068611ms","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-02-27T20:34:26.889935Z","caller":"traceutil/trace.go:171","msg":"trace[1192147076] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1858; }","duration":"217.169592ms","start":"2026-02-27T20:34:26.672751Z","end":"2026-02-27T20:34:26.889921Z","steps":["trace[1192147076] 'agreement among raft nodes before linearized reading' (duration: 216.972748ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:34:26.890952Z","caller":"traceutil/trace.go:171","msg":"trace[2107483731] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"138.108795ms","start":"2026-02-27T20:34:26.752825Z","end":"2026-02-27T20:34:26.890934Z","steps":["trace[2107483731] 'process raft request' (duration: 137.749927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:34:31.277811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.335303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:7035"} {"level":"info","ts":"2026-02-27T20:34:31.277925Z","caller":"traceutil/trace.go:171","msg":"trace[767171156] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1882; }","duration":"131.493777ms","start":"2026-02-27T20:34:31.146412Z","end":"2026-02-27T20:34:31.277905Z","steps":["trace[767171156] 'range keys from in-memory index tree' (duration: 131.053066ms)"],"step_count":1}