{"level":"warn","ts":"2026-07-01T07:25:07.267918Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T07:25:07.26803Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-01T07:25:07.268159Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T07:25:07.268176Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-07-01T07:25:07.268199Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-01T07:25:07.269112Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-07-01T07:25:07.269232Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-01T07:25:07.273517Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.904815ms"} {"level":"info","ts":"2026-07-01T07:25:07.284951Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-07-01T07:25:07.28503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T07:25:07.285052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-07-01T07:25:07.285066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T07:25:07.285077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-07-01T07:25:07.285138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-07-01T07:25:07.305961Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T07:25:07.307653Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T07:25:07.309037Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-01T07:25:07.312264Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T07:25:07.312438Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T07:25:07.31261Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T07:25:07.31264Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T07:25:07.312647Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T07:25:07.313322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-07-01T07:25:07.313472Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-07-01T07:25:07.316667Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-01T07:25:07.316884Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T07:25:07.316911Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T07:25:07.316982Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-07-01T07:25:07.316996Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-07-01T07:25:07.386338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T07:25:07.386404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T07:25:07.386439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-07-01T07:25:07.386463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-07-01T07:25:07.386477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-07-01T07:25:07.386498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-07-01T07:25:07.386515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-07-01T07:25:07.388623Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:25:07.389518Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T07:25:07.389505Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T07:25:07.389708Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T07:25:07.389902Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T07:25:07.389969Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-01T07:25:07.391042Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:25:07.391156Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:25:07.391331Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:25:07.392515Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"info","ts":"2026-07-01T07:25:07.393704Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-01T07:25:16.358868Z","caller":"traceutil/trace.go:171","msg":"trace[1416736631] linearizableReadLoop","detail":"{readStateIndex:300; appliedIndex:299; }","duration":"211.951818ms","start":"2026-07-01T07:25:16.146894Z","end":"2026-07-01T07:25:16.358846Z","steps":["trace[1416736631] 'read index received' (duration: 205.16396ms)","trace[1416736631] 'applied index is now lower than readState.Index' (duration: 6.787088ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:25:16.359033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.085381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-01T07:25:16.359083Z","caller":"traceutil/trace.go:171","msg":"trace[19875452] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:288; }","duration":"212.230545ms","start":"2026-07-01T07:25:16.146842Z","end":"2026-07-01T07:25:16.359073Z","steps":["trace[19875452] 'agreement among raft nodes before linearized reading' (duration: 212.098002ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:25:16.359062Z","caller":"traceutil/trace.go:171","msg":"trace[135425035] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"248.299705ms","start":"2026-07-01T07:25:16.110654Z","end":"2026-07-01T07:25:16.358953Z","steps":["trace[135425035] 'process raft request' (duration: 241.426605ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:25:30.661295Z","caller":"traceutil/trace.go:171","msg":"trace[1489977804] linearizableReadLoop","detail":"{readStateIndex:400; appliedIndex:399; }","duration":"255.910962ms","start":"2026-07-01T07:25:30.405358Z","end":"2026-07-01T07:25:30.661269Z","steps":["trace[1489977804] 'read index received' (duration: 255.630115ms)","trace[1489977804] 'applied index is now lower than readState.Index' (duration: 279.557µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:25:30.661401Z","caller":"traceutil/trace.go:171","msg":"trace[1978404661] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"357.564566ms","start":"2026-07-01T07:25:30.303805Z","end":"2026-07-01T07:25:30.66137Z","steps":["trace[1978404661] 'process raft request' (duration: 357.303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:30.661583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.197678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-01T07:25:30.661666Z","caller":"traceutil/trace.go:171","msg":"trace[46436796] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:385; }","duration":"256.339072ms","start":"2026-07-01T07:25:30.405306Z","end":"2026-07-01T07:25:30.661645Z","steps":["trace[46436796] 'agreement among raft nodes before linearized reading' (duration: 256.179298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:30.661657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.430469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-01T07:25:30.661739Z","caller":"traceutil/trace.go:171","msg":"trace[1419663645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:385; }","duration":"241.557752ms","start":"2026-07-01T07:25:30.420164Z","end":"2026-07-01T07:25:30.661722Z","steps":["trace[1419663645] 'agreement among raft nodes before linearized reading' (duration: 241.402848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:30.661801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.07401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:25:30.6619Z","caller":"traceutil/trace.go:171","msg":"trace[932210752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:385; }","duration":"234.217094ms","start":"2026-07-01T07:25:30.427656Z","end":"2026-07-01T07:25:30.661873Z","steps":["trace[932210752] 'agreement among raft nodes before linearized reading' (duration: 234.025349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:30.662272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:25:30.303781Z","time spent":"357.739151ms","remote":"127.0.0.1:34050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:25:40.896369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.363833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12665"} {"level":"info","ts":"2026-07-01T07:25:40.896481Z","caller":"traceutil/trace.go:171","msg":"trace[801337207] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:439; }","duration":"102.510207ms","start":"2026-07-01T07:25:40.793931Z","end":"2026-07-01T07:25:40.896441Z","steps":["trace[801337207] 'range keys from in-memory index tree' (duration: 102.185579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:43.692386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.38672ms","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-07-01T07:25:43.692488Z","caller":"traceutil/trace.go:171","msg":"trace[1378106529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:479; }","duration":"301.532414ms","start":"2026-07-01T07:25:43.390934Z","end":"2026-07-01T07:25:43.692467Z","steps":["trace[1378106529] 'range keys from in-memory index tree' (duration: 301.286138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:43.692505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.73978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T07:25:43.692532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:25:43.390915Z","time spent":"301.606356ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-01T07:25:43.69255Z","caller":"traceutil/trace.go:171","msg":"trace[1186139680] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:479; }","duration":"472.803582ms","start":"2026-07-01T07:25:43.219725Z","end":"2026-07-01T07:25:43.692529Z","steps":["trace[1186139680] 'range keys from in-memory index tree' (duration: 472.686569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:43.692574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:25:43.21971Z","time spent":"472.857903ms","remote":"127.0.0.1:33676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-07-01T07:25:43.692549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.048501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-01T07:25:43.692629Z","caller":"traceutil/trace.go:171","msg":"trace[1168917803] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:479; }","duration":"386.171123ms","start":"2026-07-01T07:25:43.306438Z","end":"2026-07-01T07:25:43.692609Z","steps":["trace[1168917803] 'range keys from in-memory index tree' (duration: 385.942918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:43.692669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:25:43.306421Z","time spent":"386.239315ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-01T07:25:43.897629Z","caller":"traceutil/trace.go:171","msg":"trace[1353250523] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"201.068307ms","start":"2026-07-01T07:25:43.696536Z","end":"2026-07-01T07:25:43.897604Z","steps":["trace[1353250523] 'process raft request' (duration: 200.847102ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:25:43.899115Z","caller":"traceutil/trace.go:171","msg":"trace[1857659928] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:497; }","duration":"190.411196ms","start":"2026-07-01T07:25:43.708676Z","end":"2026-07-01T07:25:43.899088Z","steps":["trace[1857659928] 'read index received' (duration: 188.668903ms)","trace[1857659928] 'applied index is now lower than readState.Index' (duration: 1.741063ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:25:43.899246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.54824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:25:43.899285Z","caller":"traceutil/trace.go:171","msg":"trace[1545222610] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:481; }","duration":"190.603391ms","start":"2026-07-01T07:25:43.708671Z","end":"2026-07-01T07:25:43.899274Z","steps":["trace[1545222610] 'agreement among raft nodes before linearized reading' (duration: 190.511359ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:25:43.899451Z","caller":"traceutil/trace.go:171","msg":"trace[1932286851] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"201.009376ms","start":"2026-07-01T07:25:43.69842Z","end":"2026-07-01T07:25:43.89943Z","steps":["trace[1932286851] 'process raft request' (duration: 200.574806ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:25:47.774101Z","caller":"traceutil/trace.go:171","msg":"trace[1152571282] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"152.843278ms","start":"2026-07-01T07:25:47.621225Z","end":"2026-07-01T07:25:47.774068Z","steps":["trace[1152571282] 'process raft request' (duration: 151.613519ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:25:48.413752Z","caller":"traceutil/trace.go:171","msg":"trace[1824460346] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"124.462823ms","start":"2026-07-01T07:25:48.289271Z","end":"2026-07-01T07:25:48.413734Z","steps":["trace[1824460346] 'read index received' (duration: 123.586482ms)","trace[1824460346] 'applied index is now lower than readState.Index' (duration: 875.301µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:25:48.413848Z","caller":"traceutil/trace.go:171","msg":"trace[686262454] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"129.690961ms","start":"2026-07-01T07:25:48.284111Z","end":"2026-07-01T07:25:48.413802Z","steps":["trace[686262454] 'process raft request' (duration: 128.966034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:48.413905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.613767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:25:48.41394Z","caller":"traceutil/trace.go:171","msg":"trace[669047633] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:571; }","duration":"124.675408ms","start":"2026-07-01T07:25:48.289255Z","end":"2026-07-01T07:25:48.41393Z","steps":["trace[669047633] 'agreement among raft nodes before linearized reading' (duration: 124.601006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:48.414062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.643118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:25:48.414165Z","caller":"traceutil/trace.go:171","msg":"trace[1182572813] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:571; }","duration":"124.780101ms","start":"2026-07-01T07:25:48.289344Z","end":"2026-07-01T07:25:48.414124Z","steps":["trace[1182572813] 'agreement among raft nodes before linearized reading' (duration: 124.581726ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:25:49.03946Z","caller":"traceutil/trace.go:171","msg":"trace[1948569490] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"127.96927ms","start":"2026-07-01T07:25:48.911463Z","end":"2026-07-01T07:25:49.039432Z","steps":["trace[1948569490] 'process raft request' (duration: 119.258077ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:25:49.797302Z","caller":"traceutil/trace.go:171","msg":"trace[255561846] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"283.463521ms","start":"2026-07-01T07:25:49.513795Z","end":"2026-07-01T07:25:49.797259Z","steps":["trace[255561846] 'process raft request' (duration: 281.534425ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:25:50.065178Z","caller":"traceutil/trace.go:171","msg":"trace[2029752680] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"139.43344ms","start":"2026-07-01T07:25:49.925718Z","end":"2026-07-01T07:25:50.065152Z","steps":["trace[2029752680] 'read index received' (duration: 102.22414ms)","trace[2029752680] 'applied index is now lower than readState.Index' (duration: 37.20843ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:25:50.065309Z","caller":"traceutil/trace.go:171","msg":"trace[188552172] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"200.078763ms","start":"2026-07-01T07:25:49.865219Z","end":"2026-07-01T07:25:50.065298Z","steps":["trace[188552172] 'process raft request' (duration: 162.634878ms)","trace[188552172] 'compare' (duration: 37.142198ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:25:50.06558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.85429ms","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-07-01T07:25:50.065627Z","caller":"traceutil/trace.go:171","msg":"trace[347184425] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"139.954463ms","start":"2026-07-01T07:25:49.925661Z","end":"2026-07-01T07:25:50.065616Z","steps":["trace[347184425] 'agreement among raft nodes before linearized reading' (duration: 139.716697ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:50.065835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.095223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-01T07:25:50.065956Z","caller":"traceutil/trace.go:171","msg":"trace[2100827902] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:579; }","duration":"135.244857ms","start":"2026-07-01T07:25:49.930681Z","end":"2026-07-01T07:25:50.065926Z","steps":["trace[2100827902] 'agreement among raft nodes before linearized reading' (duration: 134.996731ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:25:50.836675Z","caller":"traceutil/trace.go:171","msg":"trace[106517027] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"337.085692ms","start":"2026-07-01T07:25:50.499562Z","end":"2026-07-01T07:25:50.836648Z","steps":["trace[106517027] 'process raft request' (duration: 336.541019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:50.836828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:25:50.499532Z","time spent":"337.216896ms","remote":"127.0.0.1:33676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:25:51.246935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.46331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:25:51.247169Z","caller":"traceutil/trace.go:171","msg":"trace[134879577] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"132.458289ms","start":"2026-07-01T07:25:51.114687Z","end":"2026-07-01T07:25:51.247146Z","steps":["trace[134879577] 'read index received' (duration: 53.222µs)","trace[134879577] 'applied index is now lower than readState.Index' (duration: 132.403567ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:25:51.24727Z","caller":"traceutil/trace.go:171","msg":"trace[1920401132] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"392.97803ms","start":"2026-07-01T07:25:50.85428Z","end":"2026-07-01T07:25:51.247258Z","steps":["trace[1920401132] 'process raft request' (duration: 140.852685ms)","trace[1920401132] 'compare' (duration: 251.299255ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:25:51.247337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:25:50.854267Z","time spent":"393.033741ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:25:51.247517Z","caller":"traceutil/trace.go:171","msg":"trace[526949098] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"344.464553ms","start":"2026-07-01T07:25:50.903017Z","end":"2026-07-01T07:25:51.247481Z","steps":["trace[526949098] 'process raft request' (duration: 344.039563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:51.247903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:25:50.902976Z","time spent":"344.833172ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T07:25:51.247582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.891089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-01T07:25:51.248031Z","caller":"traceutil/trace.go:171","msg":"trace[809745166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:588; }","duration":"133.364402ms","start":"2026-07-01T07:25:51.114635Z","end":"2026-07-01T07:25:51.248Z","steps":["trace[809745166] 'agreement among raft nodes before linearized reading' (duration: 132.641705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:51.247711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.822731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8lc2m\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-01T07:25:51.248117Z","caller":"traceutil/trace.go:171","msg":"trace[32788088] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8lc2m; range_end:; response_count:1; response_revision:588; }","duration":"127.246671ms","start":"2026-07-01T07:25:51.120835Z","end":"2026-07-01T07:25:51.248082Z","steps":["trace[32788088] 'agreement among raft nodes before linearized reading' (duration: 126.662117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:51.978932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.865463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:25:51.979128Z","caller":"traceutil/trace.go:171","msg":"trace[638714474] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"724.99177ms","start":"2026-07-01T07:25:51.254107Z","end":"2026-07-01T07:25:51.979099Z","steps":["trace[638714474] 'process raft request' (duration: 386.876791ms)","trace[638714474] 'compare' (duration: 337.704119ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:25:51.979272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:25:51.254085Z","time spent":"725.114433ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:25:51.979308Z","caller":"traceutil/trace.go:171","msg":"trace[1000773998] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"714.081993ms","start":"2026-07-01T07:25:51.265068Z","end":"2026-07-01T07:25:51.97915Z","steps":["trace[1000773998] 'process raft request' (duration: 713.97154ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:51.979387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:25:51.265046Z","time spent":"714.305768ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:25:51.979538Z","caller":"traceutil/trace.go:171","msg":"trace[889460962] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"718.576841ms","start":"2026-07-01T07:25:51.260928Z","end":"2026-07-01T07:25:51.979505Z","steps":["trace[889460962] 'read index received' (duration: 380.071783ms)","trace[889460962] 'applied index is now lower than readState.Index' (duration: 338.502098ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:25:51.979751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.812108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:25:51.979827Z","caller":"traceutil/trace.go:171","msg":"trace[2145535511] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:590; }","duration":"718.953751ms","start":"2026-07-01T07:25:51.26086Z","end":"2026-07-01T07:25:51.979814Z","steps":["trace[2145535511] 'agreement among raft nodes before linearized reading' (duration: 718.824468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:51.979891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:25:51.260831Z","time spent":"719.048003ms","remote":"127.0.0.1:34100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"warn","ts":"2026-07-01T07:25:51.979961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.87701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:25:51.980101Z","caller":"traceutil/trace.go:171","msg":"trace[1499159367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"553.140256ms","start":"2026-07-01T07:25:51.426926Z","end":"2026-07-01T07:25:51.980066Z","steps":["trace[1499159367] 'agreement among raft nodes before linearized reading' (duration: 552.88191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:51.980171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:25:51.426845Z","time spent":"553.31421ms","remote":"127.0.0.1:33592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T07:25:53.284325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.595196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:25:53.284417Z","caller":"traceutil/trace.go:171","msg":"trace[1609303306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"829.72784ms","start":"2026-07-01T07:25:52.454667Z","end":"2026-07-01T07:25:53.284395Z","steps":["trace[1609303306] 'range keys from in-memory index tree' (duration: 829.516465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:53.284458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:25:52.454648Z","time spent":"829.800821ms","remote":"127.0.0.1:33592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T07:25:53.284781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.197016661s","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-07-01T07:25:53.284828Z","caller":"traceutil/trace.go:171","msg":"trace[128936478] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:590; }","duration":"1.197100253s","start":"2026-07-01T07:25:52.087716Z","end":"2026-07-01T07:25:53.284816Z","steps":["trace[128936478] 'range keys from in-memory index tree' (duration: 1.196775186s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:53.284885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:25:52.087694Z","time spent":"1.197183396s","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-01T07:25:53.285093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.193776042s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-01T07:25:53.285207Z","caller":"traceutil/trace.go:171","msg":"trace[220901499] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:590; }","duration":"1.193919356s","start":"2026-07-01T07:25:52.091265Z","end":"2026-07-01T07:25:53.285185Z","steps":["trace[220901499] 'range keys from in-memory index tree' (duration: 1.193384903s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:53.28528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:25:52.091249Z","time spent":"1.194019148s","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-01T07:25:53.2858Z","caller":"traceutil/trace.go:171","msg":"trace[1307795627] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"830.93152ms","start":"2026-07-01T07:25:52.454853Z","end":"2026-07-01T07:25:53.285784Z","steps":["trace[1307795627] 'process raft request' (duration: 830.392856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:53.285917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:25:52.454825Z","time spent":"831.027742ms","remote":"127.0.0.1:33676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:25:53.286656Z","caller":"traceutil/trace.go:171","msg":"trace[1554300427] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"791.750852ms","start":"2026-07-01T07:25:52.494868Z","end":"2026-07-01T07:25:53.286619Z","steps":["trace[1554300427] 'process raft request' (duration: 791.503866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:53.286824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:25:52.494852Z","time spent":"791.882245ms","remote":"127.0.0.1:33852","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-07-01T07:25:53.991229Z","caller":"traceutil/trace.go:171","msg":"trace[1693522486] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"151.229268ms","start":"2026-07-01T07:25:53.839973Z","end":"2026-07-01T07:25:53.991202Z","steps":["trace[1693522486] 'process raft request' (duration: 85.819499ms)","trace[1693522486] 'compare' (duration: 65.143963ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:25:54.927799Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514536365742679,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-01T07:25:55.25692Z","caller":"traceutil/trace.go:171","msg":"trace[1763576524] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"830.17429ms","start":"2026-07-01T07:25:54.426716Z","end":"2026-07-01T07:25:55.25689Z","steps":["trace[1763576524] 'read index received' (duration: 829.909234ms)","trace[1763576524] 'applied index is now lower than readState.Index' (duration: 263.556µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:25:55.256983Z","caller":"traceutil/trace.go:171","msg":"trace[939055927] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"830.13315ms","start":"2026-07-01T07:25:54.426815Z","end":"2026-07-01T07:25:55.256948Z","steps":["trace[939055927] 'process raft request' (duration: 829.896794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:55.257109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.372945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T07:25:55.25715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:25:54.426785Z","time spent":"830.286974ms","remote":"127.0.0.1:33666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T07:25:55.257169Z","caller":"traceutil/trace.go:171","msg":"trace[1057537748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"830.488428ms","start":"2026-07-01T07:25:54.426666Z","end":"2026-07-01T07:25:55.257154Z","steps":["trace[1057537748] 'agreement among raft nodes before linearized reading' (duration: 830.327094ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:25:55.257204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:25:54.426637Z","time spent":"830.56014ms","remote":"127.0.0.1:33592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T07:25:55.257297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.19071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8lc2m\" ","response":"range_response_count:1 size:17243"} {"level":"info","ts":"2026-07-01T07:25:55.257353Z","caller":"traceutil/trace.go:171","msg":"trace[1476878166] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8lc2m; range_end:; response_count:1; response_revision:608; }","duration":"118.464836ms","start":"2026-07-01T07:25:55.138873Z","end":"2026-07-01T07:25:55.257338Z","steps":["trace[1476878166] 'agreement among raft nodes before linearized reading' (duration: 118.21681ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:26:04.946019Z","caller":"traceutil/trace.go:171","msg":"trace[1885389550] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"115.683469ms","start":"2026-07-01T07:26:04.830286Z","end":"2026-07-01T07:26:04.945969Z","steps":["trace[1885389550] 'process raft request' (duration: 101.633305ms)","trace[1885389550] 'compare' (duration: 13.866649ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:26:07.369667Z","caller":"traceutil/trace.go:171","msg":"trace[584848766] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"133.975917ms","start":"2026-07-01T07:26:07.235668Z","end":"2026-07-01T07:26:07.369643Z","steps":["trace[584848766] 'process raft request' (duration: 133.835333ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:26:10.083535Z","caller":"traceutil/trace.go:171","msg":"trace[1912724979] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"130.494021ms","start":"2026-07-01T07:26:09.953013Z","end":"2026-07-01T07:26:10.083507Z","steps":["trace[1912724979] 'process raft request' (duration: 130.191923ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:26:39.593676Z","caller":"traceutil/trace.go:171","msg":"trace[1015401598] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"114.349025ms","start":"2026-07-01T07:26:39.478843Z","end":"2026-07-01T07:26:39.593192Z","steps":["trace[1015401598] 'process raft request' (duration: 114.185881ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:26:43.761682Z","caller":"traceutil/trace.go:171","msg":"trace[1659341922] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"157.377253ms","start":"2026-07-01T07:26:43.604286Z","end":"2026-07-01T07:26:43.761663Z","steps":["trace[1659341922] 'process raft request' (duration: 157.179428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:44.347904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.866671ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:26:44.347986Z","caller":"traceutil/trace.go:171","msg":"trace[1635487285] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"582.120808ms","start":"2026-07-01T07:26:43.76585Z","end":"2026-07-01T07:26:44.34797Z","steps":["trace[1635487285] 'process raft request' (duration: 345.120824ms)","trace[1635487285] 'compare' (duration: 236.4226ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:26:44.348028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:26:43.765824Z","time spent":"582.18408ms","remote":"127.0.0.1:33852","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-07-01T07:26:44.496191Z","caller":"traceutil/trace.go:171","msg":"trace[111142318] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"729.65758ms","start":"2026-07-01T07:26:43.76651Z","end":"2026-07-01T07:26:44.496167Z","steps":["trace[111142318] 'process raft request' (duration: 729.365672ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:26:44.496206Z","caller":"traceutil/trace.go:171","msg":"trace[500507084] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1010; }","duration":"604.762285ms","start":"2026-07-01T07:26:43.89142Z","end":"2026-07-01T07:26:44.496182Z","steps":["trace[500507084] 'read index received' (duration: 219.463941ms)","trace[500507084] 'applied index is now lower than readState.Index' (duration: 385.297094ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:26:44.496336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:26:43.766498Z","time spent":"729.758642ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:26:44.49636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.905429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T07:26:44.49644Z","caller":"traceutil/trace.go:171","msg":"trace[49335477] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:973; }","duration":"605.049322ms","start":"2026-07-01T07:26:43.891378Z","end":"2026-07-01T07:26:44.496427Z","steps":["trace[49335477] 'agreement among raft nodes before linearized reading' (duration: 604.890798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:44.496492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:26:43.891361Z","time spent":"605.121124ms","remote":"127.0.0.1:33910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-07-01T07:26:44.496827Z","caller":"traceutil/trace.go:171","msg":"trace[1543101563] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"257.319933ms","start":"2026-07-01T07:26:44.239496Z","end":"2026-07-01T07:26:44.496816Z","steps":["trace[1543101563] 'process raft request' (duration: 257.19921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:44.496865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.050588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-01T07:26:44.496895Z","caller":"traceutil/trace.go:171","msg":"trace[1318839248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:974; }","duration":"266.085239ms","start":"2026-07-01T07:26:44.230801Z","end":"2026-07-01T07:26:44.496886Z","steps":["trace[1318839248] 'agreement among raft nodes before linearized reading' (duration: 265.969206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:44.497113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.135795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-01T07:26:44.497145Z","caller":"traceutil/trace.go:171","msg":"trace[447447751] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:974; }","duration":"265.186826ms","start":"2026-07-01T07:26:44.231948Z","end":"2026-07-01T07:26:44.497135Z","steps":["trace[447447751] 'agreement among raft nodes before linearized reading' (duration: 265.100314ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:26:44.497309Z","caller":"traceutil/trace.go:171","msg":"trace[1678016858] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"728.225324ms","start":"2026-07-01T07:26:43.767992Z","end":"2026-07-01T07:26:44.496218Z","steps":["trace[1678016858] 'process raft request' (duration: 728.0583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:44.497413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:26:43.767981Z","time spent":"729.363422ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:27:04.241151Z","caller":"traceutil/trace.go:171","msg":"trace[238725933] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"420.217132ms","start":"2026-07-01T07:27:03.820913Z","end":"2026-07-01T07:27:04.24113Z","steps":["trace[238725933] 'process raft request' (duration: 420.064569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:04.241282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:03.820895Z","time spent":"420.321566ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:27:04.585704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.753298ms","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-07-01T07:27:04.585811Z","caller":"traceutil/trace.go:171","msg":"trace[767241877] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1036; }","duration":"143.913152ms","start":"2026-07-01T07:27:04.44187Z","end":"2026-07-01T07:27:04.585783Z","steps":["trace[767241877] 'range keys from in-memory index tree' (duration: 143.42306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:05.554078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.68587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:27:05.554151Z","caller":"traceutil/trace.go:171","msg":"trace[1200049936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1042; }","duration":"263.791953ms","start":"2026-07-01T07:27:05.290341Z","end":"2026-07-01T07:27:05.554133Z","steps":["trace[1200049936] 'range keys from in-memory index tree' (duration: 263.600098ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:05.554262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.901531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T07:27:05.554135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.329628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:27:05.554476Z","caller":"traceutil/trace.go:171","msg":"trace[430049168] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1042; }","duration":"128.668867ms","start":"2026-07-01T07:27:05.425735Z","end":"2026-07-01T07:27:05.554404Z","steps":["trace[430049168] 'range keys from in-memory index tree' (duration: 128.265557ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:05.554631Z","caller":"traceutil/trace.go:171","msg":"trace[862097334] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1042; }","duration":"185.079825ms","start":"2026-07-01T07:27:05.369252Z","end":"2026-07-01T07:27:05.554332Z","steps":["trace[862097334] 'count revisions from in-memory index tree' (duration: 184.777568ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:06.054523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.357151ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-01T07:27:21.243613Z","caller":"traceutil/trace.go:171","msg":"trace[956030722] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"135.181468ms","start":"2026-07-01T07:27:21.108407Z","end":"2026-07-01T07:27:21.243588Z","steps":["trace[956030722] 'process raft request' (duration: 135.120087ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:21.243647Z","caller":"traceutil/trace.go:171","msg":"trace[241273715] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"135.205419ms","start":"2026-07-01T07:27:21.108407Z","end":"2026-07-01T07:27:21.243613Z","steps":["trace[241273715] 'process raft request' (duration: 134.989574ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:21.427488Z","caller":"traceutil/trace.go:171","msg":"trace[704538631] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"174.085845ms","start":"2026-07-01T07:27:21.253388Z","end":"2026-07-01T07:27:21.427474Z","steps":["trace[704538631] 'process raft request' (duration: 174.053544ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:21.427722Z","caller":"traceutil/trace.go:171","msg":"trace[212589532] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"174.439663ms","start":"2026-07-01T07:27:21.253253Z","end":"2026-07-01T07:27:21.427693Z","steps":["trace[212589532] 'process raft request' (duration: 91.288827ms)","trace[212589532] 'compare' (duration: 82.789727ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:27:21.433705Z","caller":"traceutil/trace.go:171","msg":"trace[1708199083] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"127.077127ms","start":"2026-07-01T07:27:21.306614Z","end":"2026-07-01T07:27:21.433691Z","steps":["trace[1708199083] 'process raft request' (duration: 126.960735ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:24.519101Z","caller":"traceutil/trace.go:171","msg":"trace[1654838621] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"171.882282ms","start":"2026-07-01T07:27:24.347202Z","end":"2026-07-01T07:27:24.519084Z","steps":["trace[1654838621] 'process raft request' (duration: 171.788889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:25.06966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.904959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-01T07:27:25.069737Z","caller":"traceutil/trace.go:171","msg":"trace[305846979] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1153; }","duration":"217.032902ms","start":"2026-07-01T07:27:24.852684Z","end":"2026-07-01T07:27:25.069717Z","steps":["trace[305846979] 'range keys from in-memory index tree' (duration: 216.701933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:25.069791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.322749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-01T07:27:25.069823Z","caller":"traceutil/trace.go:171","msg":"trace[1344438864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1153; }","duration":"215.3868ms","start":"2026-07-01T07:27:24.854426Z","end":"2026-07-01T07:27:25.069813Z","steps":["trace[1344438864] 'range keys from in-memory index tree' (duration: 215.217096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:25.069924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.203757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-01T07:27:25.069951Z","caller":"traceutil/trace.go:171","msg":"trace[843054496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1153; }","duration":"213.240528ms","start":"2026-07-01T07:27:24.8567Z","end":"2026-07-01T07:27:25.069941Z","steps":["trace[843054496] 'range keys from in-memory index tree' (duration: 213.136576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:25.070204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.575529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-01T07:27:25.070233Z","caller":"traceutil/trace.go:171","msg":"trace[1449455780] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1153; }","duration":"183.645021ms","start":"2026-07-01T07:27:24.886579Z","end":"2026-07-01T07:27:25.070224Z","steps":["trace[1449455780] 'range keys from in-memory index tree' (duration: 183.431025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:25.070332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.719834ms","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-07-01T07:27:25.070362Z","caller":"traceutil/trace.go:171","msg":"trace[1260291226] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1153; }","duration":"322.779285ms","start":"2026-07-01T07:27:24.747575Z","end":"2026-07-01T07:27:25.070354Z","steps":["trace[1260291226] 'range keys from in-memory index tree' (duration: 322.622241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:25.070389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:24.747559Z","time spent":"322.820587ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-01T07:27:43.367688Z","caller":"traceutil/trace.go:171","msg":"trace[766737369] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1288; }","duration":"155.077487ms","start":"2026-07-01T07:27:43.21258Z","end":"2026-07-01T07:27:43.367657Z","steps":["trace[766737369] 'read index received' (duration: 155.067646ms)","trace[766737369] 'applied index is now lower than readState.Index' (duration: 7.651µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:27:43.367843Z","caller":"traceutil/trace.go:171","msg":"trace[1180724531] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"170.078567ms","start":"2026-07-01T07:27:43.19758Z","end":"2026-07-01T07:27:43.367658Z","steps":["trace[1180724531] 'process raft request' (duration: 169.980724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:43.367953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.86153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-01T07:27:43.36799Z","caller":"traceutil/trace.go:171","msg":"trace[1528225673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1234; }","duration":"132.962742ms","start":"2026-07-01T07:27:43.235015Z","end":"2026-07-01T07:27:43.367978Z","steps":["trace[1528225673] 'agreement among raft nodes before linearized reading' (duration: 132.778888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:43.368055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.460756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-01T07:27:43.368081Z","caller":"traceutil/trace.go:171","msg":"trace[781066528] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1234; }","duration":"155.523038ms","start":"2026-07-01T07:27:43.212552Z","end":"2026-07-01T07:27:43.368075Z","steps":["trace[781066528] 'agreement among raft nodes before linearized reading' (duration: 155.425205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:43.368172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.062755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-01T07:27:43.368187Z","caller":"traceutil/trace.go:171","msg":"trace[931754551] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1234; }","duration":"133.117746ms","start":"2026-07-01T07:27:43.235065Z","end":"2026-07-01T07:27:43.368183Z","steps":["trace[931754551] 'agreement among raft nodes before linearized reading' (duration: 133.070525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:43.368245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.125517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-01T07:27:43.368262Z","caller":"traceutil/trace.go:171","msg":"trace[2018479662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1234; }","duration":"133.174917ms","start":"2026-07-01T07:27:43.235083Z","end":"2026-07-01T07:27:43.368258Z","steps":["trace[2018479662] 'agreement among raft nodes before linearized reading' (duration: 133.136466ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:43.641915Z","caller":"traceutil/trace.go:171","msg":"trace[1277302569] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"268.425797ms","start":"2026-07-01T07:27:43.373461Z","end":"2026-07-01T07:27:43.641887Z","steps":["trace[1277302569] 'process raft request' (duration: 268.236352ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:43.651151Z","caller":"traceutil/trace.go:171","msg":"trace[428437276] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"277.386867ms","start":"2026-07-01T07:27:43.37375Z","end":"2026-07-01T07:27:43.651137Z","steps":["trace[428437276] 'process raft request' (duration: 277.356246ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:43.651184Z","caller":"traceutil/trace.go:171","msg":"trace[1371605621] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"277.456929ms","start":"2026-07-01T07:27:43.373706Z","end":"2026-07-01T07:27:43.651163Z","steps":["trace[1371605621] 'process raft request' (duration: 277.376127ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:43.651171Z","caller":"traceutil/trace.go:171","msg":"trace[1900165075] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"277.424139ms","start":"2026-07-01T07:27:43.373704Z","end":"2026-07-01T07:27:43.651128Z","steps":["trace[1900165075] 'process raft request' (duration: 277.310646ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:44.926539Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514536365744071,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T07:27:45.420225Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.040920322s","expected-duration":"1s"} {"level":"info","ts":"2026-07-01T07:27:45.420684Z","caller":"traceutil/trace.go:171","msg":"trace[609650895] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"1.041438835s","start":"2026-07-01T07:27:44.379197Z","end":"2026-07-01T07:27:45.420636Z","steps":["trace[609650895] 'process raft request' (duration: 1.041256161s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:45.421118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:44.379177Z","time spent":"1.041629619s","remote":"127.0.0.1:33666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T07:27:45.731652Z","caller":"traceutil/trace.go:171","msg":"trace[1233170745] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"1.121640568s","start":"2026-07-01T07:27:44.609992Z","end":"2026-07-01T07:27:45.731632Z","steps":["trace[1233170745] 'process raft request' (duration: 1.121470104s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:45.731779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:44.609968Z","time spent":"1.121747321s","remote":"127.0.0.1:33852","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-07-01T07:27:45.731837Z","caller":"traceutil/trace.go:171","msg":"trace[1548429280] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1293; }","duration":"1.306065718s","start":"2026-07-01T07:27:44.425739Z","end":"2026-07-01T07:27:45.731804Z","steps":["trace[1548429280] 'read index received' (duration: 994.709604ms)","trace[1548429280] 'applied index is now lower than readState.Index' (duration: 311.353584ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:27:45.731892Z","caller":"traceutil/trace.go:171","msg":"trace[856060886] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"634.147039ms","start":"2026-07-01T07:27:45.097725Z","end":"2026-07-01T07:27:45.731872Z","steps":["trace[856060886] 'process raft request' (duration: 633.867292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:45.73199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.306236772s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T07:27:45.732023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:45.0977Z","time spent":"634.234461ms","remote":"127.0.0.1:33748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:27:45.732034Z","caller":"traceutil/trace.go:171","msg":"trace[464781602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1242; }","duration":"1.306310425s","start":"2026-07-01T07:27:44.425709Z","end":"2026-07-01T07:27:45.732019Z","steps":["trace[464781602] 'agreement among raft nodes before linearized reading' (duration: 1.306230223s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:45.73208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:44.425694Z","time spent":"1.306373326s","remote":"127.0.0.1:33592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T07:27:45.752459Z","caller":"traceutil/trace.go:171","msg":"trace[511251833] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"326.21975ms","start":"2026-07-01T07:27:45.426216Z","end":"2026-07-01T07:27:45.752435Z","steps":["trace[511251833] 'process raft request' (duration: 326.002515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:45.752564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:45.426189Z","time spent":"326.326382ms","remote":"127.0.0.1:33666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T07:27:45.955144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.793272ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-01T07:27:45.95525Z","caller":"traceutil/trace.go:171","msg":"trace[1377589693] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1296; }","duration":"223.324077ms","start":"2026-07-01T07:27:45.731907Z","end":"2026-07-01T07:27:45.955231Z","steps":["trace[1377589693] 'read index received' (duration: 20.30914ms)","trace[1377589693] 'applied index is now lower than readState.Index' (duration: 203.013387ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:27:45.95548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.337897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-01T07:27:45.95554Z","caller":"traceutil/trace.go:171","msg":"trace[1610921398] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1243; }","duration":"301.456831ms","start":"2026-07-01T07:27:45.654068Z","end":"2026-07-01T07:27:45.955525Z","steps":["trace[1610921398] 'agreement among raft nodes before linearized reading' (duration: 301.261466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:45.955571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:45.654043Z","time spent":"301.521382ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-01T07:27:45.955571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.744155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-01T07:27:45.955607Z","caller":"traceutil/trace.go:171","msg":"trace[2037328967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1243; }","duration":"309.813947ms","start":"2026-07-01T07:27:45.645783Z","end":"2026-07-01T07:27:45.955597Z","steps":["trace[2037328967] 'agreement among raft nodes before linearized reading' (duration: 309.660073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:45.955591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.031475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-01T07:27:45.95569Z","caller":"traceutil/trace.go:171","msg":"trace[1482170724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1243; }","duration":"300.148848ms","start":"2026-07-01T07:27:45.655521Z","end":"2026-07-01T07:27:45.95567Z","steps":["trace[1482170724] 'agreement among raft nodes before linearized reading' (duration: 299.919402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:45.955724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.737278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-07-01T07:27:45.95573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:45.655515Z","time spent":"300.205989ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-01T07:27:45.955753Z","caller":"traceutil/trace.go:171","msg":"trace[1651607801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1243; }","duration":"583.80205ms","start":"2026-07-01T07:27:45.371943Z","end":"2026-07-01T07:27:45.955745Z","steps":["trace[1651607801] 'agreement among raft nodes before linearized reading' (duration: 583.721138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:45.955757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.39972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-01T07:27:45.955779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:45.371924Z","time spent":"583.849821ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T07:27:45.955639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:45.645768Z","time spent":"309.864048ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T07:27:45.955902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.470246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:27:45.955932Z","caller":"traceutil/trace.go:171","msg":"trace[43389879] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1243; }","duration":"974.538848ms","start":"2026-07-01T07:27:44.981383Z","end":"2026-07-01T07:27:45.955922Z","steps":["trace[43389879] 'agreement among raft nodes before linearized reading' (duration: 974.493977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:45.955943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.490956429s","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":"warn","ts":"2026-07-01T07:27:45.955958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:44.981337Z","time spent":"974.61403ms","remote":"127.0.0.1:57258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-07-01T07:27:45.955968Z","caller":"traceutil/trace.go:171","msg":"trace[856969636] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1243; }","duration":"1.491015761s","start":"2026-07-01T07:27:44.464944Z","end":"2026-07-01T07:27:45.95596Z","steps":["trace[856969636] 'agreement among raft nodes before linearized reading' (duration: 1.49097978s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:45.955988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:44.464925Z","time spent":"1.491059751s","remote":"127.0.0.1:33852","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":"info","ts":"2026-07-01T07:27:45.955783Z","caller":"traceutil/trace.go:171","msg":"trace[595343727] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1243; }","duration":"301.483041ms","start":"2026-07-01T07:27:45.654293Z","end":"2026-07-01T07:27:45.955776Z","steps":["trace[595343727] 'agreement among raft nodes before linearized reading' (duration: 301.41804ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:45.956074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:45.654275Z","time spent":"301.794439ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-01T07:27:45.980842Z","caller":"traceutil/trace.go:171","msg":"trace[293062287] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"224.354333ms","start":"2026-07-01T07:27:45.756464Z","end":"2026-07-01T07:27:45.980818Z","steps":["trace[293062287] 'process raft request' (duration: 224.175289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:45.980905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.659841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:27:45.980975Z","caller":"traceutil/trace.go:171","msg":"trace[244920968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1244; }","duration":"246.761734ms","start":"2026-07-01T07:27:45.734195Z","end":"2026-07-01T07:27:45.980957Z","steps":["trace[244920968] 'agreement among raft nodes before linearized reading' (duration: 246.624911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:46.490461Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514536365744087,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-01T07:27:46.714194Z","caller":"traceutil/trace.go:171","msg":"trace[679981041] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"754.215245ms","start":"2026-07-01T07:27:45.959929Z","end":"2026-07-01T07:27:46.714144Z","steps":["trace[679981041] 'process raft request' (duration: 753.605951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:46.71446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:45.959914Z","time spent":"754.395909ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:27:46.714909Z","caller":"traceutil/trace.go:171","msg":"trace[1118807657] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"754.621235ms","start":"2026-07-01T07:27:45.960276Z","end":"2026-07-01T07:27:46.714897Z","steps":["trace[1118807657] 'process raft request' (duration: 753.457546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:46.714982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:45.960254Z","time spent":"754.691206ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:27:46.715059Z","caller":"traceutil/trace.go:171","msg":"trace[956751673] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"754.626835ms","start":"2026-07-01T07:27:45.960394Z","end":"2026-07-01T07:27:46.715021Z","steps":["trace[956751673] 'process raft request' (duration: 754.334527ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:46.715264Z","caller":"traceutil/trace.go:171","msg":"trace[684970257] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"754.644285ms","start":"2026-07-01T07:27:45.960601Z","end":"2026-07-01T07:27:46.715245Z","steps":["trace[684970257] 'process raft request' (duration: 754.556343ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:46.715264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:45.960377Z","time spent":"754.780608ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:27:46.715351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:45.960593Z","time spent":"754.727387ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T07:27:46.715365Z","caller":"traceutil/trace.go:171","msg":"trace[542112849] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"754.941992ms","start":"2026-07-01T07:27:45.960399Z","end":"2026-07-01T07:27:46.715341Z","steps":["trace[542112849] 'process raft request' (duration: 754.463521ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:46.715447Z","caller":"traceutil/trace.go:171","msg":"trace[271666619] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"754.953553ms","start":"2026-07-01T07:27:45.960483Z","end":"2026-07-01T07:27:46.715437Z","steps":["trace[271666619] 'process raft request' (duration: 754.635405ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:46.715503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:45.960474Z","time spent":"755.002354ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:27:46.715507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:45.960389Z","time spent":"755.045615ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:27:46.716704Z","caller":"traceutil/trace.go:171","msg":"trace[76178632] linearizableReadLoop","detail":"{readStateIndex:1306; appliedIndex:1300; }","duration":"727.423405ms","start":"2026-07-01T07:27:45.989266Z","end":"2026-07-01T07:27:46.716689Z","steps":["trace[76178632] 'read index received' (duration: 724.527214ms)","trace[76178632] 'applied index is now lower than readState.Index' (duration: 2.895351ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:27:46.716762Z","caller":"traceutil/trace.go:171","msg":"trace[548810583] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"521.355122ms","start":"2026-07-01T07:27:46.195387Z","end":"2026-07-01T07:27:46.716742Z","steps":["trace[548810583] 'process raft request' (duration: 521.220939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:46.716806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.538737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:27:46.716838Z","caller":"traceutil/trace.go:171","msg":"trace[229817618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1251; }","duration":"727.604389ms","start":"2026-07-01T07:27:45.989224Z","end":"2026-07-01T07:27:46.716829Z","steps":["trace[229817618] 'agreement among raft nodes before linearized reading' (duration: 727.547498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:46.716836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:46.195366Z","time spent":"521.431695ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:27:46.716867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:45.989208Z","time spent":"727.652891ms","remote":"127.0.0.1:33592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T07:27:46.716897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.82213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T07:27:46.716925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.588922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T07:27:46.716955Z","caller":"traceutil/trace.go:171","msg":"trace[925920934] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1251; }","duration":"711.930123ms","start":"2026-07-01T07:27:46.00501Z","end":"2026-07-01T07:27:46.71694Z","steps":["trace[925920934] 'agreement among raft nodes before linearized reading' (duration: 711.829521ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:46.716975Z","caller":"traceutil/trace.go:171","msg":"trace[1994091133] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1251; }","duration":"279.643944ms","start":"2026-07-01T07:27:46.437317Z","end":"2026-07-01T07:27:46.716961Z","steps":["trace[1994091133] 'agreement among raft nodes before linearized reading' (duration: 279.546961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:46.716986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:46.004991Z","time spent":"711.988225ms","remote":"127.0.0.1:57268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-01T07:27:46.717152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.244353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:27:46.717813Z","caller":"traceutil/trace.go:171","msg":"trace[1798309819] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1251; }","duration":"578.945691ms","start":"2026-07-01T07:27:46.138853Z","end":"2026-07-01T07:27:46.717799Z","steps":["trace[1798309819] 'agreement among raft nodes before linearized reading' (duration: 578.274695ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:46.717873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:46.138827Z","time spent":"579.034512ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-01T07:27:55.968569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.524682ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-01T07:27:55.968899Z","caller":"traceutil/trace.go:171","msg":"trace[1010847477] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"205.826396ms","start":"2026-07-01T07:27:55.763042Z","end":"2026-07-01T07:27:55.968869Z","steps":["trace[1010847477] 'process raft request' (duration: 205.626821ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:55.969182Z","caller":"traceutil/trace.go:171","msg":"trace[324978417] linearizableReadLoop","detail":"{readStateIndex:1372; appliedIndex:1371; }","duration":"327.785028ms","start":"2026-07-01T07:27:55.64104Z","end":"2026-07-01T07:27:55.968825Z","steps":["trace[324978417] 'read index received' (duration: 221.061231ms)","trace[324978417] 'applied index is now lower than readState.Index' (duration: 106.720637ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:27:56.774481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.644184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:27:56.774613Z","caller":"traceutil/trace.go:171","msg":"trace[153204086] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1316; }","duration":"420.822679ms","start":"2026-07-01T07:27:56.35377Z","end":"2026-07-01T07:27:56.774592Z","steps":["trace[153204086] 'count revisions from in-memory index tree' (duration: 420.519331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:56.774742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.133680796s","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:535"} {"level":"info","ts":"2026-07-01T07:27:56.774825Z","caller":"traceutil/trace.go:171","msg":"trace[512544816] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1316; }","duration":"1.133805099s","start":"2026-07-01T07:27:55.640997Z","end":"2026-07-01T07:27:56.774802Z","steps":["trace[512544816] 'agreement among raft nodes before linearized reading' (duration: 328.048055ms)","trace[512544816] 'range keys from in-memory index tree' (duration: 805.394935ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:27:56.774883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:55.640975Z","time spent":"1.133896751s","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-01T07:27:56.774932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.501067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:27:56.775055Z","caller":"traceutil/trace.go:171","msg":"trace[1749861710] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1316; }","duration":"532.62239ms","start":"2026-07-01T07:27:56.242358Z","end":"2026-07-01T07:27:56.77498Z","steps":["trace[1749861710] 'count revisions from in-memory index tree' (duration: 532.335023ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:56.775117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:56.242335Z","time spent":"532.769344ms","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-07-01T07:27:56.774672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:56.353748Z","time spent":"420.90325ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-07-01T07:28:02.945261Z","caller":"traceutil/trace.go:171","msg":"trace[515564611] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1404; }","duration":"136.535531ms","start":"2026-07-01T07:28:02.808695Z","end":"2026-07-01T07:28:02.945231Z","steps":["trace[515564611] 'read index received' (duration: 127.654842ms)","trace[515564611] 'applied index is now lower than readState.Index' (duration: 8.879159ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:28:02.94559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.865939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-01T07:28:02.945699Z","caller":"traceutil/trace.go:171","msg":"trace[289280061] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1347; }","duration":"136.997333ms","start":"2026-07-01T07:28:02.808678Z","end":"2026-07-01T07:28:02.945675Z","steps":["trace[289280061] 'agreement among raft nodes before linearized reading' (duration: 136.695265ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:02.945328Z","caller":"traceutil/trace.go:171","msg":"trace[1566582370] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"137.31732ms","start":"2026-07-01T07:28:02.807974Z","end":"2026-07-01T07:28:02.945292Z","steps":["trace[1566582370] 'process raft request' (duration: 128.304198ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:02.947934Z","caller":"traceutil/trace.go:171","msg":"trace[746135824] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"133.264411ms","start":"2026-07-01T07:28:02.81465Z","end":"2026-07-01T07:28:02.947914Z","steps":["trace[746135824] 'process raft request' (duration: 133.166979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:02.948074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.788273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-01T07:28:02.948607Z","caller":"traceutil/trace.go:171","msg":"trace[1370632647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1349; }","duration":"134.366527ms","start":"2026-07-01T07:28:02.814225Z","end":"2026-07-01T07:28:02.948591Z","steps":["trace[1370632647] 'agreement among raft nodes before linearized reading' (duration: 133.6613ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:02.948095Z","caller":"traceutil/trace.go:171","msg":"trace[844550241] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"137.227398ms","start":"2026-07-01T07:28:02.810841Z","end":"2026-07-01T07:28:02.948069Z","steps":["trace[844550241] 'process raft request' (duration: 136.873319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:02.948188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.711947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-01T07:28:02.94883Z","caller":"traceutil/trace.go:171","msg":"trace[353233678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1349; }","duration":"133.361883ms","start":"2026-07-01T07:28:02.815447Z","end":"2026-07-01T07:28:02.948809Z","steps":["trace[353233678] 'agreement among raft nodes before linearized reading' (duration: 132.665066ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:03.676505Z","caller":"traceutil/trace.go:171","msg":"trace[1318614703] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"132.42376ms","start":"2026-07-01T07:28:03.544058Z","end":"2026-07-01T07:28:03.676482Z","steps":["trace[1318614703] 'process raft request' (duration: 132.283297ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:12.22164Z","caller":"traceutil/trace.go:171","msg":"trace[300581782] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"407.645374ms","start":"2026-07-01T07:28:11.813956Z","end":"2026-07-01T07:28:12.221601Z","steps":["trace[300581782] 'process raft request' (duration: 407.441419ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:12.221695Z","caller":"traceutil/trace.go:171","msg":"trace[1508038784] linearizableReadLoop","detail":"{readStateIndex:1449; appliedIndex:1449; }","duration":"402.669482ms","start":"2026-07-01T07:28:11.818975Z","end":"2026-07-01T07:28:12.221644Z","steps":["trace[1508038784] 'read index received' (duration: 402.628491ms)","trace[1508038784] 'applied index is now lower than readState.Index' (duration: 37.501µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:28:12.221828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:11.813928Z","time spent":"407.815108ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:28:12.222101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.079832ms","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-07-01T07:28:12.222152Z","caller":"traceutil/trace.go:171","msg":"trace[352730253] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1389; }","duration":"403.185864ms","start":"2026-07-01T07:28:11.818953Z","end":"2026-07-01T07:28:12.222139Z","steps":["trace[352730253] 'agreement among raft nodes before linearized reading' (duration: 402.852716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:12.222198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:11.818935Z","time spent":"403.252536ms","remote":"127.0.0.1:33852","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-07-01T07:28:12.225603Z","caller":"traceutil/trace.go:171","msg":"trace[200877995] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"205.678233ms","start":"2026-07-01T07:28:12.019903Z","end":"2026-07-01T07:28:12.225581Z","steps":["trace[200877995] 'process raft request' (duration: 205.5714ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:13.183725Z","caller":"traceutil/trace.go:171","msg":"trace[1086433777] linearizableReadLoop","detail":"{readStateIndex:1452; appliedIndex:1451; }","duration":"196.575568ms","start":"2026-07-01T07:28:12.98712Z","end":"2026-07-01T07:28:13.183696Z","steps":["trace[1086433777] 'read index received' (duration: 196.383813ms)","trace[1086433777] 'applied index is now lower than readState.Index' (duration: 190.425µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:28:13.183853Z","caller":"traceutil/trace.go:171","msg":"trace[2071500442] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"411.893569ms","start":"2026-07-01T07:28:12.771949Z","end":"2026-07-01T07:28:13.183843Z","steps":["trace[2071500442] 'process raft request' (duration: 411.535959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:13.183958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:12.771932Z","time spent":"411.951361ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:28:13.184006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.887287ms","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-07-01T07:28:13.184068Z","caller":"traceutil/trace.go:171","msg":"trace[1183905486] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1392; }","duration":"196.99685ms","start":"2026-07-01T07:28:12.987057Z","end":"2026-07-01T07:28:13.184054Z","steps":["trace[1183905486] 'agreement among raft nodes before linearized reading' (duration: 196.844536ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:13.26383Z","caller":"traceutil/trace.go:171","msg":"trace[119200794] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"198.936477ms","start":"2026-07-01T07:28:13.064872Z","end":"2026-07-01T07:28:13.263809Z","steps":["trace[119200794] 'process raft request' (duration: 198.821834ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:13.263998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.152148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-01T07:28:13.264069Z","caller":"traceutil/trace.go:171","msg":"trace[1448635539] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1393; }","duration":"274.2518ms","start":"2026-07-01T07:28:12.989801Z","end":"2026-07-01T07:28:13.264053Z","steps":["trace[1448635539] 'agreement among raft nodes before linearized reading' (duration: 274.020055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:13.264071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.896455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-01T07:28:13.264164Z","caller":"traceutil/trace.go:171","msg":"trace[838209204] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1393; }","duration":"253.029318ms","start":"2026-07-01T07:28:13.011112Z","end":"2026-07-01T07:28:13.264141Z","steps":["trace[838209204] 'agreement among raft nodes before linearized reading' (duration: 252.748271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:13.264221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.258763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-01T07:28:13.264248Z","caller":"traceutil/trace.go:171","msg":"trace[935844161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1393; }","duration":"253.321155ms","start":"2026-07-01T07:28:13.010919Z","end":"2026-07-01T07:28:13.26424Z","steps":["trace[935844161] 'agreement among raft nodes before linearized reading' (duration: 253.246683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:13.264362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.085983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-01T07:28:13.26439Z","caller":"traceutil/trace.go:171","msg":"trace[613593635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1393; }","duration":"256.137884ms","start":"2026-07-01T07:28:13.008245Z","end":"2026-07-01T07:28:13.264383Z","steps":["trace[613593635] 'agreement among raft nodes before linearized reading' (duration: 256.070783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:13.264484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.297585ms","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-07-01T07:28:13.264545Z","caller":"traceutil/trace.go:171","msg":"trace[1331788066] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1393; }","duration":"253.374096ms","start":"2026-07-01T07:28:13.011156Z","end":"2026-07-01T07:28:13.26453Z","steps":["trace[1331788066] 'agreement among raft nodes before linearized reading' (duration: 253.166311ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:13.461469Z","caller":"traceutil/trace.go:171","msg":"trace[2006543312] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"192.289463ms","start":"2026-07-01T07:28:13.269134Z","end":"2026-07-01T07:28:13.461424Z","steps":["trace[2006543312] 'process raft request' (duration: 192.15975ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:13.464373Z","caller":"traceutil/trace.go:171","msg":"trace[856504021] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"194.139639ms","start":"2026-07-01T07:28:13.270217Z","end":"2026-07-01T07:28:13.464357Z","steps":["trace[856504021] 'process raft request' (duration: 194.072318ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:13.464582Z","caller":"traceutil/trace.go:171","msg":"trace[670634090] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"195.120783ms","start":"2026-07-01T07:28:13.26941Z","end":"2026-07-01T07:28:13.464531Z","steps":["trace[670634090] 'process raft request' (duration: 194.798735ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:13.465074Z","caller":"traceutil/trace.go:171","msg":"trace[893579439] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"194.477087ms","start":"2026-07-01T07:28:13.270573Z","end":"2026-07-01T07:28:13.46505Z","steps":["trace[893579439] 'process raft request' (duration: 193.75171ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:13.466273Z","caller":"traceutil/trace.go:171","msg":"trace[859399883] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"193.80577ms","start":"2026-07-01T07:28:13.271747Z","end":"2026-07-01T07:28:13.465553Z","steps":["trace[859399883] 'process raft request' (duration: 192.798576ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:15.614612Z","caller":"traceutil/trace.go:171","msg":"trace[26510401] linearizableReadLoop","detail":"{readStateIndex:1462; appliedIndex:1461; }","duration":"323.476232ms","start":"2026-07-01T07:28:15.291114Z","end":"2026-07-01T07:28:15.61459Z","steps":["trace[26510401] 'read index received' (duration: 323.308398ms)","trace[26510401] 'applied index is now lower than readState.Index' (duration: 166.754µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:28:15.614767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.639585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:28:15.614752Z","caller":"traceutil/trace.go:171","msg":"trace[812594863] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"339.098277ms","start":"2026-07-01T07:28:15.275617Z","end":"2026-07-01T07:28:15.614716Z","steps":["trace[812594863] 'process raft request' (duration: 338.8281ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:15.614809Z","caller":"traceutil/trace.go:171","msg":"trace[1687832430] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1402; }","duration":"323.716948ms","start":"2026-07-01T07:28:15.291082Z","end":"2026-07-01T07:28:15.614799Z","steps":["trace[1687832430] 'agreement among raft nodes before linearized reading' (duration: 323.639216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:15.614829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.551621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:28:15.61493Z","caller":"traceutil/trace.go:171","msg":"trace[745524201] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1402; }","duration":"188.692224ms","start":"2026-07-01T07:28:15.426208Z","end":"2026-07-01T07:28:15.6149Z","steps":["trace[745524201] 'agreement among raft nodes before linearized reading' (duration: 188.541661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:15.614945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.069226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-01T07:28:15.614996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.845884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-01T07:28:15.615013Z","caller":"traceutil/trace.go:171","msg":"trace[858331740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1402; }","duration":"144.204759ms","start":"2026-07-01T07:28:15.470795Z","end":"2026-07-01T07:28:15.615Z","steps":["trace[858331740] 'agreement among raft nodes before linearized reading' (duration: 143.990573ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:15.615027Z","caller":"traceutil/trace.go:171","msg":"trace[977082626] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1402; }","duration":"144.896406ms","start":"2026-07-01T07:28:15.470122Z","end":"2026-07-01T07:28:15.615018Z","steps":["trace[977082626] 'agreement among raft nodes before linearized reading' (duration: 144.779173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:15.615177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.349318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-01T07:28:15.615211Z","caller":"traceutil/trace.go:171","msg":"trace[1797160587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1402; }","duration":"143.401739ms","start":"2026-07-01T07:28:15.471799Z","end":"2026-07-01T07:28:15.615201Z","steps":["trace[1797160587] 'agreement among raft nodes before linearized reading' (duration: 143.320668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:15.614866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:15.291069Z","time spent":"323.789489ms","remote":"127.0.0.1:33592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T07:28:15.615271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.476372ms","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-07-01T07:28:15.615228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.92247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-01T07:28:15.615319Z","caller":"traceutil/trace.go:171","msg":"trace[2017575168] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1402; }","duration":"141.543763ms","start":"2026-07-01T07:28:15.47376Z","end":"2026-07-01T07:28:15.615304Z","steps":["trace[2017575168] 'agreement among raft nodes before linearized reading' (duration: 141.158254ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:15.615346Z","caller":"traceutil/trace.go:171","msg":"trace[1146302152] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1402; }","duration":"148.084284ms","start":"2026-07-01T07:28:15.467238Z","end":"2026-07-01T07:28:15.615322Z","steps":["trace[1146302152] 'agreement among raft nodes before linearized reading' (duration: 147.685045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:15.615465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:15.275595Z","time spent":"339.226979ms","remote":"127.0.0.1:33852","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-07-01T07:28:23.825672Z","caller":"traceutil/trace.go:171","msg":"trace[715808551] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"104.563954ms","start":"2026-07-01T07:28:23.721083Z","end":"2026-07-01T07:28:23.825647Z","steps":["trace[715808551] 'process raft request' (duration: 78.552784ms)","trace[715808551] 'compare' (duration: 25.869426ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:28:23.826366Z","caller":"traceutil/trace.go:171","msg":"trace[1790675966] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"104.759179ms","start":"2026-07-01T07:28:23.721593Z","end":"2026-07-01T07:28:23.826352Z","steps":["trace[1790675966] 'process raft request' (duration: 104.616976ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:29:18.455179Z","caller":"traceutil/trace.go:171","msg":"trace[2045334396] transaction","detail":"{read_only:false; response_revision:1694; number_of_response:1; }","duration":"120.407714ms","start":"2026-07-01T07:29:18.334737Z","end":"2026-07-01T07:29:18.455144Z","steps":["trace[2045334396] 'process raft request' (duration: 90.549544ms)","trace[2045334396] 'compare' (duration: 29.526572ms)"],"step_count":2}