{"level":"warn","ts":"2026-04-08T10:46:28.162696Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T10:46:28.162789Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.125:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.125:2380","--initial-cluster=instance=https://199.19.213.125:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.125:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.125: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-04-08T10:46:28.162868Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T10:46:28.162879Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.125:2380"]} {"level":"info","ts":"2026-04-08T10:46:28.162899Z","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-04-08T10:46:28.163682Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.125:2379"]} {"level":"info","ts":"2026-04-08T10:46:28.163808Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.125:2380"],"listen-peer-urls":["https://199.19.213.125:2380"],"advertise-client-urls":["https://199.19.213.125:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.125:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.125: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-04-08T10:46:28.168716Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.645471ms"} {"level":"info","ts":"2026-04-08T10:46:28.176925Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b448fdce642f2755","cluster-id":"66f7e5dc9a72c5e3"} {"level":"info","ts":"2026-04-08T10:46:28.176996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T10:46:28.177017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 became follower at term 0"} {"level":"info","ts":"2026-04-08T10:46:28.177033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b448fdce642f2755 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T10:46:28.177045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 became follower at term 1"} {"level":"info","ts":"2026-04-08T10:46:28.177125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 switched to configuration voters=(12990912188036097877)"} {"level":"warn","ts":"2026-04-08T10:46:28.187232Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T10:46:28.191749Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T10:46:28.195956Z","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-04-08T10:46:28.198965Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b448fdce642f2755","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T10:46:28.202336Z","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-04-08T10:46:28.202865Z","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-04-08T10:46:28.202895Z","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-04-08T10:46:28.206172Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b448fdce642f2755","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T10:46:28.206252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 switched to configuration voters=(12990912188036097877)"} {"level":"info","ts":"2026-04-08T10:46:28.206387Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"66f7e5dc9a72c5e3","local-member-id":"b448fdce642f2755","added-peer-id":"b448fdce642f2755","added-peer-peer-urls":["https://199.19.213.125:2380"]} {"level":"info","ts":"2026-04-08T10:46:28.207192Z","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-04-08T10:46:28.207477Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b448fdce642f2755","initial-advertise-peer-urls":["https://199.19.213.125:2380"],"listen-peer-urls":["https://199.19.213.125:2380"],"advertise-client-urls":["https://199.19.213.125:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.125:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T10:46:28.207578Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T10:46:28.207748Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.125:2380"} {"level":"info","ts":"2026-04-08T10:46:28.207768Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.125:2380"} {"level":"info","ts":"2026-04-08T10:46:28.377533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T10:46:28.377581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T10:46:28.377597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 received MsgPreVoteResp from b448fdce642f2755 at term 1"} {"level":"info","ts":"2026-04-08T10:46:28.377608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 became candidate at term 2"} {"level":"info","ts":"2026-04-08T10:46:28.377613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 received MsgVoteResp from b448fdce642f2755 at term 2"} {"level":"info","ts":"2026-04-08T10:46:28.377621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b448fdce642f2755 became leader at term 2"} {"level":"info","ts":"2026-04-08T10:46:28.377628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b448fdce642f2755 elected leader b448fdce642f2755 at term 2"} {"level":"info","ts":"2026-04-08T10:46:28.378367Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T10:46:28.379359Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T10:46:28.379398Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T10:46:28.379565Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"66f7e5dc9a72c5e3","local-member-id":"b448fdce642f2755","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T10:46:28.379661Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T10:46:28.379685Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T10:46:28.37976Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T10:46:28.379856Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T10:46:28.379328Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b448fdce642f2755","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.125:2379]}","request-path":"/0/members/b448fdce642f2755/attributes","cluster-id":"66f7e5dc9a72c5e3","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T10:46:28.383279Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T10:46:28.386408Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.125:2379"} {"level":"info","ts":"2026-04-08T10:46:50.71387Z","caller":"traceutil/trace.go:171","msg":"trace[971348006] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"157.549391ms","start":"2026-04-08T10:46:50.556287Z","end":"2026-04-08T10:46:50.713836Z","steps":["trace[971348006] 'process raft request' (duration: 148.326036ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:46:52.07739Z","caller":"traceutil/trace.go:171","msg":"trace[873001218] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"115.654126ms","start":"2026-04-08T10:46:51.961712Z","end":"2026-04-08T10:46:52.077366Z","steps":["trace[873001218] 'process raft request' (duration: 115.501193ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:46:56.417148Z","caller":"traceutil/trace.go:171","msg":"trace[838802334] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"322.113035ms","start":"2026-04-08T10:46:56.095005Z","end":"2026-04-08T10:46:56.417118Z","steps":["trace[838802334] 'process raft request' (duration: 321.974783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:46:56.417796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:46:56.09498Z","time spent":"322.250068ms","remote":"127.0.0.1:35046","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-04-08T10:46:56.615671Z","caller":"traceutil/trace.go:171","msg":"trace[2065131326] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:484; }","duration":"125.658115ms","start":"2026-04-08T10:46:56.489986Z","end":"2026-04-08T10:46:56.615644Z","steps":["trace[2065131326] 'read index received' (duration: 92.283142ms)","trace[2065131326] 'applied index is now lower than readState.Index' (duration: 33.373853ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:46:56.615867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.86223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T10:46:56.615934Z","caller":"traceutil/trace.go:171","msg":"trace[1038605681] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:472; }","duration":"125.994181ms","start":"2026-04-08T10:46:56.489929Z","end":"2026-04-08T10:46:56.615923Z","steps":["trace[1038605681] 'agreement among raft nodes before linearized reading' (duration: 125.827818ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:46:56.615909Z","caller":"traceutil/trace.go:171","msg":"trace[947460576] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"153.32599ms","start":"2026-04-08T10:46:56.462528Z","end":"2026-04-08T10:46:56.615854Z","steps":["trace[947460576] 'process raft request' (duration: 119.728552ms)","trace[947460576] 'compare' (duration: 33.259572ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:46:59.216516Z","caller":"traceutil/trace.go:171","msg":"trace[1351248001] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"473.857777ms","start":"2026-04-08T10:46:58.742629Z","end":"2026-04-08T10:46:59.216487Z","steps":["trace[1351248001] 'process raft request' (duration: 471.064394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:46:59.216935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:46:58.742604Z","time spent":"474.013369ms","remote":"127.0.0.1:34862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T10:46:59.222689Z","caller":"traceutil/trace.go:171","msg":"trace[1359418198] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"475.61634ms","start":"2026-04-08T10:46:58.747041Z","end":"2026-04-08T10:46:59.222658Z","steps":["trace[1359418198] 'process raft request' (duration: 475.413907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:46:59.222843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:46:58.747022Z","time spent":"475.757903ms","remote":"127.0.0.1:34862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T10:46:59.223046Z","caller":"traceutil/trace.go:171","msg":"trace[1682599236] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"469.020644ms","start":"2026-04-08T10:46:58.754013Z","end":"2026-04-08T10:46:59.223034Z","steps":["trace[1682599236] 'process raft request' (duration: 468.566986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:46:59.22312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:46:58.753992Z","time spent":"469.083045ms","remote":"127.0.0.1:34862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T10:46:59.223562Z","caller":"traceutil/trace.go:171","msg":"trace[531613131] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"441.149815ms","start":"2026-04-08T10:46:58.782386Z","end":"2026-04-08T10:46:59.223536Z","steps":["trace[531613131] 'process raft request' (duration: 440.407261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:46:59.223719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:46:58.782355Z","time spent":"441.283498ms","remote":"127.0.0.1:34862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T10:47:05.748727Z","caller":"traceutil/trace.go:171","msg":"trace[74569089] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"263.444711ms","start":"2026-04-08T10:47:05.485261Z","end":"2026-04-08T10:47:05.748706Z","steps":["trace[74569089] 'read index received' (duration: 262.886431ms)","trace[74569089] 'applied index is now lower than readState.Index' (duration: 557.62µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:47:05.748831Z","caller":"traceutil/trace.go:171","msg":"trace[1725316783] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"345.772485ms","start":"2026-04-08T10:47:05.403051Z","end":"2026-04-08T10:47:05.748824Z","steps":["trace[1725316783] 'process raft request' (duration: 345.241755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:47:05.748941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:47:05.403014Z","time spent":"345.835816ms","remote":"127.0.0.1:34894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T10:47:05.74936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.054942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qxhhv\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-08T10:47:05.749477Z","caller":"traceutil/trace.go:171","msg":"trace[410085140] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qxhhv; range_end:; response_count:1; response_revision:621; }","duration":"264.260127ms","start":"2026-04-08T10:47:05.485188Z","end":"2026-04-08T10:47:05.749449Z","steps":["trace[410085140] 'agreement among raft nodes before linearized reading' (duration: 263.841279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:47:06.270978Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2834344630691059213,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T10:47:06.547969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.699851ms","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-04-08T10:47:06.548099Z","caller":"traceutil/trace.go:171","msg":"trace[1835749178] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:621; }","duration":"652.839494ms","start":"2026-04-08T10:47:05.895239Z","end":"2026-04-08T10:47:06.548079Z","steps":["trace[1835749178] 'range keys from in-memory index tree' (duration: 652.686411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:47:06.54832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.918597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T10:47:06.548523Z","caller":"traceutil/trace.go:171","msg":"trace[883013337] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:639; }","duration":"778.246165ms","start":"2026-04-08T10:47:05.770266Z","end":"2026-04-08T10:47:06.548512Z","steps":["trace[883013337] 'read index received' (duration: 512.407308ms)","trace[883013337] 'applied index is now lower than readState.Index' (duration: 265.837817ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:47:06.548605Z","caller":"traceutil/trace.go:171","msg":"trace[937480941] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"738.729564ms","start":"2026-04-08T10:47:05.809865Z","end":"2026-04-08T10:47:06.548595Z","steps":["trace[937480941] 'process raft request' (duration: 738.612302ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:47:06.548572Z","caller":"traceutil/trace.go:171","msg":"trace[1628521794] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"781.245412ms","start":"2026-04-08T10:47:05.767306Z","end":"2026-04-08T10:47:06.548551Z","steps":["trace[1628521794] 'process raft request' (duration: 781.097119ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:47:06.548634Z","caller":"traceutil/trace.go:171","msg":"trace[1531541843] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"794.672166ms","start":"2026-04-08T10:47:05.75395Z","end":"2026-04-08T10:47:06.548622Z","steps":["trace[1531541843] 'process raft request' (duration: 15.24777ms)","trace[1531541843] 'compare' (duration: 778.819825ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:47:06.54866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:47:05.809831Z","time spent":"738.804785ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T10:47:06.548695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:47:05.753925Z","time spent":"794.736077ms","remote":"127.0.0.1:35046","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-04-08T10:47:06.548721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:47:05.767283Z","time spent":"781.367003ms","remote":"127.0.0.1:34980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T10:47:06.548901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.639122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T10:47:06.548939Z","caller":"traceutil/trace.go:171","msg":"trace[1943909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"778.716423ms","start":"2026-04-08T10:47:05.770214Z","end":"2026-04-08T10:47:06.548931Z","steps":["trace[1943909] 'agreement among raft nodes before linearized reading' (duration: 778.590481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:47:06.548969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:47:05.770194Z","time spent":"778.767565ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T10:47:06.549065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.795153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T10:47:06.549105Z","caller":"traceutil/trace.go:171","msg":"trace[965532789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"160.866214ms","start":"2026-04-08T10:47:06.388229Z","end":"2026-04-08T10:47:06.549095Z","steps":["trace[965532789] 'agreement among raft nodes before linearized reading' (duration: 160.812513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:47:07.056161Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2834344630691059219,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T10:47:07.498519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.480895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T10:47:07.49911Z","caller":"traceutil/trace.go:171","msg":"trace[1059293190] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"945.193074ms","start":"2026-04-08T10:47:06.553821Z","end":"2026-04-08T10:47:07.499014Z","steps":["trace[1059293190] 'process raft request' (duration: 624.076758ms)","trace[1059293190] 'compare' (duration: 320.163958ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:47:07.499127Z","caller":"traceutil/trace.go:171","msg":"trace[1531287482] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"944.4646ms","start":"2026-04-08T10:47:06.554631Z","end":"2026-04-08T10:47:07.499096Z","steps":["trace[1531287482] 'process raft request' (duration: 944.131894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:47:07.49948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:47:06.554605Z","time spent":"944.606973ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T10:47:07.499484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:47:06.553805Z","time spent":"945.392478ms","remote":"127.0.0.1:35046","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-04-08T10:47:07.508632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.310759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-08T10:47:07.508699Z","caller":"traceutil/trace.go:171","msg":"trace[179503000] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:626; }","duration":"953.39124ms","start":"2026-04-08T10:47:06.555297Z","end":"2026-04-08T10:47:07.508689Z","steps":["trace[179503000] 'agreement among raft nodes before linearized reading' (duration: 953.290088ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:47:07.508508Z","caller":"traceutil/trace.go:171","msg":"trace[904185736] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:642; }","duration":"953.160406ms","start":"2026-04-08T10:47:06.555315Z","end":"2026-04-08T10:47:07.508475Z","steps":["trace[904185736] 'read index received' (duration: 622.5654ms)","trace[904185736] 'applied index is now lower than readState.Index' (duration: 330.590196ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:47:07.508724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:47:06.555288Z","time spent":"953.429531ms","remote":"127.0.0.1:35000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-08T10:47:07.509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.314034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T10:47:07.509043Z","caller":"traceutil/trace.go:171","msg":"trace[1149862330] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"120.396906ms","start":"2026-04-08T10:47:07.38863Z","end":"2026-04-08T10:47:07.509027Z","steps":["trace[1149862330] 'agreement among raft nodes before linearized reading' (duration: 120.317805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:47:07.509167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.410203ms","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-04-08T10:47:07.509239Z","caller":"traceutil/trace.go:171","msg":"trace[903129801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:626; }","duration":"812.586367ms","start":"2026-04-08T10:47:06.696632Z","end":"2026-04-08T10:47:07.509218Z","steps":["trace[903129801] 'agreement among raft nodes before linearized reading' (duration: 812.273741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:47:07.509337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:47:06.696606Z","time spent":"812.714279ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-08T10:47:08.966996Z","caller":"traceutil/trace.go:171","msg":"trace[1041523971] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"199.096ms","start":"2026-04-08T10:47:08.767867Z","end":"2026-04-08T10:47:08.966963Z","steps":["trace[1041523971] 'read index received' (duration: 198.832215ms)","trace[1041523971] 'applied index is now lower than readState.Index' (duration: 262.355µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:47:08.967037Z","caller":"traceutil/trace.go:171","msg":"trace[1476984467] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"312.436662ms","start":"2026-04-08T10:47:08.654565Z","end":"2026-04-08T10:47:08.967002Z","steps":["trace[1476984467] 'process raft request' (duration: 312.198757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:47:08.967314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.403026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T10:47:08.967367Z","caller":"traceutil/trace.go:171","msg":"trace[185415155] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:640; }","duration":"199.519208ms","start":"2026-04-08T10:47:08.767835Z","end":"2026-04-08T10:47:08.967354Z","steps":["trace[185415155] 'agreement among raft nodes before linearized reading' (duration: 199.305834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:47:08.967501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:47:08.654533Z","time spent":"312.737297ms","remote":"127.0.0.1:34882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T10:47:08.968324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.378234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T10:47:08.968625Z","caller":"traceutil/trace.go:171","msg":"trace[1956299174] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:640; }","duration":"200.688711ms","start":"2026-04-08T10:47:08.767913Z","end":"2026-04-08T10:47:08.968602Z","steps":["trace[1956299174] 'agreement among raft nodes before linearized reading' (duration: 200.362764ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:47:24.072918Z","caller":"traceutil/trace.go:171","msg":"trace[1005772146] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"129.703721ms","start":"2026-04-08T10:47:23.943187Z","end":"2026-04-08T10:47:24.07289Z","steps":["trace[1005772146] 'process raft request' (duration: 129.09781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:47:25.156428Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2834344630691059601,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T10:47:25.175315Z","caller":"traceutil/trace.go:171","msg":"trace[2131772412] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"631.790924ms","start":"2026-04-08T10:47:24.54349Z","end":"2026-04-08T10:47:25.175281Z","steps":["trace[2131772412] 'process raft request' (duration: 631.60254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:47:25.175472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:47:24.543464Z","time spent":"631.911196ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T10:47:25.476652Z","caller":"traceutil/trace.go:171","msg":"trace[1669956449] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:812; }","duration":"820.951456ms","start":"2026-04-08T10:47:24.655683Z","end":"2026-04-08T10:47:25.476634Z","steps":["trace[1669956449] 'read index received' (duration: 519.526533ms)","trace[1669956449] 'applied index is now lower than readState.Index' (duration: 301.424213ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:47:25.476855Z","caller":"traceutil/trace.go:171","msg":"trace[1334370231] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"768.086711ms","start":"2026-04-08T10:47:24.70873Z","end":"2026-04-08T10:47:25.476817Z","steps":["trace[1334370231] 'process raft request' (duration: 767.794586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:47:25.477029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.339263ms","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-04-08T10:47:25.477036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:47:24.708715Z","time spent":"768.231824ms","remote":"127.0.0.1:35036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T10:47:25.477112Z","caller":"traceutil/trace.go:171","msg":"trace[1445946937] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:791; }","duration":"821.446335ms","start":"2026-04-08T10:47:24.655631Z","end":"2026-04-08T10:47:25.477078Z","steps":["trace[1445946937] 'agreement among raft nodes before linearized reading' (duration: 821.20466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:47:25.47716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:47:24.655611Z","time spent":"821.541296ms","remote":"127.0.0.1:35046","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-04-08T10:47:25.477271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.683565ms","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-04-08T10:47:25.477347Z","caller":"traceutil/trace.go:171","msg":"trace[904678346] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:791; }","duration":"323.802777ms","start":"2026-04-08T10:47:25.153527Z","end":"2026-04-08T10:47:25.47733Z","steps":["trace[904678346] 'agreement among raft nodes before linearized reading' (duration: 323.462931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:47:25.4774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:47:25.153504Z","time spent":"323.878119ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-08T10:47:26.59498Z","caller":"traceutil/trace.go:171","msg":"trace[1342586963] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"110.967238ms","start":"2026-04-08T10:47:26.483991Z","end":"2026-04-08T10:47:26.594958Z","steps":["trace[1342586963] 'process raft request' (duration: 110.821125ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:47:51.889479Z","caller":"traceutil/trace.go:171","msg":"trace[916631509] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1009; }","duration":"120.953102ms","start":"2026-04-08T10:47:51.768501Z","end":"2026-04-08T10:47:51.889454Z","steps":["trace[916631509] 'read index received' (duration: 119.559347ms)","trace[916631509] 'applied index is now lower than readState.Index' (duration: 1.392805ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:47:51.889563Z","caller":"traceutil/trace.go:171","msg":"trace[470667809] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"130.500074ms","start":"2026-04-08T10:47:51.75905Z","end":"2026-04-08T10:47:51.889551Z","steps":["trace[470667809] 'process raft request' (duration: 129.052057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:47:51.889688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.169577ms","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-04-08T10:47:51.89048Z","caller":"traceutil/trace.go:171","msg":"trace[1028614737] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:980; }","duration":"121.243968ms","start":"2026-04-08T10:47:51.768472Z","end":"2026-04-08T10:47:51.889716Z","steps":["trace[1028614737] 'agreement among raft nodes before linearized reading' (duration: 121.111836ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:47:53.799739Z","caller":"traceutil/trace.go:171","msg":"trace[302428258] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"116.686491ms","start":"2026-04-08T10:47:53.683034Z","end":"2026-04-08T10:47:53.799721Z","steps":["trace[302428258] 'process raft request' (duration: 115.55362ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:00.11071Z","caller":"traceutil/trace.go:171","msg":"trace[2080248370] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"407.721506ms","start":"2026-04-08T10:47:59.702962Z","end":"2026-04-08T10:48:00.110683Z","steps":["trace[2080248370] 'process raft request' (duration: 407.544023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:00.110959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:47:59.702938Z","time spent":"407.933901ms","remote":"127.0.0.1:35046","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-04-08T10:48:00.632377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.231187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T10:48:00.632463Z","caller":"traceutil/trace.go:171","msg":"trace[685969697] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1038; }","duration":"807.643525ms","start":"2026-04-08T10:47:59.824805Z","end":"2026-04-08T10:48:00.632448Z","steps":["trace[685969697] 'read index received' (duration: 285.711434ms)","trace[685969697] 'applied index is now lower than readState.Index' (duration: 521.930891ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:48:00.632672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.86728ms","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-04-08T10:48:00.632693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.900442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T10:48:00.632717Z","caller":"traceutil/trace.go:171","msg":"trace[976042587] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1008; }","duration":"807.939321ms","start":"2026-04-08T10:47:59.824767Z","end":"2026-04-08T10:48:00.632706Z","steps":["trace[976042587] 'agreement among raft nodes before linearized reading' (duration: 807.802239ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:00.632723Z","caller":"traceutil/trace.go:171","msg":"trace[750523076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1008; }","duration":"644.976903ms","start":"2026-04-08T10:47:59.987738Z","end":"2026-04-08T10:48:00.632715Z","steps":["trace[750523076] 'agreement among raft nodes before linearized reading' (duration: 644.899862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:00.632785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:47:59.824756Z","time spent":"807.984542ms","remote":"127.0.0.1:35046","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-04-08T10:48:00.632799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:47:59.987711Z","time spent":"645.029574ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T10:48:00.632841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.269825ms","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-04-08T10:48:00.632886Z","caller":"traceutil/trace.go:171","msg":"trace[1540863481] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"808.860798ms","start":"2026-04-08T10:47:59.824014Z","end":"2026-04-08T10:48:00.632875Z","steps":["trace[1540863481] 'process raft request' (duration: 438.068811ms)","trace[1540863481] 'compare' (duration: 369.950382ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:48:00.632934Z","caller":"traceutil/trace.go:171","msg":"trace[101521728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1008; }","duration":"696.396757ms","start":"2026-04-08T10:47:59.936513Z","end":"2026-04-08T10:48:00.63291Z","steps":["trace[101521728] 'agreement among raft nodes before linearized reading' (duration: 696.101271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:00.63295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:47:59.823991Z","time spent":"808.92586ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T10:48:00.632966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.794157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T10:48:00.632992Z","caller":"traceutil/trace.go:171","msg":"trace[714027782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1008; }","duration":"246.852678ms","start":"2026-04-08T10:48:00.386131Z","end":"2026-04-08T10:48:00.632984Z","steps":["trace[714027782] 'agreement among raft nodes before linearized reading' (duration: 246.811317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:00.632989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:47:59.936501Z","time spent":"696.474528ms","remote":"127.0.0.1:35046","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-04-08T10:48:00.633183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.800664ms","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-04-08T10:48:00.633224Z","caller":"traceutil/trace.go:171","msg":"trace[2123709214] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1008; }","duration":"649.861165ms","start":"2026-04-08T10:47:59.983351Z","end":"2026-04-08T10:48:00.633212Z","steps":["trace[2123709214] 'agreement among raft nodes before linearized reading' (duration: 649.746473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:00.633263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:47:59.983341Z","time spent":"649.911406ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-08T10:48:02.796374Z","caller":"traceutil/trace.go:171","msg":"trace[268080169] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"138.090617ms","start":"2026-04-08T10:48:02.658259Z","end":"2026-04-08T10:48:02.79635Z","steps":["trace[268080169] 'process raft request' (duration: 137.928714ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:02.79666Z","caller":"traceutil/trace.go:171","msg":"trace[1647696497] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"138.20572ms","start":"2026-04-08T10:48:02.658422Z","end":"2026-04-08T10:48:02.796628Z","steps":["trace[1647696497] 'process raft request' (duration: 137.888233ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:02.796745Z","caller":"traceutil/trace.go:171","msg":"trace[91937877] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"138.171609ms","start":"2026-04-08T10:48:02.658552Z","end":"2026-04-08T10:48:02.796723Z","steps":["trace[91937877] 'process raft request' (duration: 137.981555ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:14.391847Z","caller":"traceutil/trace.go:171","msg":"trace[841038554] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"144.893915ms","start":"2026-04-08T10:48:14.246933Z","end":"2026-04-08T10:48:14.391827Z","steps":["trace[841038554] 'process raft request' (duration: 144.728532ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:15.038195Z","caller":"traceutil/trace.go:171","msg":"trace[524684829] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"158.762619ms","start":"2026-04-08T10:48:14.879411Z","end":"2026-04-08T10:48:15.038174Z","steps":["trace[524684829] 'process raft request' (duration: 158.648677ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:17.107532Z","caller":"traceutil/trace.go:171","msg":"trace[1758938058] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"187.159487ms","start":"2026-04-08T10:48:16.920354Z","end":"2026-04-08T10:48:17.107513Z","steps":["trace[1758938058] 'process raft request' (duration: 183.454807ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:17.107824Z","caller":"traceutil/trace.go:171","msg":"trace[591611379] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"186.861643ms","start":"2026-04-08T10:48:16.920838Z","end":"2026-04-08T10:48:17.1077Z","steps":["trace[591611379] 'process raft request' (duration: 186.620198ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:22.72979Z","caller":"traceutil/trace.go:171","msg":"trace[785370603] linearizableReadLoop","detail":"{readStateIndex:1117; appliedIndex:1116; }","duration":"342.911589ms","start":"2026-04-08T10:48:22.386854Z","end":"2026-04-08T10:48:22.729766Z","steps":["trace[785370603] 'read index received' (duration: 342.707256ms)","trace[785370603] 'applied index is now lower than readState.Index' (duration: 203.303µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:48:22.729919Z","caller":"traceutil/trace.go:171","msg":"trace[1058214719] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"347.639898ms","start":"2026-04-08T10:48:22.382267Z","end":"2026-04-08T10:48:22.729907Z","steps":["trace[1058214719] 'process raft request' (duration: 347.304452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:22.730041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:22.38224Z","time spent":"347.71848ms","remote":"127.0.0.1:35046","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-04-08T10:48:22.730193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.056044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T10:48:22.730229Z","caller":"traceutil/trace.go:171","msg":"trace[1685318848] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1080; }","duration":"254.116977ms","start":"2026-04-08T10:48:22.476103Z","end":"2026-04-08T10:48:22.73022Z","steps":["trace[1685318848] 'agreement among raft nodes before linearized reading' (duration: 254.055214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:22.730205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.013044ms","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-04-08T10:48:22.730241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.009754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T10:48:22.730299Z","caller":"traceutil/trace.go:171","msg":"trace[774862945] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1080; }","duration":"311.175168ms","start":"2026-04-08T10:48:22.4191Z","end":"2026-04-08T10:48:22.730275Z","steps":["trace[774862945] 'agreement among raft nodes before linearized reading' (duration: 310.901152ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:22.730326Z","caller":"traceutil/trace.go:171","msg":"trace[468854822] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1080; }","duration":"149.158557ms","start":"2026-04-08T10:48:22.581151Z","end":"2026-04-08T10:48:22.730309Z","steps":["trace[468854822] 'agreement among raft nodes before linearized reading' (duration: 148.993703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:22.730359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:22.419079Z","time spent":"311.26866ms","remote":"127.0.0.1:35046","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-04-08T10:48:22.730321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.43736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T10:48:22.73092Z","caller":"traceutil/trace.go:171","msg":"trace[1353354055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1080; }","duration":"344.083311ms","start":"2026-04-08T10:48:22.386815Z","end":"2026-04-08T10:48:22.730898Z","steps":["trace[1353354055] 'agreement among raft nodes before linearized reading' (duration: 343.369307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:22.731012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:22.3868Z","time spent":"344.160693ms","remote":"127.0.0.1:34816","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-04-08T10:48:23.298763Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2834344630691060248,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T10:48:23.530738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.645738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T10:48:23.530849Z","caller":"traceutil/trace.go:171","msg":"trace[799098539] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"795.821141ms","start":"2026-04-08T10:48:22.735006Z","end":"2026-04-08T10:48:23.530827Z","steps":["trace[799098539] 'process raft request' (duration: 56.99861ms)","trace[799098539] 'compare' (duration: 738.20432ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:48:23.530918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:22.734986Z","time spent":"795.894623ms","remote":"127.0.0.1:35046","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-04-08T10:48:23.542736Z","caller":"traceutil/trace.go:171","msg":"trace[963664565] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1118; }","duration":"744.236224ms","start":"2026-04-08T10:48:22.798482Z","end":"2026-04-08T10:48:23.542718Z","steps":["trace[963664565] 'read index received' (duration: 744.186993ms)","trace[963664565] 'applied index is now lower than readState.Index' (duration: 48.171µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:48:23.542905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.408848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T10:48:23.542967Z","caller":"traceutil/trace.go:171","msg":"trace[532404462] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1082; }","duration":"744.504089ms","start":"2026-04-08T10:48:22.798446Z","end":"2026-04-08T10:48:23.54295Z","steps":["trace[532404462] 'agreement among raft nodes before linearized reading' (duration: 744.358137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:23.543026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:22.798432Z","time spent":"744.5841ms","remote":"127.0.0.1:35328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-04-08T10:48:23.543029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.513928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T10:48:23.543112Z","caller":"traceutil/trace.go:171","msg":"trace[1203269776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1082; }","duration":"154.679151ms","start":"2026-04-08T10:48:23.388416Z","end":"2026-04-08T10:48:23.543095Z","steps":["trace[1203269776] 'agreement among raft nodes before linearized reading' (duration: 154.465387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:23.543175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.025621ms","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-04-08T10:48:23.543304Z","caller":"traceutil/trace.go:171","msg":"trace[1187329805] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1082; }","duration":"657.174844ms","start":"2026-04-08T10:48:22.8861Z","end":"2026-04-08T10:48:23.543274Z","steps":["trace[1187329805] 'agreement among raft nodes before linearized reading' (duration: 656.786657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:23.543387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:22.886046Z","time spent":"657.318687ms","remote":"127.0.0.1:35168","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":"info","ts":"2026-04-08T10:48:23.543315Z","caller":"traceutil/trace.go:171","msg":"trace[572364878] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"800.117033ms","start":"2026-04-08T10:48:22.742815Z","end":"2026-04-08T10:48:23.542932Z","steps":["trace[572364878] 'process raft request' (duration: 799.808337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:23.543522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.186421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-04-08T10:48:23.543611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.854123ms","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-04-08T10:48:23.543686Z","caller":"traceutil/trace.go:171","msg":"trace[1166067997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1082; }","duration":"403.970966ms","start":"2026-04-08T10:48:23.139702Z","end":"2026-04-08T10:48:23.543673Z","steps":["trace[1166067997] 'agreement among raft nodes before linearized reading' (duration: 403.231662ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:23.543659Z","caller":"traceutil/trace.go:171","msg":"trace[1775550040] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1082; }","duration":"403.433006ms","start":"2026-04-08T10:48:23.140206Z","end":"2026-04-08T10:48:23.543639Z","steps":["trace[1775550040] 'agreement among raft nodes before linearized reading' (duration: 402.715903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:23.543768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:23.139681Z","time spent":"404.058648ms","remote":"127.0.0.1:35046","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-04-08T10:48:23.543824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:23.140195Z","time spent":"403.584569ms","remote":"127.0.0.1:35046","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-04-08T10:48:23.54399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.289148ms","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-04-08T10:48:23.544038Z","caller":"traceutil/trace.go:171","msg":"trace[1772916513] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1082; }","duration":"408.3763ms","start":"2026-04-08T10:48:23.13565Z","end":"2026-04-08T10:48:23.544026Z","steps":["trace[1772916513] 'agreement among raft nodes before linearized reading' (duration: 407.292349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:23.544076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:23.135629Z","time spent":"408.439841ms","remote":"127.0.0.1:35046","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-04-08T10:48:23.544185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.615445ms","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-04-08T10:48:23.544289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:22.742803Z","time spent":"801.329636ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T10:48:23.544235Z","caller":"traceutil/trace.go:171","msg":"trace[748164412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1082; }","duration":"569.688546ms","start":"2026-04-08T10:48:22.974535Z","end":"2026-04-08T10:48:23.544223Z","steps":["trace[748164412] 'agreement among raft nodes before linearized reading' (duration: 568.410692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:23.544532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:22.974473Z","time spent":"570.046003ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-08T10:48:25.687338Z","caller":"traceutil/trace.go:171","msg":"trace[1635641664] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1123; }","duration":"121.676776ms","start":"2026-04-08T10:48:25.565636Z","end":"2026-04-08T10:48:25.687313Z","steps":["trace[1635641664] 'read index received' (duration: 762.904µs)","trace[1635641664] 'applied index is now lower than readState.Index' (duration: 120.912882ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:48:25.687412Z","caller":"traceutil/trace.go:171","msg":"trace[1309966515] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"137.418825ms","start":"2026-04-08T10:48:25.549951Z","end":"2026-04-08T10:48:25.68737Z","steps":["trace[1309966515] 'process raft request' (duration: 137.19915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:25.68781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.95472ms","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-04-08T10:48:25.687903Z","caller":"traceutil/trace.go:171","msg":"trace[2100309681] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1088; }","duration":"114.110363ms","start":"2026-04-08T10:48:25.57378Z","end":"2026-04-08T10:48:25.687891Z","steps":["trace[2100309681] 'agreement among raft nodes before linearized reading' (duration: 113.860348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:25.687937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.772948ms","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-04-08T10:48:25.687753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.090694ms","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-04-08T10:48:25.687989Z","caller":"traceutil/trace.go:171","msg":"trace[458328739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1088; }","duration":"112.816288ms","start":"2026-04-08T10:48:25.575157Z","end":"2026-04-08T10:48:25.687973Z","steps":["trace[458328739] 'agreement among raft nodes before linearized reading' (duration: 112.724247ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:25.688019Z","caller":"traceutil/trace.go:171","msg":"trace[1849995807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1088; }","duration":"122.402179ms","start":"2026-04-08T10:48:25.565602Z","end":"2026-04-08T10:48:25.688004Z","steps":["trace[1849995807] 'agreement among raft nodes before linearized reading' (duration: 121.834959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:25.687828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.321219ms","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-04-08T10:48:25.688118Z","caller":"traceutil/trace.go:171","msg":"trace[344001914] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1088; }","duration":"112.674965ms","start":"2026-04-08T10:48:25.575434Z","end":"2026-04-08T10:48:25.688109Z","steps":["trace[344001914] 'agreement among raft nodes before linearized reading' (duration: 112.224187ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:26.770863Z","caller":"traceutil/trace.go:171","msg":"trace[660177001] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"195.27153ms","start":"2026-04-08T10:48:26.57556Z","end":"2026-04-08T10:48:26.770832Z","steps":["trace[660177001] 'process raft request' (duration: 194.913303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:27.417463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.292601ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-08T10:48:27.80037Z","caller":"traceutil/trace.go:171","msg":"trace[1860091885] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1131; }","duration":"215.961383ms","start":"2026-04-08T10:48:27.584385Z","end":"2026-04-08T10:48:27.800347Z","steps":["trace[1860091885] 'read index received' (duration: 215.873921ms)","trace[1860091885] 'applied index is now lower than readState.Index' (duration: 86.362µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:48:27.800725Z","caller":"traceutil/trace.go:171","msg":"trace[1700601426] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"226.880789ms","start":"2026-04-08T10:48:27.573816Z","end":"2026-04-08T10:48:27.800697Z","steps":["trace[1700601426] 'process raft request' (duration: 226.3843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:27.800752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.32698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T10:48:27.800829Z","caller":"traceutil/trace.go:171","msg":"trace[2109093598] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1094; }","duration":"216.450822ms","start":"2026-04-08T10:48:27.58436Z","end":"2026-04-08T10:48:27.800811Z","steps":["trace[2109093598] 'agreement among raft nodes before linearized reading' (duration: 216.249829ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:27.806975Z","caller":"traceutil/trace.go:171","msg":"trace[1893130427] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"112.854428ms","start":"2026-04-08T10:48:27.694096Z","end":"2026-04-08T10:48:27.806951Z","steps":["trace[1893130427] 'process raft request' (duration: 112.509672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:27.807229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.421652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T10:48:27.807308Z","caller":"traceutil/trace.go:171","msg":"trace[1754743644] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1095; }","duration":"121.554604ms","start":"2026-04-08T10:48:27.685738Z","end":"2026-04-08T10:48:27.807292Z","steps":["trace[1754743644] 'agreement among raft nodes before linearized reading' (duration: 121.006133ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:39.977582Z","caller":"traceutil/trace.go:171","msg":"trace[1783617040] linearizableReadLoop","detail":"{readStateIndex:1229; appliedIndex:1228; }","duration":"107.270743ms","start":"2026-04-08T10:48:39.870282Z","end":"2026-04-08T10:48:39.977553Z","steps":["trace[1783617040] 'read index received' (duration: 107.053039ms)","trace[1783617040] 'applied index is now lower than readState.Index' (duration: 216.854µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:48:39.977642Z","caller":"traceutil/trace.go:171","msg":"trace[1078303998] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"111.564834ms","start":"2026-04-08T10:48:39.866046Z","end":"2026-04-08T10:48:39.97761Z","steps":["trace[1078303998] 'process raft request' (duration: 111.35859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:39.977831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.526318ms","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-04-08T10:48:39.977875Z","caller":"traceutil/trace.go:171","msg":"trace[1019175782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1187; }","duration":"107.62321ms","start":"2026-04-08T10:48:39.87024Z","end":"2026-04-08T10:48:39.977863Z","steps":["trace[1019175782] 'agreement among raft nodes before linearized reading' (duration: 107.443856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:39.977986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.428579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T10:48:39.978062Z","caller":"traceutil/trace.go:171","msg":"trace[1032163907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1187; }","duration":"103.562442ms","start":"2026-04-08T10:48:39.874481Z","end":"2026-04-08T10:48:39.978043Z","steps":["trace[1032163907] 'agreement among raft nodes before linearized reading' (duration: 103.375209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:39.978211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.756789ms","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-04-08T10:48:39.97825Z","caller":"traceutil/trace.go:171","msg":"trace[141155282] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1187; }","duration":"100.809771ms","start":"2026-04-08T10:48:39.877431Z","end":"2026-04-08T10:48:39.978241Z","steps":["trace[141155282] 'agreement among raft nodes before linearized reading' (duration: 100.713009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:42.463627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:41.548831Z","time spent":"914.791455ms","remote":"127.0.0.1:34836","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-08T10:48:42.488388Z","caller":"traceutil/trace.go:171","msg":"trace[1641103221] linearizableReadLoop","detail":"{readStateIndex:1236; appliedIndex:1234; }","duration":"498.517347ms","start":"2026-04-08T10:48:41.989842Z","end":"2026-04-08T10:48:42.48836Z","steps":["trace[1641103221] 'read index received' (duration: 473.705567ms)","trace[1641103221] 'applied index is now lower than readState.Index' (duration: 24.81008ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:48:42.48843Z","caller":"traceutil/trace.go:171","msg":"trace[775854975] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"504.146944ms","start":"2026-04-08T10:48:41.984257Z","end":"2026-04-08T10:48:42.488404Z","steps":["trace[775854975] 'process raft request' (duration: 503.898559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:42.488555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:41.98424Z","time spent":"504.244545ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T10:48:42.488622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.762281ms","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-04-08T10:48:42.488682Z","caller":"traceutil/trace.go:171","msg":"trace[687632883] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1193; }","duration":"498.849183ms","start":"2026-04-08T10:48:41.989818Z","end":"2026-04-08T10:48:42.488667Z","steps":["trace[687632883] 'agreement among raft nodes before linearized reading' (duration: 498.667489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:42.488709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:41.989811Z","time spent":"498.892873ms","remote":"127.0.0.1:35046","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-04-08T10:48:42.49053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.357377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-08T10:48:42.490525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.377707ms","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-04-08T10:48:42.490555Z","caller":"traceutil/trace.go:171","msg":"trace[319712398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1194; }","duration":"496.597821ms","start":"2026-04-08T10:48:41.993951Z","end":"2026-04-08T10:48:42.490549Z","steps":["trace[319712398] 'agreement among raft nodes before linearized reading' (duration: 496.52547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:42.490572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:41.993925Z","time spent":"496.643601ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T10:48:42.490572Z","caller":"traceutil/trace.go:171","msg":"trace[1749197617] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1194; }","duration":"496.514289ms","start":"2026-04-08T10:48:41.994045Z","end":"2026-04-08T10:48:42.490559Z","steps":["trace[1749197617] 'agreement among raft nodes before linearized reading' (duration: 496.366567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:42.49062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:41.994037Z","time spent":"496.57402ms","remote":"127.0.0.1:35046","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-04-08T10:48:42.490643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.468268ms","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-04-08T10:48:42.490669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.456159ms","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-04-08T10:48:42.490714Z","caller":"traceutil/trace.go:171","msg":"trace[125865450] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1194; }","duration":"496.577001ms","start":"2026-04-08T10:48:41.99412Z","end":"2026-04-08T10:48:42.490697Z","steps":["trace[125865450] 'agreement among raft nodes before linearized reading' (duration: 496.367257ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:42.490749Z","caller":"traceutil/trace.go:171","msg":"trace[283488278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1194; }","duration":"496.608831ms","start":"2026-04-08T10:48:41.994121Z","end":"2026-04-08T10:48:42.49073Z","steps":["trace[283488278] 'agreement among raft nodes before linearized reading' (duration: 496.354267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:42.490765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:41.994106Z","time spent":"496.650602ms","remote":"127.0.0.1:35046","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-04-08T10:48:42.490794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:41.994048Z","time spent":"496.736903ms","remote":"127.0.0.1:35046","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-04-08T10:48:42.490831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.805839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T10:48:42.49085Z","caller":"traceutil/trace.go:171","msg":"trace[347380160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1194; }","duration":"102.85976ms","start":"2026-04-08T10:48:42.387985Z","end":"2026-04-08T10:48:42.490844Z","steps":["trace[347380160] 'agreement among raft nodes before linearized reading' (duration: 102.83094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:57.1914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.798071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T10:48:57.191537Z","caller":"traceutil/trace.go:171","msg":"trace[471535686] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"617.325078ms","start":"2026-04-08T10:48:56.574192Z","end":"2026-04-08T10:48:57.191517Z","steps":["trace[471535686] 'process raft request' (duration: 179.339254ms)","trace[471535686] 'compare' (duration: 437.682519ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:48:57.191596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:56.574139Z","time spent":"617.42982ms","remote":"127.0.0.1:35046","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-04-08T10:48:57.192008Z","caller":"traceutil/trace.go:171","msg":"trace[511168791] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"615.972664ms","start":"2026-04-08T10:48:56.576024Z","end":"2026-04-08T10:48:57.191996Z","steps":["trace[511168791] 'process raft request' (duration: 615.74956ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:57.192011Z","caller":"traceutil/trace.go:171","msg":"trace[1750189116] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"617.488221ms","start":"2026-04-08T10:48:56.574465Z","end":"2026-04-08T10:48:57.191953Z","steps":["trace[1750189116] 'process raft request' (duration: 617.016982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:57.19207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:56.57601Z","time spent":"616.027555ms","remote":"127.0.0.1:34966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T10:48:57.192077Z","caller":"traceutil/trace.go:171","msg":"trace[117323512] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1300; }","duration":"282.674328ms","start":"2026-04-08T10:48:56.909377Z","end":"2026-04-08T10:48:57.192051Z","steps":["trace[117323512] 'read index received' (duration: 24.341µs)","trace[117323512] 'applied index is now lower than readState.Index' (duration: 282.648857ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:48:57.192166Z","caller":"traceutil/trace.go:171","msg":"trace[595270169] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"615.263741ms","start":"2026-04-08T10:48:56.576873Z","end":"2026-04-08T10:48:57.192137Z","steps":["trace[595270169] 'process raft request' (duration: 615.072298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:57.192231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:56.574456Z","time spent":"617.668375ms","remote":"127.0.0.1:35046","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-04-08T10:48:57.19226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:56.57686Z","time spent":"615.353323ms","remote":"127.0.0.1:35046","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-04-08T10:48:57.19236Z","caller":"traceutil/trace.go:171","msg":"trace[664913065] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"615.427664ms","start":"2026-04-08T10:48:56.576915Z","end":"2026-04-08T10:48:57.192342Z","steps":["trace[664913065] 'process raft request' (duration: 615.084748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:57.19236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.950753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-24p7z\" ","response":"range_response_count:1 size:11061"} {"level":"warn","ts":"2026-04-08T10:48:57.192415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:56.576911Z","time spent":"615.475605ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T10:48:57.192416Z","caller":"traceutil/trace.go:171","msg":"trace[1826370758] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-24p7z; range_end:; response_count:1; response_revision:1260; }","duration":"283.049824ms","start":"2026-04-08T10:48:56.90935Z","end":"2026-04-08T10:48:57.1924Z","steps":["trace[1826370758] 'agreement among raft nodes before linearized reading' (duration: 282.83334ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:57.551874Z","caller":"traceutil/trace.go:171","msg":"trace[323508321] linearizableReadLoop","detail":"{readStateIndex:1306; appliedIndex:1305; }","duration":"354.918717ms","start":"2026-04-08T10:48:57.196917Z","end":"2026-04-08T10:48:57.551836Z","steps":["trace[323508321] 'read index received' (duration: 349.71105ms)","trace[323508321] 'applied index is now lower than readState.Index' (duration: 5.205956ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:48:57.55188Z","caller":"traceutil/trace.go:171","msg":"trace[208577021] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"382.421276ms","start":"2026-04-08T10:48:57.169435Z","end":"2026-04-08T10:48:57.551856Z","steps":["trace[208577021] 'process raft request' (duration: 377.181939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:57.552009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.08005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-08T10:48:57.552049Z","caller":"traceutil/trace.go:171","msg":"trace[11800807] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1261; }","duration":"355.165141ms","start":"2026-04-08T10:48:57.196874Z","end":"2026-04-08T10:48:57.552039Z","steps":["trace[11800807] 'agreement among raft nodes before linearized reading' (duration: 355.07608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:57.552098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:57.196858Z","time spent":"355.231763ms","remote":"127.0.0.1:35162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-08T10:48:57.55208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:57.169411Z","time spent":"382.587738ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T10:48:57.606642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.647044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T10:48:57.606807Z","caller":"traceutil/trace.go:171","msg":"trace[356973335] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1262; }","duration":"332.848357ms","start":"2026-04-08T10:48:57.273944Z","end":"2026-04-08T10:48:57.606792Z","steps":["trace[356973335] 'agreement among raft nodes before linearized reading' (duration: 332.636064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:57.606826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.303404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T10:48:57.606896Z","caller":"traceutil/trace.go:171","msg":"trace[1064968690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1262; }","duration":"219.408226ms","start":"2026-04-08T10:48:57.387471Z","end":"2026-04-08T10:48:57.60688Z","steps":["trace[1064968690] 'agreement among raft nodes before linearized reading' (duration: 219.290154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:57.606851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:57.273926Z","time spent":"332.916158ms","remote":"127.0.0.1:42032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-04-08T10:48:57.606717Z","caller":"traceutil/trace.go:171","msg":"trace[1553339493] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"403.458415ms","start":"2026-04-08T10:48:57.203211Z","end":"2026-04-08T10:48:57.606669Z","steps":["trace[1553339493] 'process raft request' (duration: 403.269802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:57.60709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:57.2032Z","time spent":"403.801022ms","remote":"127.0.0.1:34980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T10:48:57.606746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.667705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T10:48:57.607269Z","caller":"traceutil/trace.go:171","msg":"trace[323415945] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1262; }","duration":"176.205595ms","start":"2026-04-08T10:48:57.431048Z","end":"2026-04-08T10:48:57.607253Z","steps":["trace[323415945] 'agreement among raft nodes before linearized reading' (duration: 175.663625ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:57.733412Z","caller":"traceutil/trace.go:171","msg":"trace[1477051369] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"121.527611ms","start":"2026-04-08T10:48:57.611846Z","end":"2026-04-08T10:48:57.733374Z","steps":["trace[1477051369] 'process raft request' (duration: 48.560919ms)","trace[1477051369] 'compare' (duration: 72.683987ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:48:57.733928Z","caller":"traceutil/trace.go:171","msg":"trace[1895648360] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"120.664215ms","start":"2026-04-08T10:48:57.612686Z","end":"2026-04-08T10:48:57.73335Z","steps":["trace[1895648360] 'process raft request' (duration: 120.576954ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:57.751719Z","caller":"traceutil/trace.go:171","msg":"trace[415843318] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"133.89654ms","start":"2026-04-08T10:48:57.617466Z","end":"2026-04-08T10:48:57.751362Z","steps":["trace[415843318] 'process raft request' (duration: 133.473222ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:58.729519Z","caller":"traceutil/trace.go:171","msg":"trace[1855808597] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"409.115361ms","start":"2026-04-08T10:48:58.320376Z","end":"2026-04-08T10:48:58.729492Z","steps":["trace[1855808597] 'read index received' (duration: 409.022859ms)","trace[1855808597] 'applied index is now lower than readState.Index' (duration: 91.191µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:48:58.729547Z","caller":"traceutil/trace.go:171","msg":"trace[2057972752] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"486.984584ms","start":"2026-04-08T10:48:58.242527Z","end":"2026-04-08T10:48:58.729512Z","steps":["trace[2057972752] 'process raft request' (duration: 486.817591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:58.729679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:58.242502Z","time spent":"487.111106ms","remote":"127.0.0.1:34882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T10:48:58.729727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.329774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T10:48:58.729802Z","caller":"traceutil/trace.go:171","msg":"trace[698860404] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1268; }","duration":"409.443006ms","start":"2026-04-08T10:48:58.320343Z","end":"2026-04-08T10:48:58.729786Z","steps":["trace[698860404] 'agreement among raft nodes before linearized reading' (duration: 409.275853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:58.729851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:58.320328Z","time spent":"409.512058ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-08T10:48:59.22968Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2834344630691060651,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T10:48:59.40182Z","caller":"traceutil/trace.go:171","msg":"trace[1211973518] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"826.247989ms","start":"2026-04-08T10:48:58.575549Z","end":"2026-04-08T10:48:59.401797Z","steps":["trace[1211973518] 'process raft request' (duration: 826.096427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:59.40197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:58.575532Z","time spent":"826.361411ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T10:48:59.442989Z","caller":"traceutil/trace.go:171","msg":"trace[120681060] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1315; }","duration":"713.360407ms","start":"2026-04-08T10:48:58.729587Z","end":"2026-04-08T10:48:59.442947Z","steps":["trace[120681060] 'read index received' (duration: 672.820516ms)","trace[120681060] 'applied index is now lower than readState.Index' (duration: 40.538761ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:48:59.443016Z","caller":"traceutil/trace.go:171","msg":"trace[641574881] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"708.291333ms","start":"2026-04-08T10:48:58.734686Z","end":"2026-04-08T10:48:59.442978Z","steps":["trace[641574881] 'process raft request' (duration: 708.074039ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:59.443129Z","caller":"traceutil/trace.go:171","msg":"trace[271996981] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"441.098054ms","start":"2026-04-08T10:48:59.002016Z","end":"2026-04-08T10:48:59.443114Z","steps":["trace[271996981] 'process raft request' (duration: 440.874009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:59.44317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:58.73466Z","time spent":"708.441906ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T10:48:59.443242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:59.001993Z","time spent":"441.172055ms","remote":"127.0.0.1:34882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T10:48:59.443251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05563829s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T10:48:59.443322Z","caller":"traceutil/trace.go:171","msg":"trace[1796595379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1271; }","duration":"1.055745682s","start":"2026-04-08T10:48:58.387559Z","end":"2026-04-08T10:48:59.443305Z","steps":["trace[1796595379] 'agreement among raft nodes before linearized reading' (duration: 1.055573569s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:59.443301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.737001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-08T10:48:59.443355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:58.387549Z","time spent":"1.055801343s","remote":"127.0.0.1:34816","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-04-08T10:48:59.443378Z","caller":"traceutil/trace.go:171","msg":"trace[1268443688] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1271; }","duration":"356.885613ms","start":"2026-04-08T10:48:59.086475Z","end":"2026-04-08T10:48:59.443361Z","steps":["trace[1268443688] 'agreement among raft nodes before linearized reading' (duration: 356.7183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:59.443418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.152119ms","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-04-08T10:48:59.443459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.63148ms","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-04-08T10:48:59.443432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:59.086461Z","time spent":"356.959675ms","remote":"127.0.0.1:35268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-04-08T10:48:59.443533Z","caller":"traceutil/trace.go:171","msg":"trace[756856215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1271; }","duration":"246.764371ms","start":"2026-04-08T10:48:59.196751Z","end":"2026-04-08T10:48:59.443515Z","steps":["trace[756856215] 'agreement among raft nodes before linearized reading' (duration: 246.524007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:59.443579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.827033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T10:48:59.443609Z","caller":"traceutil/trace.go:171","msg":"trace[2111549023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1271; }","duration":"246.874894ms","start":"2026-04-08T10:48:59.196726Z","end":"2026-04-08T10:48:59.443601Z","steps":["trace[2111549023] 'agreement among raft nodes before linearized reading' (duration: 246.729491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:59.443611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.891394ms","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-04-08T10:48:59.443652Z","caller":"traceutil/trace.go:171","msg":"trace[1417465339] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1271; }","duration":"246.969036ms","start":"2026-04-08T10:48:59.196671Z","end":"2026-04-08T10:48:59.443641Z","steps":["trace[1417465339] 'agreement among raft nodes before linearized reading' (duration: 246.808263ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:48:59.443487Z","caller":"traceutil/trace.go:171","msg":"trace[1943746366] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1271; }","duration":"832.258742ms","start":"2026-04-08T10:48:58.611211Z","end":"2026-04-08T10:48:59.443469Z","steps":["trace[1943746366] 'agreement among raft nodes before linearized reading' (duration: 832.047828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:48:59.443743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:48:58.611196Z","time spent":"832.537956ms","remote":"127.0.0.1:35046","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-04-08T10:48:59.443772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.830493ms","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-04-08T10:48:59.443858Z","caller":"traceutil/trace.go:171","msg":"trace[1475839847] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1271; }","duration":"246.948065ms","start":"2026-04-08T10:48:59.196892Z","end":"2026-04-08T10:48:59.44384Z","steps":["trace[1475839847] 'agreement among raft nodes before linearized reading' (duration: 246.756752ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:49:01.558394Z","caller":"traceutil/trace.go:171","msg":"trace[1794970454] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"152.297562ms","start":"2026-04-08T10:49:01.406067Z","end":"2026-04-08T10:49:01.558364Z","steps":["trace[1794970454] 'process raft request' (duration: 152.001166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:49:01.677514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.672469ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-08T10:49:01.677988Z","caller":"traceutil/trace.go:171","msg":"trace[1389284024] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"115.399418ms","start":"2026-04-08T10:49:01.56257Z","end":"2026-04-08T10:49:01.677969Z","steps":["trace[1389284024] 'process raft request' (duration: 115.329117ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:49:01.677995Z","caller":"traceutil/trace.go:171","msg":"trace[124849484] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"115.609493ms","start":"2026-04-08T10:49:01.562371Z","end":"2026-04-08T10:49:01.677981Z","steps":["trace[124849484] 'process raft request' (duration: 115.236865ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:49:01.67815Z","caller":"traceutil/trace.go:171","msg":"trace[1217103561] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"115.501471ms","start":"2026-04-08T10:49:01.562636Z","end":"2026-04-08T10:49:01.678138Z","steps":["trace[1217103561] 'process raft request' (duration: 115.302757ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:49:01.678214Z","caller":"traceutil/trace.go:171","msg":"trace[236781009] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"112.27443ms","start":"2026-04-08T10:49:01.565902Z","end":"2026-04-08T10:49:01.678176Z","steps":["trace[236781009] 'process raft request' (duration: 112.213879ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:49:01.678223Z","caller":"traceutil/trace.go:171","msg":"trace[878783393] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"115.519021ms","start":"2026-04-08T10:49:01.562691Z","end":"2026-04-08T10:49:01.67821Z","steps":["trace[878783393] 'process raft request' (duration: 115.356518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:49:03.601554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.086054ms","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":"info","ts":"2026-04-08T10:49:03.601638Z","caller":"traceutil/trace.go:171","msg":"trace[1763017849] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1294; }","duration":"108.231807ms","start":"2026-04-08T10:49:03.493388Z","end":"2026-04-08T10:49:03.60162Z","steps":["trace[1763017849] 'count revisions from in-memory index tree' (duration: 107.998902ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:49:09.464789Z","caller":"traceutil/trace.go:171","msg":"trace[552074167] linearizableReadLoop","detail":"{readStateIndex:1391; appliedIndex:1390; }","duration":"162.597942ms","start":"2026-04-08T10:49:09.302167Z","end":"2026-04-08T10:49:09.464765Z","steps":["trace[552074167] 'read index received' (duration: 162.429139ms)","trace[552074167] 'applied index is now lower than readState.Index' (duration: 167.653µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:49:09.464851Z","caller":"traceutil/trace.go:171","msg":"trace[1769380627] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"187.338161ms","start":"2026-04-08T10:49:09.277467Z","end":"2026-04-08T10:49:09.464805Z","steps":["trace[1769380627] 'process raft request' (duration: 187.139447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:49:09.464959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.770625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T10:49:09.465051Z","caller":"traceutil/trace.go:171","msg":"trace[1059376846] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1343; }","duration":"162.877477ms","start":"2026-04-08T10:49:09.302153Z","end":"2026-04-08T10:49:09.46503Z","steps":["trace[1059376846] 'agreement among raft nodes before linearized reading' (duration: 162.729094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:49:10.150685Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2834344630691060785,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T10:49:10.380313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.457808ms","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-04-08T10:49:10.380387Z","caller":"traceutil/trace.go:171","msg":"trace[552367179] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1343; }","duration":"823.579811ms","start":"2026-04-08T10:49:09.55679Z","end":"2026-04-08T10:49:10.380369Z","steps":["trace[552367179] 'range keys from in-memory index tree' (duration: 823.255084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:49:10.380426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:49:09.556691Z","time spent":"823.726463ms","remote":"127.0.0.1:35046","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-04-08T10:49:10.382924Z","caller":"traceutil/trace.go:171","msg":"trace[1289990149] linearizableReadLoop","detail":"{readStateIndex:1392; appliedIndex:1391; }","duration":"733.093635ms","start":"2026-04-08T10:49:09.649813Z","end":"2026-04-08T10:49:10.382906Z","steps":["trace[1289990149] 'read index received' (duration: 732.890761ms)","trace[1289990149] 'applied index is now lower than readState.Index' (duration: 201.674µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:49:10.383023Z","caller":"traceutil/trace.go:171","msg":"trace[679967397] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"763.531558ms","start":"2026-04-08T10:49:09.61948Z","end":"2026-04-08T10:49:10.383012Z","steps":["trace[679967397] 'process raft request' (duration: 763.290663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:49:10.38311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:49:09.619457Z","time spent":"763.594298ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T10:49:10.383265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.608395ms","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-04-08T10:49:10.383266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.534539ms","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-04-08T10:49:10.383345Z","caller":"traceutil/trace.go:171","msg":"trace[967736848] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1344; }","duration":"667.665211ms","start":"2026-04-08T10:49:09.715657Z","end":"2026-04-08T10:49:10.383323Z","steps":["trace[967736848] 'agreement among raft nodes before linearized reading' (duration: 667.447667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:49:10.383368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.567433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-08T10:49:10.383396Z","caller":"traceutil/trace.go:171","msg":"trace[885508539] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1344; }","duration":"733.634774ms","start":"2026-04-08T10:49:09.649754Z","end":"2026-04-08T10:49:10.383388Z","steps":["trace[885508539] 'agreement among raft nodes before linearized reading' (duration: 733.565443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:49:10.383396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:49:09.715638Z","time spent":"667.748153ms","remote":"127.0.0.1:35046","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-04-08T10:49:10.383422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:49:09.649734Z","time spent":"733.682365ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-08T10:49:10.383298Z","caller":"traceutil/trace.go:171","msg":"trace[449020058] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1344; }","duration":"664.657206ms","start":"2026-04-08T10:49:09.718632Z","end":"2026-04-08T10:49:10.38329Z","steps":["trace[449020058] 'agreement among raft nodes before linearized reading' (duration: 664.567254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:49:10.38351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:49:09.718625Z","time spent":"664.87688ms","remote":"127.0.0.1:35046","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-04-08T10:49:10.383628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.840239ms","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-04-08T10:49:10.383661Z","caller":"traceutil/trace.go:171","msg":"trace[2076940868] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1344; }","duration":"664.913109ms","start":"2026-04-08T10:49:09.71874Z","end":"2026-04-08T10:49:10.383653Z","steps":["trace[2076940868] 'agreement among raft nodes before linearized reading' (duration: 664.830428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:49:10.383687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:49:09.718718Z","time spent":"664.963081ms","remote":"127.0.0.1:35046","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-04-08T10:49:10.383734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.704905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-08T10:49:10.383789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.696375ms","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-04-08T10:49:10.383856Z","caller":"traceutil/trace.go:171","msg":"trace[301434718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1344; }","duration":"665.790248ms","start":"2026-04-08T10:49:09.718055Z","end":"2026-04-08T10:49:10.383846Z","steps":["trace[301434718] 'agreement among raft nodes before linearized reading' (duration: 665.625934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:49:10.383891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:49:09.718042Z","time spent":"665.842378ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-08T10:49:10.383934Z","caller":"traceutil/trace.go:171","msg":"trace[108439391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1344; }","duration":"665.932719ms","start":"2026-04-08T10:49:09.717899Z","end":"2026-04-08T10:49:10.383832Z","steps":["trace[108439391] 'agreement among raft nodes before linearized reading' (duration: 665.577202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:49:10.384002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:49:09.717888Z","time spent":"666.102653ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T10:49:10.497411Z","caller":"traceutil/trace.go:171","msg":"trace[811153608] linearizableReadLoop","detail":"{readStateIndex:1393; appliedIndex:1392; }","duration":"107.893559ms","start":"2026-04-08T10:49:10.389497Z","end":"2026-04-08T10:49:10.49739Z","steps":["trace[811153608] 'read index received' (duration: 105.510175ms)","trace[811153608] 'applied index is now lower than readState.Index' (duration: 2.382694ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:49:10.497605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.079843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T10:49:10.497688Z","caller":"traceutil/trace.go:171","msg":"trace[309858121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1345; }","duration":"108.202965ms","start":"2026-04-08T10:49:10.389475Z","end":"2026-04-08T10:49:10.497678Z","steps":["trace[309858121] 'agreement among raft nodes before linearized reading' (duration: 108.047322ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:49:10.497753Z","caller":"traceutil/trace.go:171","msg":"trace[1772692631] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"110.245253ms","start":"2026-04-08T10:49:10.38748Z","end":"2026-04-08T10:49:10.497726Z","steps":["trace[1772692631] 'process raft request' (duration: 107.515133ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:49:10.498879Z","caller":"traceutil/trace.go:171","msg":"trace[1930551423] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"108.577223ms","start":"2026-04-08T10:49:10.390287Z","end":"2026-04-08T10:49:10.498864Z","steps":["trace[1930551423] 'process raft request' (duration: 108.544452ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:49:10.49905Z","caller":"traceutil/trace.go:171","msg":"trace[49659144] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"109.237844ms","start":"2026-04-08T10:49:10.389802Z","end":"2026-04-08T10:49:10.499039Z","steps":["trace[49659144] 'process raft request' (duration: 108.940408ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:49:10.499137Z","caller":"traceutil/trace.go:171","msg":"trace[2028502216] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"109.317135ms","start":"2026-04-08T10:49:10.389809Z","end":"2026-04-08T10:49:10.499126Z","steps":["trace[2028502216] 'process raft request' (duration: 108.965349ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:49:10.499212Z","caller":"traceutil/trace.go:171","msg":"trace[1586200464] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"109.356606ms","start":"2026-04-08T10:49:10.389845Z","end":"2026-04-08T10:49:10.499202Z","steps":["trace[1586200464] 'process raft request' (duration: 108.954548ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:49:10.499262Z","caller":"traceutil/trace.go:171","msg":"trace[851880778] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"109.430407ms","start":"2026-04-08T10:49:10.389752Z","end":"2026-04-08T10:49:10.499182Z","steps":["trace[851880778] 'process raft request' (duration: 108.832737ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:49:10.4995Z","caller":"traceutil/trace.go:171","msg":"trace[1330444005] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"109.675982ms","start":"2026-04-08T10:49:10.389812Z","end":"2026-04-08T10:49:10.499488Z","steps":["trace[1330444005] 'process raft request' (duration: 108.879518ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:49:46.957747Z","caller":"traceutil/trace.go:171","msg":"trace[232184934] transaction","detail":"{read_only:false; response_revision:1518; number_of_response:1; }","duration":"110.459488ms","start":"2026-04-08T10:49:46.847259Z","end":"2026-04-08T10:49:46.957719Z","steps":["trace[232184934] 'process raft request' (duration: 110.251224ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:49:59.136059Z","caller":"traceutil/trace.go:171","msg":"trace[1757284887] linearizableReadLoop","detail":"{readStateIndex:1629; appliedIndex:1628; }","duration":"101.474388ms","start":"2026-04-08T10:49:59.034562Z","end":"2026-04-08T10:49:59.136036Z","steps":["trace[1757284887] 'read index received' (duration: 88.622733ms)","trace[1757284887] 'applied index is now lower than readState.Index' (duration: 12.850515ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:49:59.136152Z","caller":"traceutil/trace.go:171","msg":"trace[132045176] transaction","detail":"{read_only:false; response_revision:1571; number_of_response:1; }","duration":"185.117741ms","start":"2026-04-08T10:49:58.951001Z","end":"2026-04-08T10:49:59.136119Z","steps":["trace[132045176] 'process raft request' (duration: 172.189604ms)","trace[132045176] 'compare' (duration: 12.751213ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:49:59.13642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.848406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T10:49:59.136469Z","caller":"traceutil/trace.go:171","msg":"trace[1167062511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1571; }","duration":"101.950747ms","start":"2026-04-08T10:49:59.034506Z","end":"2026-04-08T10:49:59.136457Z","steps":["trace[1167062511] 'agreement among raft nodes before linearized reading' (duration: 101.664261ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:52:11.526429Z","caller":"traceutil/trace.go:171","msg":"trace[1369683871] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"172.921044ms","start":"2026-04-08T10:52:11.353467Z","end":"2026-04-08T10:52:11.526388Z","steps":["trace[1369683871] 'process raft request' (duration: 172.70805ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:52:12.096887Z","caller":"traceutil/trace.go:171","msg":"trace[1818413135] linearizableReadLoop","detail":"{readStateIndex:2287; appliedIndex:2286; }","duration":"132.898233ms","start":"2026-04-08T10:52:11.963968Z","end":"2026-04-08T10:52:12.096867Z","steps":["trace[1818413135] 'read index received' (duration: 132.75402ms)","trace[1818413135] 'applied index is now lower than readState.Index' (duration: 143.273µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:52:12.096972Z","caller":"traceutil/trace.go:171","msg":"trace[1922980852] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"146.796114ms","start":"2026-04-08T10:52:11.950135Z","end":"2026-04-08T10:52:12.096932Z","steps":["trace[1922980852] 'process raft request' (duration: 146.5978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:52:12.097103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.114967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-08T10:52:12.097193Z","caller":"traceutil/trace.go:171","msg":"trace[1883638880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2201; }","duration":"133.245301ms","start":"2026-04-08T10:52:11.963934Z","end":"2026-04-08T10:52:12.097179Z","steps":["trace[1883638880] 'agreement among raft nodes before linearized reading' (duration: 133.012425ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:52:13.739579Z","caller":"traceutil/trace.go:171","msg":"trace[621580922] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"138.576034ms","start":"2026-04-08T10:52:13.600943Z","end":"2026-04-08T10:52:13.739519Z","steps":["trace[621580922] 'process raft request' (duration: 79.739395ms)","trace[621580922] 'compare' (duration: 58.658034ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:52:14.413283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.58057ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T10:52:14.413443Z","caller":"traceutil/trace.go:171","msg":"trace[1671127746] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"675.928806ms","start":"2026-04-08T10:52:13.737487Z","end":"2026-04-08T10:52:14.413416Z","steps":["trace[1671127746] 'process raft request' (duration: 406.092161ms)","trace[1671127746] 'compare' (duration: 269.306244ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:52:14.413516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:52:13.737456Z","time spent":"676.035768ms","remote":"127.0.0.1:35478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1146,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T10:52:14.441205Z","caller":"traceutil/trace.go:171","msg":"trace[1316456080] linearizableReadLoop","detail":"{readStateIndex:2294; appliedIndex:2292; }","duration":"670.966779ms","start":"2026-04-08T10:52:13.770212Z","end":"2026-04-08T10:52:14.441179Z","steps":["trace[1316456080] 'read index received' (duration: 373.389614ms)","trace[1316456080] 'applied index is now lower than readState.Index' (duration: 297.575585ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:52:14.441541Z","caller":"traceutil/trace.go:171","msg":"trace[125835508] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"697.651609ms","start":"2026-04-08T10:52:13.743832Z","end":"2026-04-08T10:52:14.441484Z","steps":["trace[125835508] 'process raft request' (duration: 697.017696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:52:14.441852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.605761ms","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-04-08T10:52:14.442073Z","caller":"traceutil/trace.go:171","msg":"trace[1565085638] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2208; }","duration":"671.899727ms","start":"2026-04-08T10:52:13.770147Z","end":"2026-04-08T10:52:14.442047Z","steps":["trace[1565085638] 'agreement among raft nodes before linearized reading' (duration: 671.373477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:52:14.441892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:52:13.743793Z","time spent":"697.914124ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T10:52:14.442224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:52:13.770127Z","time spent":"672.081121ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-08T10:52:14.479118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.024563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2723"} {"level":"info","ts":"2026-04-08T10:52:14.479183Z","caller":"traceutil/trace.go:171","msg":"trace[959550111] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2209; }","duration":"136.144005ms","start":"2026-04-08T10:52:14.343025Z","end":"2026-04-08T10:52:14.479169Z","steps":["trace[959550111] 'agreement among raft nodes before linearized reading' (duration: 135.904121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:52:14.479189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.755248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-04-08T10:52:14.479236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.731357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-08T10:52:14.479301Z","caller":"traceutil/trace.go:171","msg":"trace[1885516769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2209; }","duration":"339.822159ms","start":"2026-04-08T10:52:14.139459Z","end":"2026-04-08T10:52:14.479281Z","steps":["trace[1885516769] 'agreement among raft nodes before linearized reading' (duration: 339.705577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:52:14.479343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:52:14.139438Z","time spent":"339.89716ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T10:52:14.479105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.429763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-08T10:52:14.479405Z","caller":"traceutil/trace.go:171","msg":"trace[238603318] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2209; }","duration":"375.810141ms","start":"2026-04-08T10:52:14.103584Z","end":"2026-04-08T10:52:14.479394Z","steps":["trace[238603318] 'agreement among raft nodes before linearized reading' (duration: 375.318061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:52:14.479474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:52:14.103558Z","time spent":"375.903833ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-08T10:52:14.479659Z","caller":"traceutil/trace.go:171","msg":"trace[1863007772] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2209; }","duration":"297.867751ms","start":"2026-04-08T10:52:14.181355Z","end":"2026-04-08T10:52:14.479223Z","steps":["trace[1863007772] 'agreement among raft nodes before linearized reading' (duration: 297.735089ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:52:14.571057Z","caller":"traceutil/trace.go:171","msg":"trace[1351379675] transaction","detail":"{read_only:false; response_revision:2210; number_of_response:1; }","duration":"123.670622ms","start":"2026-04-08T10:52:14.447362Z","end":"2026-04-08T10:52:14.571033Z","steps":["trace[1351379675] 'process raft request' (duration: 114.983643ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:52:15.470436Z","caller":"traceutil/trace.go:171","msg":"trace[1410431125] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"138.89075ms","start":"2026-04-08T10:52:15.331503Z","end":"2026-04-08T10:52:15.470394Z","steps":["trace[1410431125] 'process raft request' (duration: 138.625765ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:52:16.69657Z","caller":"traceutil/trace.go:171","msg":"trace[1088335992] transaction","detail":"{read_only:false; response_revision:2224; number_of_response:1; }","duration":"112.235159ms","start":"2026-04-08T10:52:16.584306Z","end":"2026-04-08T10:52:16.696542Z","steps":["trace[1088335992] 'process raft request' (duration: 112.194778ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:52:16.696685Z","caller":"traceutil/trace.go:171","msg":"trace[946056993] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"114.2868ms","start":"2026-04-08T10:52:16.582351Z","end":"2026-04-08T10:52:16.696638Z","steps":["trace[946056993] 'process raft request' (duration: 114.112596ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:52:16.696902Z","caller":"traceutil/trace.go:171","msg":"trace[163058701] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"114.505333ms","start":"2026-04-08T10:52:16.582084Z","end":"2026-04-08T10:52:16.696589Z","steps":["trace[163058701] 'process raft request' (duration: 114.255308ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:52:17.687465Z","caller":"traceutil/trace.go:171","msg":"trace[1875888697] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"148.391754ms","start":"2026-04-08T10:52:17.539009Z","end":"2026-04-08T10:52:17.687401Z","steps":["trace[1875888697] 'process raft request' (duration: 73.527644ms)","trace[1875888697] 'compare' (duration: 74.566975ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:52:19.84574Z","caller":"traceutil/trace.go:171","msg":"trace[1167583327] transaction","detail":"{read_only:false; response_revision:2234; number_of_response:1; }","duration":"333.485746ms","start":"2026-04-08T10:52:19.512227Z","end":"2026-04-08T10:52:19.845713Z","steps":["trace[1167583327] 'process raft request' (duration: 333.326223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:52:19.845905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:52:19.512185Z","time spent":"333.632729ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T10:52:20.064746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.565997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T10:52:20.064869Z","caller":"traceutil/trace.go:171","msg":"trace[690089759] linearizableReadLoop","detail":"{readStateIndex:2322; appliedIndex:2321; }","duration":"164.961709ms","start":"2026-04-08T10:52:19.899887Z","end":"2026-04-08T10:52:20.064849Z","steps":["trace[690089759] 'read index received' (duration: 54.260869ms)","trace[690089759] 'applied index is now lower than readState.Index' (duration: 110.69936ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:52:20.06501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.126762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-04-08T10:52:20.065048Z","caller":"traceutil/trace.go:171","msg":"trace[1095843714] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2235; }","duration":"165.198663ms","start":"2026-04-08T10:52:19.899836Z","end":"2026-04-08T10:52:20.065035Z","steps":["trace[1095843714] 'agreement among raft nodes before linearized reading' (duration: 165.061771ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:52:20.065008Z","caller":"traceutil/trace.go:171","msg":"trace[1748992485] transaction","detail":"{read_only:false; response_revision:2235; number_of_response:1; }","duration":"174.166947ms","start":"2026-04-08T10:52:19.890803Z","end":"2026-04-08T10:52:20.06497Z","steps":["trace[1748992485] 'process raft request' (duration: 63.285034ms)","trace[1748992485] 'compare' (duration: 110.401134ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:52:21.365932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.941682ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T10:52:21.366301Z","caller":"traceutil/trace.go:171","msg":"trace[1209529460] linearizableReadLoop","detail":"{readStateIndex:2326; appliedIndex:2325; }","duration":"572.834504ms","start":"2026-04-08T10:52:20.793442Z","end":"2026-04-08T10:52:21.366277Z","steps":["trace[1209529460] 'read index received' (duration: 193.436653ms)","trace[1209529460] 'applied index is now lower than readState.Index' (duration: 379.395221ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:52:21.366497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.033788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-08T10:52:21.36662Z","caller":"traceutil/trace.go:171","msg":"trace[1286175603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2239; }","duration":"573.198131ms","start":"2026-04-08T10:52:20.793404Z","end":"2026-04-08T10:52:21.366602Z","steps":["trace[1286175603] 'agreement among raft nodes before linearized reading' (duration: 572.966667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:52:21.366667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:52:20.793385Z","time spent":"573.266123ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T10:52:21.366382Z","caller":"traceutil/trace.go:171","msg":"trace[1789439043] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"643.259498ms","start":"2026-04-08T10:52:20.723091Z","end":"2026-04-08T10:52:21.36635Z","steps":["trace[1789439043] 'process raft request' (duration: 263.797105ms)","trace[1789439043] 'compare' (duration: 378.793739ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:52:21.367005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.489447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-08T10:52:21.367048Z","caller":"traceutil/trace.go:171","msg":"trace[1266732598] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2239; }","duration":"310.565238ms","start":"2026-04-08T10:52:21.056471Z","end":"2026-04-08T10:52:21.367037Z","steps":["trace[1266732598] 'agreement among raft nodes before linearized reading' (duration: 310.435455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:52:21.367075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:52:21.056453Z","time spent":"310.614379ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-08T10:52:21.367062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:52:20.72307Z","time spent":"643.740796ms","remote":"127.0.0.1:35046","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-04-08T10:52:21.367183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.692371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-04-08T10:52:21.367233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.011479ms","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-04-08T10:52:21.367284Z","caller":"traceutil/trace.go:171","msg":"trace[1928073649] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2239; }","duration":"573.816823ms","start":"2026-04-08T10:52:20.79345Z","end":"2026-04-08T10:52:21.367267Z","steps":["trace[1928073649] 'agreement among raft nodes before linearized reading' (duration: 573.454216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:52:21.36758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:52:20.793435Z","time spent":"574.126619ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-08T10:52:21.367422Z","caller":"traceutil/trace.go:171","msg":"trace[846566521] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2239; }","duration":"451.286473ms","start":"2026-04-08T10:52:20.91611Z","end":"2026-04-08T10:52:21.367397Z","steps":["trace[846566521] 'agreement among raft nodes before linearized reading' (duration: 450.800464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:52:21.367781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:52:20.916095Z","time spent":"451.666131ms","remote":"127.0.0.1:35046","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-04-08T10:52:21.561126Z","caller":"traceutil/trace.go:171","msg":"trace[1388670688] transaction","detail":"{read_only:false; response_revision:2240; number_of_response:1; }","duration":"184.277264ms","start":"2026-04-08T10:52:21.37682Z","end":"2026-04-08T10:52:21.561097Z","steps":["trace[1388670688] 'process raft request' (duration: 106.622869ms)","trace[1388670688] 'compare' (duration: 77.457212ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:52:21.561468Z","caller":"traceutil/trace.go:171","msg":"trace[1108893744] transaction","detail":"{read_only:false; response_revision:2241; number_of_response:1; }","duration":"184.56503ms","start":"2026-04-08T10:52:21.376875Z","end":"2026-04-08T10:52:21.56144Z","steps":["trace[1108893744] 'process raft request' (duration: 184.173062ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:52:21.561519Z","caller":"traceutil/trace.go:171","msg":"trace[1073736801] linearizableReadLoop","detail":"{readStateIndex:2330; appliedIndex:2326; }","duration":"175.560654ms","start":"2026-04-08T10:52:21.38593Z","end":"2026-04-08T10:52:21.56149Z","steps":["trace[1073736801] 'read index received' (duration: 97.550271ms)","trace[1073736801] 'applied index is now lower than readState.Index' (duration: 78.008983ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:52:21.561645Z","caller":"traceutil/trace.go:171","msg":"trace[81686688] transaction","detail":"{read_only:false; response_revision:2242; number_of_response:1; }","duration":"184.5683ms","start":"2026-04-08T10:52:21.377057Z","end":"2026-04-08T10:52:21.561625Z","steps":["trace[81686688] 'process raft request' (duration: 184.276135ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:52:21.561664Z","caller":"traceutil/trace.go:171","msg":"trace[1810805289] transaction","detail":"{read_only:false; response_revision:2243; number_of_response:1; }","duration":"184.372476ms","start":"2026-04-08T10:52:21.37727Z","end":"2026-04-08T10:52:21.561643Z","steps":["trace[1810805289] 'process raft request' (duration: 184.168752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:52:21.561835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.312229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T10:52:21.561839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.812989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T10:52:21.561881Z","caller":"traceutil/trace.go:171","msg":"trace[406835715] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:2243; }","duration":"174.459243ms","start":"2026-04-08T10:52:21.38741Z","end":"2026-04-08T10:52:21.561869Z","steps":["trace[406835715] 'agreement among raft nodes before linearized reading' (duration: 174.265309ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:52:21.561907Z","caller":"traceutil/trace.go:171","msg":"trace[944001355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2243; }","duration":"175.971402ms","start":"2026-04-08T10:52:21.385917Z","end":"2026-04-08T10:52:21.561888Z","steps":["trace[944001355] 'agreement among raft nodes before linearized reading' (duration: 175.737618ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:52:24.818406Z","caller":"traceutil/trace.go:171","msg":"trace[1803418977] transaction","detail":"{read_only:false; response_revision:2256; number_of_response:1; }","duration":"194.977353ms","start":"2026-04-08T10:52:24.623381Z","end":"2026-04-08T10:52:24.818358Z","steps":["trace[1803418977] 'process raft request' (duration: 194.831691ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:52:26.929226Z","caller":"traceutil/trace.go:171","msg":"trace[1612872476] linearizableReadLoop","detail":"{readStateIndex:2356; appliedIndex:2355; }","duration":"104.189903ms","start":"2026-04-08T10:52:26.825014Z","end":"2026-04-08T10:52:26.929204Z","steps":["trace[1612872476] 'read index received' (duration: 104.006599ms)","trace[1612872476] 'applied index is now lower than readState.Index' (duration: 181.964µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:52:26.929282Z","caller":"traceutil/trace.go:171","msg":"trace[936273460] transaction","detail":"{read_only:false; response_revision:2267; number_of_response:1; }","duration":"107.995496ms","start":"2026-04-08T10:52:26.821241Z","end":"2026-04-08T10:52:26.929236Z","steps":["trace[936273460] 'process raft request' (duration: 107.785392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:52:26.929579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.55302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-08T10:52:26.929627Z","caller":"traceutil/trace.go:171","msg":"trace[1815380313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2267; }","duration":"104.663192ms","start":"2026-04-08T10:52:26.824952Z","end":"2026-04-08T10:52:26.929615Z","steps":["trace[1815380313] 'agreement among raft nodes before linearized reading' (duration: 104.344066ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:52:31.220031Z","caller":"traceutil/trace.go:171","msg":"trace[612318783] linearizableReadLoop","detail":"{readStateIndex:2378; appliedIndex:2377; }","duration":"226.720212ms","start":"2026-04-08T10:52:30.993286Z","end":"2026-04-08T10:52:31.220006Z","steps":["trace[612318783] 'read index received' (duration: 226.434857ms)","trace[612318783] 'applied index is now lower than readState.Index' (duration: 283.915µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:52:31.2201Z","caller":"traceutil/trace.go:171","msg":"trace[358884513] transaction","detail":"{read_only:false; response_revision:2289; number_of_response:1; }","duration":"240.836687ms","start":"2026-04-08T10:52:30.979228Z","end":"2026-04-08T10:52:31.220064Z","steps":["trace[358884513] 'process raft request' (duration: 240.609513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:52:31.220243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.927577ms","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-04-08T10:52:31.220283Z","caller":"traceutil/trace.go:171","msg":"trace[1109782446] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2289; }","duration":"227.011998ms","start":"2026-04-08T10:52:30.993261Z","end":"2026-04-08T10:52:31.220273Z","steps":["trace[1109782446] 'agreement among raft nodes before linearized reading' (duration: 226.849764ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:53:03.610286Z","caller":"traceutil/trace.go:171","msg":"trace[1437785707] transaction","detail":"{read_only:false; response_revision:2446; number_of_response:1; }","duration":"130.825112ms","start":"2026-04-08T10:53:03.479423Z","end":"2026-04-08T10:53:03.610248Z","steps":["trace[1437785707] 'process raft request' (duration: 130.386794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:53:04.029056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.089589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-08T10:53:04.029145Z","caller":"traceutil/trace.go:171","msg":"trace[2074137542] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2449; }","duration":"127.244832ms","start":"2026-04-08T10:53:03.901878Z","end":"2026-04-08T10:53:04.029123Z","steps":["trace[2074137542] 'range keys from in-memory index tree' (duration: 126.877965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:53:04.029396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.968443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:134 size:103360"} {"level":"info","ts":"2026-04-08T10:53:04.02946Z","caller":"traceutil/trace.go:171","msg":"trace[1912931257] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:134; response_revision:2449; }","duration":"114.040914ms","start":"2026-04-08T10:53:03.915405Z","end":"2026-04-08T10:53:04.029446Z","steps":["trace[1912931257] 'range keys from in-memory index tree' (duration: 113.558215ms)"],"step_count":1}