{"level":"warn","ts":"2026-07-17T03:44:39.043483Z","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-17T03:44:39.043608Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.177: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.177:2380","--initial-cluster=instance=https://199.204.45.177:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.177:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.177: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-17T03:44:39.043715Z","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-17T03:44:39.043785Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-07-17T03:44:39.043817Z","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-17T03:44:39.044957Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"]} {"level":"info","ts":"2026-07-17T03:44:39.045098Z","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.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.177: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-17T03:44:39.049779Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.324372ms"} {"level":"info","ts":"2026-07-17T03:44:39.064309Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7c53a18098324dc9","cluster-id":"4717e8aa221973c8"} {"level":"info","ts":"2026-07-17T03:44:39.064402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=()"} {"level":"info","ts":"2026-07-17T03:44:39.064435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 0"} {"level":"info","ts":"2026-07-17T03:44:39.064451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7c53a18098324dc9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-17T03:44:39.064468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 1"} {"level":"info","ts":"2026-07-17T03:44:39.064515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"warn","ts":"2026-07-17T03:44:39.072966Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-17T03:44:39.074482Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-17T03:44:39.075648Z","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-17T03:44:39.078589Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7c53a18098324dc9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-17T03:44:39.079265Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7c53a18098324dc9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-17T03:44:39.079385Z","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-17T03:44:39.079424Z","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-17T03:44:39.079435Z","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-17T03:44:39.0834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"info","ts":"2026-07-17T03:44:39.083565Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","added-peer-id":"7c53a18098324dc9","added-peer-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-07-17T03:44:39.085263Z","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-17T03:44:39.085452Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7c53a18098324dc9","initial-advertise-peer-urls":["https://199.204.45.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-17T03:44:39.085479Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-17T03:44:39.085563Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-07-17T03:44:39.085578Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-07-17T03:44:40.065059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 is starting a new election at term 1"} {"level":"info","ts":"2026-07-17T03:44:40.065108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-17T03:44:40.065136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgPreVoteResp from 7c53a18098324dc9 at term 1"} {"level":"info","ts":"2026-07-17T03:44:40.065151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became candidate at term 2"} {"level":"info","ts":"2026-07-17T03:44:40.065159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgVoteResp from 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-07-17T03:44:40.06517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became leader at term 2"} {"level":"info","ts":"2026-07-17T03:44:40.065193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7c53a18098324dc9 elected leader 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-07-17T03:44:40.066171Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7c53a18098324dc9","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.177:2379]}","request-path":"/0/members/7c53a18098324dc9/attributes","cluster-id":"4717e8aa221973c8","publish-timeout":"7s"} {"level":"info","ts":"2026-07-17T03:44:40.066206Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T03:44:40.066296Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T03:44:40.066335Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T03:44:40.067176Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T03:44:40.067303Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T03:44:40.067344Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T03:44:40.068315Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-17T03:44:40.068346Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-17T03:44:40.069063Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-17T03:44:40.07099Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.177:2379"} {"level":"warn","ts":"2026-07-17T03:45:02.536145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.06249ms","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-17T03:45:02.536257Z","caller":"traceutil/trace.go:171","msg":"trace[493210617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:425; }","duration":"689.196983ms","start":"2026-07-17T03:45:01.847038Z","end":"2026-07-17T03:45:02.536235Z","steps":["trace[493210617] 'range keys from in-memory index tree' (duration: 688.964447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:02.536307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:01.847024Z","time spent":"689.273445ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-17T03:45:02.536341Z","caller":"traceutil/trace.go:171","msg":"trace[1110243655] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"361.815531ms","start":"2026-07-17T03:45:02.174486Z","end":"2026-07-17T03:45:02.536301Z","steps":["trace[1110243655] 'process raft request' (duration: 337.294194ms)","trace[1110243655] 'compare' (duration: 24.426675ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:45:02.53597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.573917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T03:45:02.536526Z","caller":"traceutil/trace.go:171","msg":"trace[22731861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:425; }","duration":"880.171234ms","start":"2026-07-17T03:45:01.656336Z","end":"2026-07-17T03:45:02.536507Z","steps":["trace[22731861] 'range keys from in-memory index tree' (duration: 879.420454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:02.536558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:01.656318Z","time spent":"880.231296ms","remote":"127.0.0.1:47928","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-17T03:45:02.536789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:02.174465Z","time spent":"361.944184ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:45:03.256626Z","caller":"traceutil/trace.go:171","msg":"trace[378051259] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"715.920513ms","start":"2026-07-17T03:45:02.540676Z","end":"2026-07-17T03:45:03.256597Z","steps":["trace[378051259] 'process raft request' (duration: 715.746388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:03.2568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:02.540652Z","time spent":"716.050466ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:45:03.259813Z","caller":"traceutil/trace.go:171","msg":"trace[129175730] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"718.752073ms","start":"2026-07-17T03:45:02.54105Z","end":"2026-07-17T03:45:03.259802Z","steps":["trace[129175730] 'process raft request' (duration: 718.491086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:03.259874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:02.541037Z","time spent":"718.809725ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:45:03.259958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.566123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:45:03.260009Z","caller":"traceutil/trace.go:171","msg":"trace[1047484868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:428; }","duration":"452.654865ms","start":"2026-07-17T03:45:02.807342Z","end":"2026-07-17T03:45:03.259996Z","steps":["trace[1047484868] 'agreement among raft nodes before linearized reading' (duration: 452.569023ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:03.260036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:02.80732Z","time spent":"452.709766ms","remote":"127.0.0.1:47614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T03:45:03.259637Z","caller":"traceutil/trace.go:171","msg":"trace[235996928] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:438; }","duration":"452.234883ms","start":"2026-07-17T03:45:02.807386Z","end":"2026-07-17T03:45:03.259621Z","steps":["trace[235996928] 'read index received' (duration: 449.027892ms)","trace[235996928] 'applied index is now lower than readState.Index' (duration: 3.206171ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:45:03.26046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.569662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.177\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-17T03:45:03.260489Z","caller":"traceutil/trace.go:171","msg":"trace[1663362466] range","detail":"{range_begin:/registry/masterleases/199.204.45.177; range_end:; response_count:1; response_revision:428; }","duration":"125.637054ms","start":"2026-07-17T03:45:03.134844Z","end":"2026-07-17T03:45:03.260481Z","steps":["trace[1663362466] 'agreement among raft nodes before linearized reading' (duration: 125.583942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:03.260742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.236586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-07-17T03:45:03.260822Z","caller":"traceutil/trace.go:171","msg":"trace[2114656229] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:428; }","duration":"360.35163ms","start":"2026-07-17T03:45:02.900446Z","end":"2026-07-17T03:45:03.260798Z","steps":["trace[2114656229] 'agreement among raft nodes before linearized reading' (duration: 359.910717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:03.260874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:02.900423Z","time spent":"360.438732ms","remote":"127.0.0.1:47834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4391,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-17T03:45:20.92006Z","caller":"traceutil/trace.go:171","msg":"trace[888339495] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"221.560923ms","start":"2026-07-17T03:45:20.698471Z","end":"2026-07-17T03:45:20.920032Z","steps":["trace[888339495] 'process raft request' (duration: 221.401068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:21.490917Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186506791624214,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T03:45:21.713086Z","caller":"traceutil/trace.go:171","msg":"trace[1188783627] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"761.136459ms","start":"2026-07-17T03:45:20.951931Z","end":"2026-07-17T03:45:21.713068Z","steps":["trace[1188783627] 'process raft request' (duration: 761.027566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:21.71333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:20.951908Z","time spent":"761.357846ms","remote":"127.0.0.1:47674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10362,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:45:21.71562Z","caller":"traceutil/trace.go:171","msg":"trace[480784060] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"725.618648ms","start":"2026-07-17T03:45:20.989979Z","end":"2026-07-17T03:45:21.715597Z","steps":["trace[480784060] 'read index received' (duration: 723.189109ms)","trace[480784060] 'applied index is now lower than readState.Index' (duration: 2.418969ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:45:21.715759Z","caller":"traceutil/trace.go:171","msg":"trace[2023909529] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"619.198802ms","start":"2026-07-17T03:45:21.096487Z","end":"2026-07-17T03:45:21.715686Z","steps":["trace[2023909529] 'process raft request' (duration: 619.013916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:21.715879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.17616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T03:45:21.715908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:21.096459Z","time spent":"619.384237ms","remote":"127.0.0.1:47674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T03:45:21.715953Z","caller":"traceutil/trace.go:171","msg":"trace[1503012695] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:633; }","duration":"221.294514ms","start":"2026-07-17T03:45:21.494641Z","end":"2026-07-17T03:45:21.715935Z","steps":["trace[1503012695] 'agreement among raft nodes before linearized reading' (duration: 221.190081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:21.715859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.856511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:45:21.716085Z","caller":"traceutil/trace.go:171","msg":"trace[1018846186] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:633; }","duration":"421.124379ms","start":"2026-07-17T03:45:21.29495Z","end":"2026-07-17T03:45:21.716075Z","steps":["trace[1018846186] 'agreement among raft nodes before linearized reading' (duration: 420.843461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:21.71616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:21.294928Z","time spent":"421.221352ms","remote":"127.0.0.1:47674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-07-17T03:45:21.716151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.064425ms","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-17T03:45:21.716233Z","caller":"traceutil/trace.go:171","msg":"trace[883246501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"261.186519ms","start":"2026-07-17T03:45:21.455035Z","end":"2026-07-17T03:45:21.716222Z","steps":["trace[883246501] 'agreement among raft nodes before linearized reading' (duration: 260.87554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:21.716308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.056641ms","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-17T03:45:21.716341Z","caller":"traceutil/trace.go:171","msg":"trace[1755874154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"309.109362ms","start":"2026-07-17T03:45:21.407223Z","end":"2026-07-17T03:45:21.716332Z","steps":["trace[1755874154] 'agreement among raft nodes before linearized reading' (duration: 309.011759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:21.716368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:21.407188Z","time spent":"309.174684ms","remote":"127.0.0.1:47928","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-17T03:45:21.716419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.548043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T03:45:21.716448Z","caller":"traceutil/trace.go:171","msg":"trace[402645642] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"310.621925ms","start":"2026-07-17T03:45:21.405818Z","end":"2026-07-17T03:45:21.71644Z","steps":["trace[402645642] 'agreement among raft nodes before linearized reading' (duration: 310.551223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:21.716467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:21.405793Z","time spent":"310.668246ms","remote":"127.0.0.1:47928","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-17T03:45:21.716589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.601786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-ptmnx\" ","response":"range_response_count:1 size:4683"} {"level":"info","ts":"2026-07-17T03:45:21.71664Z","caller":"traceutil/trace.go:171","msg":"trace[286514780] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-ptmnx; range_end:; response_count:1; response_revision:633; }","duration":"726.677628ms","start":"2026-07-17T03:45:20.98995Z","end":"2026-07-17T03:45:21.716627Z","steps":["trace[286514780] 'agreement among raft nodes before linearized reading' (duration: 725.759242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:21.716668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:20.989935Z","time spent":"726.72472ms","remote":"127.0.0.1:47844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4705,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-ptmnx\" "} {"level":"info","ts":"2026-07-17T03:45:26.600528Z","caller":"traceutil/trace.go:171","msg":"trace[1500639098] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"254.670324ms","start":"2026-07-17T03:45:26.345836Z","end":"2026-07-17T03:45:26.600506Z","steps":["trace[1500639098] 'process raft request' (duration: 254.506909ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:28.257182Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186506791624371,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T03:45:28.343993Z","caller":"traceutil/trace.go:171","msg":"trace[1225783281] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"601.945671ms","start":"2026-07-17T03:45:27.74203Z","end":"2026-07-17T03:45:28.343975Z","steps":["trace[1225783281] 'process raft request' (duration: 601.805437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:28.344182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:27.742013Z","time spent":"602.086395ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:45:28.402302Z","caller":"traceutil/trace.go:171","msg":"trace[1738051132] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:719; }","duration":"645.413117ms","start":"2026-07-17T03:45:27.756856Z","end":"2026-07-17T03:45:28.402269Z","steps":["trace[1738051132] 'read index received' (duration: 586.999077ms)","trace[1738051132] 'applied index is now lower than readState.Index' (duration: 58.41299ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:45:28.402372Z","caller":"traceutil/trace.go:171","msg":"trace[996615845] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"659.582191ms","start":"2026-07-17T03:45:27.742755Z","end":"2026-07-17T03:45:28.402337Z","steps":["trace[996615845] 'process raft request' (duration: 650.304696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:28.402576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.688106ms","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-17T03:45:28.402638Z","caller":"traceutil/trace.go:171","msg":"trace[2117584455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:698; }","duration":"645.778198ms","start":"2026-07-17T03:45:27.756842Z","end":"2026-07-17T03:45:28.402621Z","steps":["trace[2117584455] 'agreement among raft nodes before linearized reading' (duration: 645.584082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:28.402623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:27.742744Z","time spent":"659.751085ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:45:28.402687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:27.756837Z","time spent":"645.838919ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T03:45:28.402949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.08104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gxq27\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-07-17T03:45:28.402973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.386458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:45:28.403068Z","caller":"traceutil/trace.go:171","msg":"trace[733839328] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gxq27; range_end:; response_count:1; response_revision:698; }","duration":"387.168821ms","start":"2026-07-17T03:45:28.015824Z","end":"2026-07-17T03:45:28.402993Z","steps":["trace[733839328] 'agreement among raft nodes before linearized reading' (duration: 387.038878ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:45:28.40317Z","caller":"traceutil/trace.go:171","msg":"trace[1329149340] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:698; }","duration":"593.625945ms","start":"2026-07-17T03:45:27.809523Z","end":"2026-07-17T03:45:28.403149Z","steps":["trace[1329149340] 'agreement among raft nodes before linearized reading' (duration: 593.361877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:28.403425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:27.809506Z","time spent":"593.83346ms","remote":"127.0.0.1:47614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T03:45:28.403248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:28.015807Z","time spent":"387.421819ms","remote":"127.0.0.1:47844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-gxq27\" "} {"level":"warn","ts":"2026-07-17T03:45:28.402951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.781409ms","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-17T03:45:28.4036Z","caller":"traceutil/trace.go:171","msg":"trace[849196570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:698; }","duration":"646.410826ms","start":"2026-07-17T03:45:27.757144Z","end":"2026-07-17T03:45:28.403555Z","steps":["trace[849196570] 'agreement among raft nodes before linearized reading' (duration: 645.452639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:28.403696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:27.75714Z","time spent":"646.506699ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-17T03:45:28.606872Z","caller":"traceutil/trace.go:171","msg":"trace[1301050797] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"199.031552ms","start":"2026-07-17T03:45:28.407811Z","end":"2026-07-17T03:45:28.606843Z","steps":["trace[1301050797] 'process raft request' (duration: 182.997876ms)","trace[1301050797] 'compare' (duration: 15.752327ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:45:28.999781Z","caller":"traceutil/trace.go:171","msg":"trace[1205586348] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"591.269967ms","start":"2026-07-17T03:45:28.40848Z","end":"2026-07-17T03:45:28.99975Z","steps":["trace[1205586348] 'process raft request' (duration: 559.520973ms)","trace[1205586348] 'compare' (duration: 31.170198ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:45:29.000018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:28.408468Z","time spent":"591.385791ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:45:29.000202Z","caller":"traceutil/trace.go:171","msg":"trace[738999425] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"582.132018ms","start":"2026-07-17T03:45:28.418032Z","end":"2026-07-17T03:45:29.000164Z","steps":["trace[738999425] 'process raft request' (duration: 581.456859ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:45:29.000341Z","caller":"traceutil/trace.go:171","msg":"trace[439514621] linearizableReadLoop","detail":"{readStateIndex:724; appliedIndex:722; }","duration":"395.484558ms","start":"2026-07-17T03:45:28.604844Z","end":"2026-07-17T03:45:29.000328Z","steps":["trace[439514621] 'read index received' (duration: 363.160328ms)","trace[439514621] 'applied index is now lower than readState.Index' (duration: 32.32302ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:45:29.000379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:28.418004Z","time spent":"582.284312ms","remote":"127.0.0.1:47844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:45:29.014877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.998158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:45:29.014933Z","caller":"traceutil/trace.go:171","msg":"trace[842267146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:701; }","duration":"168.097471ms","start":"2026-07-17T03:45:28.846821Z","end":"2026-07-17T03:45:29.014919Z","steps":["trace[842267146] 'agreement among raft nodes before linearized reading' (duration: 168.009438ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:29.015186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.334071ms","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-17T03:45:29.015305Z","caller":"traceutil/trace.go:171","msg":"trace[1462886478] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:701; }","duration":"410.496005ms","start":"2026-07-17T03:45:28.604785Z","end":"2026-07-17T03:45:29.015281Z","steps":["trace[1462886478] 'agreement among raft nodes before linearized reading' (duration: 395.644343ms)","trace[1462886478] 'range keys from in-memory index tree' (duration: 14.456781ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:45:29.01539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:28.604738Z","time spent":"410.638869ms","remote":"127.0.0.1:47928","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-17T03:45:31.589764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.782663044s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T03:45:31.589865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.241667537s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T03:45:31.589866Z","caller":"traceutil/trace.go:171","msg":"trace[1631989820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:702; }","duration":"1.782784948s","start":"2026-07-17T03:45:29.807061Z","end":"2026-07-17T03:45:31.589846Z","steps":["trace[1631989820] 'range keys from in-memory index tree' (duration: 1.782541831s)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:45:31.590303Z","caller":"traceutil/trace.go:171","msg":"trace[972293210] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:702; }","duration":"1.24215188s","start":"2026-07-17T03:45:30.348136Z","end":"2026-07-17T03:45:31.590288Z","steps":["trace[972293210] 'range keys from in-memory index tree' (duration: 1.241475752s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:31.59031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:29.807005Z","time spent":"1.783294022s","remote":"127.0.0.1:47602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T03:45:31.590338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:30.348113Z","time spent":"1.242216103s","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T03:45:31.590111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.925483ms","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-17T03:45:31.590517Z","caller":"traceutil/trace.go:171","msg":"trace[261674501] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:702; }","duration":"549.378457ms","start":"2026-07-17T03:45:31.041127Z","end":"2026-07-17T03:45:31.590505Z","steps":["trace[261674501] 'range keys from in-memory index tree' (duration: 548.84278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:31.590552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:31.041105Z","time spent":"549.435998ms","remote":"127.0.0.1:47928","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-17T03:45:31.590187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.744128ms","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-17T03:45:31.590724Z","caller":"traceutil/trace.go:171","msg":"trace[1232197287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:702; }","duration":"587.312875ms","start":"2026-07-17T03:45:31.003402Z","end":"2026-07-17T03:45:31.590715Z","steps":["trace[1232197287] 'range keys from in-memory index tree' (duration: 586.605824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:31.590767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:31.003382Z","time spent":"587.374808ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T03:45:31.590161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.805758111s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:45:31.59092Z","caller":"traceutil/trace.go:171","msg":"trace[1679244663] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:702; }","duration":"1.806519744s","start":"2026-07-17T03:45:29.784388Z","end":"2026-07-17T03:45:31.590908Z","steps":["trace[1679244663] 'range keys from in-memory index tree' (duration: 1.805747941s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:31.590205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182925726s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T03:45:31.591042Z","caller":"traceutil/trace.go:171","msg":"trace[1298076427] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:702; }","duration":"1.183821282s","start":"2026-07-17T03:45:30.407205Z","end":"2026-07-17T03:45:31.591027Z","steps":["trace[1298076427] 'range keys from in-memory index tree' (duration: 1.182739011s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:31.590226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.205102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-17T03:45:31.591085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:30.407185Z","time spent":"1.183887814s","remote":"127.0.0.1:47928","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-17T03:45:31.591135Z","caller":"traceutil/trace.go:171","msg":"trace[1135303783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:702; }","duration":"980.127719ms","start":"2026-07-17T03:45:30.610956Z","end":"2026-07-17T03:45:31.591084Z","steps":["trace[1135303783] 'range keys from in-memory index tree' (duration: 979.037967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:31.59122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:30.610936Z","time spent":"980.260422ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-17T03:45:31.775243Z","caller":"traceutil/trace.go:171","msg":"trace[1721433940] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"180.498514ms","start":"2026-07-17T03:45:31.594722Z","end":"2026-07-17T03:45:31.775221Z","steps":["trace[1721433940] 'process raft request' (duration: 180.357619ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:45:31.776329Z","caller":"traceutil/trace.go:171","msg":"trace[1610598326] linearizableReadLoop","detail":"{readStateIndex:731; appliedIndex:726; }","duration":"175.833202ms","start":"2026-07-17T03:45:31.600471Z","end":"2026-07-17T03:45:31.776305Z","steps":["trace[1610598326] 'read index received' (duration: 174.599646ms)","trace[1610598326] 'applied index is now lower than readState.Index' (duration: 1.232536ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:45:31.776504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.016937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:45:31.776533Z","caller":"traceutil/trace.go:171","msg":"trace[22442562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:707; }","duration":"176.082479ms","start":"2026-07-17T03:45:31.600444Z","end":"2026-07-17T03:45:31.776526Z","steps":["trace[22442562] 'agreement among raft nodes before linearized reading' (duration: 175.965956ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:45:31.776686Z","caller":"traceutil/trace.go:171","msg":"trace[1613586796] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"181.525053ms","start":"2026-07-17T03:45:31.595154Z","end":"2026-07-17T03:45:31.776679Z","steps":["trace[1613586796] 'process raft request' (duration: 181.083171ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:45:31.776737Z","caller":"traceutil/trace.go:171","msg":"trace[1805718305] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"181.543454ms","start":"2026-07-17T03:45:31.595137Z","end":"2026-07-17T03:45:31.77668Z","steps":["trace[1805718305] 'process raft request' (duration: 181.061281ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:45:31.77678Z","caller":"traceutil/trace.go:171","msg":"trace[33509376] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"179.972809ms","start":"2026-07-17T03:45:31.596801Z","end":"2026-07-17T03:45:31.776773Z","steps":["trace[33509376] 'process raft request' (duration: 179.465665ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:45:31.77696Z","caller":"traceutil/trace.go:171","msg":"trace[1569890484] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"181.974046ms","start":"2026-07-17T03:45:31.594971Z","end":"2026-07-17T03:45:31.776945Z","steps":["trace[1569890484] 'process raft request' (duration: 181.089841ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:45:44.078047Z","caller":"traceutil/trace.go:171","msg":"trace[970859249] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"240.709696ms","start":"2026-07-17T03:45:43.837317Z","end":"2026-07-17T03:45:44.078026Z","steps":["trace[970859249] 'process raft request' (duration: 240.53015ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:45:44.082759Z","caller":"traceutil/trace.go:171","msg":"trace[788730893] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"244.910293ms","start":"2026-07-17T03:45:43.83783Z","end":"2026-07-17T03:45:44.082741Z","steps":["trace[788730893] 'process raft request' (duration: 244.779139ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:45:44.083151Z","caller":"traceutil/trace.go:171","msg":"trace[1591403199] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"244.932783ms","start":"2026-07-17T03:45:43.838198Z","end":"2026-07-17T03:45:44.08313Z","steps":["trace[1591403199] 'process raft request' (duration: 244.511409ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:45:44.083153Z","caller":"traceutil/trace.go:171","msg":"trace[615526511] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"243.856929ms","start":"2026-07-17T03:45:43.839277Z","end":"2026-07-17T03:45:44.083134Z","steps":["trace[615526511] 'process raft request' (duration: 243.798617ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:45:44.083211Z","caller":"traceutil/trace.go:171","msg":"trace[1766904215] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"244.375355ms","start":"2026-07-17T03:45:43.838789Z","end":"2026-07-17T03:45:44.083164Z","steps":["trace[1766904215] 'process raft request' (duration: 244.132077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:47.263633Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186506791624609,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T03:45:47.351304Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.058915461s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T03:45:47.351989Z","caller":"traceutil/trace.go:171","msg":"trace[153171789] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"1.059668885s","start":"2026-07-17T03:45:46.292293Z","end":"2026-07-17T03:45:47.351962Z","steps":["trace[153171789] 'process raft request' (duration: 1.059471339s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:47.352189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:46.292275Z","time spent":"1.05979494s","remote":"127.0.0.1:47834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:45:47.562361Z","caller":"traceutil/trace.go:171","msg":"trace[821586172] linearizableReadLoop","detail":"{readStateIndex:848; appliedIndex:846; }","duration":"799.084715ms","start":"2026-07-17T03:45:46.763251Z","end":"2026-07-17T03:45:47.562335Z","steps":["trace[821586172] 'read index received' (duration: 588.425793ms)","trace[821586172] 'applied index is now lower than readState.Index' (duration: 210.657822ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:45:47.562493Z","caller":"traceutil/trace.go:171","msg":"trace[483177411] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"433.560331ms","start":"2026-07-17T03:45:47.1289Z","end":"2026-07-17T03:45:47.56246Z","steps":["trace[483177411] 'process raft request' (duration: 433.273482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:47.562556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.282911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:45:47.56261Z","caller":"traceutil/trace.go:171","msg":"trace[627560101] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:819; }","duration":"799.387544ms","start":"2026-07-17T03:45:46.763201Z","end":"2026-07-17T03:45:47.562589Z","steps":["trace[627560101] 'agreement among raft nodes before linearized reading' (duration: 799.288181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:47.562655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:47.128872Z","time spent":"433.697525ms","remote":"127.0.0.1:47898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:45:47.562663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:46.763184Z","time spent":"799.463837ms","remote":"127.0.0.1:47794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-07-17T03:45:47.562811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.049514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:45:47.562968Z","caller":"traceutil/trace.go:171","msg":"trace[1216946939] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:819; }","duration":"754.278431ms","start":"2026-07-17T03:45:46.80867Z","end":"2026-07-17T03:45:47.562948Z","steps":["trace[1216946939] 'agreement among raft nodes before linearized reading' (duration: 753.841657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:45:47.563128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:45:46.808649Z","time spent":"754.394874ms","remote":"127.0.0.1:47614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T03:46:28.917354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.662194ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-17T03:46:28.917772Z","caller":"traceutil/trace.go:171","msg":"trace[157406461] linearizableReadLoop","detail":"{readStateIndex:1088; appliedIndex:1087; }","duration":"627.254662ms","start":"2026-07-17T03:46:28.290499Z","end":"2026-07-17T03:46:28.917754Z","steps":["trace[157406461] 'read index received' (duration: 51.891µs)","trace[157406461] 'applied index is now lower than readState.Index' (duration: 627.201211ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:46:28.917962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.460759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T03:46:28.917993Z","caller":"traceutil/trace.go:171","msg":"trace[1695334181] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1049; }","duration":"627.537451ms","start":"2026-07-17T03:46:28.290446Z","end":"2026-07-17T03:46:28.917983Z","steps":["trace[1695334181] 'agreement among raft nodes before linearized reading' (duration: 627.393096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:28.918029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:28.290425Z","time spent":"627.593193ms","remote":"127.0.0.1:47928","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-17T03:46:28.920537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.463944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T03:46:28.920589Z","caller":"traceutil/trace.go:171","msg":"trace[1354272948] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1050; }","duration":"621.557557ms","start":"2026-07-17T03:46:28.29902Z","end":"2026-07-17T03:46:28.920577Z","steps":["trace[1354272948] 'agreement among raft nodes before linearized reading' (duration: 621.425473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:28.920622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:28.298999Z","time spent":"621.615199ms","remote":"127.0.0.1:47928","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-17T03:46:28.920853Z","caller":"traceutil/trace.go:171","msg":"trace[404363914] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"582.132778ms","start":"2026-07-17T03:46:28.338709Z","end":"2026-07-17T03:46:28.920841Z","steps":["trace[404363914] 'process raft request' (duration: 581.627522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:28.920948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:28.338685Z","time spent":"582.2032ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:46:28.921127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.264159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:46:28.921173Z","caller":"traceutil/trace.go:171","msg":"trace[281035261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1050; }","duration":"115.342572ms","start":"2026-07-17T03:46:28.805817Z","end":"2026-07-17T03:46:28.92116Z","steps":["trace[281035261] 'agreement among raft nodes before linearized reading' (duration: 115.28309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:28.921315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.840183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:46:28.921355Z","caller":"traceutil/trace.go:171","msg":"trace[195102604] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1050; }","duration":"224.922555ms","start":"2026-07-17T03:46:28.696423Z","end":"2026-07-17T03:46:28.921346Z","steps":["trace[195102604] 'agreement among raft nodes before linearized reading' (duration: 224.866563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:28.921678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.951686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:46:28.921723Z","caller":"traceutil/trace.go:171","msg":"trace[1903035706] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1050; }","duration":"439.164643ms","start":"2026-07-17T03:46:28.482545Z","end":"2026-07-17T03:46:28.921709Z","steps":["trace[1903035706] 'agreement among raft nodes before linearized reading' (duration: 438.938625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:28.921754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:28.482434Z","time spent":"439.310978ms","remote":"127.0.0.1:47964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-17T03:46:28.921914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.71135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:46:28.921951Z","caller":"traceutil/trace.go:171","msg":"trace[1116668582] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1050; }","duration":"525.793292ms","start":"2026-07-17T03:46:28.39615Z","end":"2026-07-17T03:46:28.921943Z","steps":["trace[1116668582] 'agreement among raft nodes before linearized reading' (duration: 525.73875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:28.92198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:28.396128Z","time spent":"525.844134ms","remote":"127.0.0.1:48036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-17T03:46:28.922172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.119966ms","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-17T03:46:28.922241Z","caller":"traceutil/trace.go:171","msg":"trace[215250953] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1050; }","duration":"618.167987ms","start":"2026-07-17T03:46:28.304032Z","end":"2026-07-17T03:46:28.9222Z","steps":["trace[215250953] 'agreement among raft nodes before linearized reading' (duration: 618.071704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:28.922297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:28.304025Z","time spent":"618.23898ms","remote":"127.0.0.1:47928","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-17T03:46:28.922483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.691459ms","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-17T03:46:28.922521Z","caller":"traceutil/trace.go:171","msg":"trace[860569802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1050; }","duration":"620.752461ms","start":"2026-07-17T03:46:28.301758Z","end":"2026-07-17T03:46:28.92251Z","steps":["trace[860569802] 'agreement among raft nodes before linearized reading' (duration: 620.666249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:28.922556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:28.301747Z","time spent":"620.800153ms","remote":"127.0.0.1:47928","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-17T03:46:28.922678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.314759ms","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-17T03:46:28.922706Z","caller":"traceutil/trace.go:171","msg":"trace[2006586787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1050; }","duration":"621.368611ms","start":"2026-07-17T03:46:28.301329Z","end":"2026-07-17T03:46:28.922698Z","steps":["trace[2006586787] 'agreement among raft nodes before linearized reading' (duration: 621.289879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:28.922734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:28.301317Z","time spent":"621.410592ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T03:46:29.42442Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186506791625184,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T03:46:30.512801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.976359ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:46:30.512929Z","caller":"traceutil/trace.go:171","msg":"trace[1976843386] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1050; }","duration":"728.109783ms","start":"2026-07-17T03:46:29.784801Z","end":"2026-07-17T03:46:30.512911Z","steps":["trace[1976843386] 'range keys from in-memory index tree' (duration: 727.956898ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:30.513373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.429618ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T03:46:30.514371Z","caller":"traceutil/trace.go:171","msg":"trace[23963341] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"1.591486383s","start":"2026-07-17T03:46:28.92284Z","end":"2026-07-17T03:46:30.514326Z","steps":["trace[23963341] 'process raft request' (duration: 613.008681ms)","trace[23963341] 'compare' (duration: 977.325385ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:46:30.514484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:28.922819Z","time spent":"1.591592287s","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:46:30.530045Z","caller":"traceutil/trace.go:171","msg":"trace[1092257156] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"1.602140199s","start":"2026-07-17T03:46:28.927874Z","end":"2026-07-17T03:46:30.530015Z","steps":["trace[1092257156] 'process raft request' (duration: 1.602089898s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:30.530226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:28.927866Z","time spent":"1.602271443s","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:46:30.530298Z","caller":"traceutil/trace.go:171","msg":"trace[2019785777] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"1.602399218s","start":"2026-07-17T03:46:28.927875Z","end":"2026-07-17T03:46:30.530275Z","steps":["trace[2019785777] 'process raft request' (duration: 1.602112899s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:30.53043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:28.927855Z","time spent":"1.60250493s","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:46:30.530636Z","caller":"traceutil/trace.go:171","msg":"trace[1105384965] linearizableReadLoop","detail":"{readStateIndex:1094; appliedIndex:1089; }","duration":"1.606825311s","start":"2026-07-17T03:46:28.923788Z","end":"2026-07-17T03:46:30.530613Z","steps":["trace[1105384965] 'read index received' (duration: 612.279407ms)","trace[1105384965] 'applied index is now lower than readState.Index' (duration: 994.545214ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:46:30.530681Z","caller":"traceutil/trace.go:171","msg":"trace[684563489] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"1.602958555s","start":"2026-07-17T03:46:28.927709Z","end":"2026-07-17T03:46:30.530667Z","steps":["trace[684563489] 'process raft request' (duration: 1.602102738s)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:46:30.530736Z","caller":"traceutil/trace.go:171","msg":"trace[2091520841] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"1.60279793s","start":"2026-07-17T03:46:28.927931Z","end":"2026-07-17T03:46:30.530729Z","steps":["trace[2091520841] 'process raft request' (duration: 1.602282244s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:30.530754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:28.927695Z","time spent":"1.603017147s","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:46:30.530767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:28.927925Z","time spent":"1.602825201s","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:46:30.53092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.60712317s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:46:30.530948Z","caller":"traceutil/trace.go:171","msg":"trace[1566660997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1055; }","duration":"1.607189123s","start":"2026-07-17T03:46:28.923751Z","end":"2026-07-17T03:46:30.53094Z","steps":["trace[1566660997] 'agreement among raft nodes before linearized reading' (duration: 1.607137161s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:30.53097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:28.923702Z","time spent":"1.607262686s","remote":"127.0.0.1:47602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T03:46:30.530943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.92113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T03:46:30.531031Z","caller":"traceutil/trace.go:171","msg":"trace[778914355] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1055; }","duration":"931.055224ms","start":"2026-07-17T03:46:29.599953Z","end":"2026-07-17T03:46:30.531008Z","steps":["trace[778914355] 'agreement among raft nodes before linearized reading' (duration: 930.874058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:30.531083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:29.599938Z","time spent":"931.131516ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-07-17T03:46:30.531134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.252255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T03:46:30.533588Z","caller":"traceutil/trace.go:171","msg":"trace[1515394229] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1055; }","duration":"287.366599ms","start":"2026-07-17T03:46:30.243813Z","end":"2026-07-17T03:46:30.53118Z","steps":["trace[1515394229] 'agreement among raft nodes before linearized reading' (duration: 287.236015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:51.112747Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186506791625358,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T03:46:51.387939Z","caller":"traceutil/trace.go:171","msg":"trace[876336384] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"775.755577ms","start":"2026-07-17T03:46:50.612159Z","end":"2026-07-17T03:46:51.387914Z","steps":["trace[876336384] 'process raft request' (duration: 775.550072ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:51.388084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:50.612131Z","time spent":"775.883591ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:46:51.795713Z","caller":"traceutil/trace.go:171","msg":"trace[1562783754] linearizableReadLoop","detail":"{readStateIndex:1161; appliedIndex:1159; }","duration":"1.183447669s","start":"2026-07-17T03:46:50.612235Z","end":"2026-07-17T03:46:51.795683Z","steps":["trace[1562783754] 'read index received' (duration: 775.546202ms)","trace[1562783754] 'applied index is now lower than readState.Index' (duration: 407.900227ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:46:51.795898Z","caller":"traceutil/trace.go:171","msg":"trace[257684564] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"801.290984ms","start":"2026-07-17T03:46:50.994585Z","end":"2026-07-17T03:46:51.795876Z","steps":["trace[257684564] 'process raft request' (duration: 755.885248ms)","trace[257684564] 'compare' (duration: 44.79997ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:46:51.79603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:50.994557Z","time spent":"801.390697ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:46:51.79613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.18389439s","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-17T03:46:51.796168Z","caller":"traceutil/trace.go:171","msg":"trace[1245208720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1118; }","duration":"1.183966012s","start":"2026-07-17T03:46:50.612191Z","end":"2026-07-17T03:46:51.796157Z","steps":["trace[1245208720] 'agreement among raft nodes before linearized reading' (duration: 1.18388178s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:51.796197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:50.612174Z","time spent":"1.184015334s","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T03:46:51.796052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182894464s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-17T03:46:51.796312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181790175s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T03:46:51.796391Z","caller":"traceutil/trace.go:171","msg":"trace[651403706] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1118; }","duration":"1.181876757s","start":"2026-07-17T03:46:50.614496Z","end":"2026-07-17T03:46:51.796373Z","steps":["trace[651403706] 'agreement among raft nodes before linearized reading' (duration: 1.181673502s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:51.796439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:50.614424Z","time spent":"1.18200627s","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T03:46:51.796411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.177739245s","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-17T03:46:51.796314Z","caller":"traceutil/trace.go:171","msg":"trace[906613123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1118; }","duration":"1.183167771s","start":"2026-07-17T03:46:50.613132Z","end":"2026-07-17T03:46:51.7963Z","steps":["trace[906613123] 'agreement among raft nodes before linearized reading' (duration: 1.182828092s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:51.797156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:50.613124Z","time spent":"1.184019135s","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-17T03:46:51.796853Z","caller":"traceutil/trace.go:171","msg":"trace[1839902959] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1118; }","duration":"1.178191648s","start":"2026-07-17T03:46:50.61864Z","end":"2026-07-17T03:46:51.796831Z","steps":["trace[1839902959] 'agreement among raft nodes before linearized reading' (duration: 1.177475799s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:51.798142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:50.618634Z","time spent":"1.179441372s","remote":"127.0.0.1:47928","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-17T03:46:53.787363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.659457ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T03:46:53.787961Z","caller":"traceutil/trace.go:171","msg":"trace[1452344850] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1166; }","duration":"395.71006ms","start":"2026-07-17T03:46:53.392227Z","end":"2026-07-17T03:46:53.787937Z","steps":["trace[1452344850] 'read index received' (duration: 288.455807ms)","trace[1452344850] 'applied index is now lower than readState.Index' (duration: 107.251443ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:46:53.787983Z","caller":"traceutil/trace.go:171","msg":"trace[1871919388] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"478.314042ms","start":"2026-07-17T03:46:53.309633Z","end":"2026-07-17T03:46:53.787947Z","steps":["trace[1871919388] 'process raft request' (duration: 370.992367ms)","trace[1871919388] 'compare' (duration: 106.210786ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:46:53.788161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.929275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-17T03:46:53.78816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:53.309613Z","time spent":"478.436396ms","remote":"127.0.0.1:47654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:46:53.788193Z","caller":"traceutil/trace.go:171","msg":"trace[867421920] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1123; }","duration":"396.000207ms","start":"2026-07-17T03:46:53.392182Z","end":"2026-07-17T03:46:53.788183Z","steps":["trace[867421920] 'agreement among raft nodes before linearized reading' (duration: 395.824292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:46:53.788228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:46:53.392163Z","time spent":"396.055708ms","remote":"127.0.0.1:47928","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-17T03:46:53.962253Z","caller":"traceutil/trace.go:171","msg":"trace[682854499] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"124.814133ms","start":"2026-07-17T03:46:53.837409Z","end":"2026-07-17T03:46:53.962223Z","steps":["trace[682854499] 'process raft request' (duration: 83.812305ms)","trace[682854499] 'compare' (duration: 40.792872ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:46:54.104846Z","caller":"traceutil/trace.go:171","msg":"trace[1356861728] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"137.603726ms","start":"2026-07-17T03:46:53.967205Z","end":"2026-07-17T03:46:54.104808Z","steps":["trace[1356861728] 'process raft request' (duration: 137.551855ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:46:54.104905Z","caller":"traceutil/trace.go:171","msg":"trace[191069746] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"137.949875ms","start":"2026-07-17T03:46:53.966915Z","end":"2026-07-17T03:46:54.104865Z","steps":["trace[191069746] 'process raft request' (duration: 125.697787ms)","trace[191069746] 'compare' (duration: 12.009251ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:46:54.664912Z","caller":"traceutil/trace.go:171","msg":"trace[1570897732] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"125.390729ms","start":"2026-07-17T03:46:54.539494Z","end":"2026-07-17T03:46:54.664885Z","steps":["trace[1570897732] 'process raft request' (duration: 125.186093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:12.402775Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186506791625625,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T03:47:12.761188Z","caller":"traceutil/trace.go:171","msg":"trace[1591362276] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1283; }","duration":"859.094572ms","start":"2026-07-17T03:47:11.90206Z","end":"2026-07-17T03:47:12.761154Z","steps":["trace[1591362276] 'read index received' (duration: 858.794624ms)","trace[1591362276] 'applied index is now lower than readState.Index' (duration: 298.988µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:47:12.761698Z","caller":"traceutil/trace.go:171","msg":"trace[1814654418] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"862.77981ms","start":"2026-07-17T03:47:11.898881Z","end":"2026-07-17T03:47:12.761661Z","steps":["trace[1814654418] 'process raft request' (duration: 862.068161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:12.761823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.800481ms","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-17T03:47:12.761884Z","caller":"traceutil/trace.go:171","msg":"trace[43511068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1235; }","duration":"725.914664ms","start":"2026-07-17T03:47:12.035954Z","end":"2026-07-17T03:47:12.761869Z","steps":["trace[43511068] 'agreement among raft nodes before linearized reading' (duration: 725.706958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:12.761925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:12.035938Z","time spent":"725.975156ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T03:47:12.762013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.299616ms","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-17T03:47:12.76204Z","caller":"traceutil/trace.go:171","msg":"trace[94807612] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1235; }","duration":"548.372118ms","start":"2026-07-17T03:47:12.213661Z","end":"2026-07-17T03:47:12.762034Z","steps":["trace[94807612] 'agreement among raft nodes before linearized reading' (duration: 548.230204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:12.762057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:12.213638Z","time spent":"548.415339ms","remote":"127.0.0.1:47928","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-17T03:47:12.762175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.15518ms","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-17T03:47:12.762228Z","caller":"traceutil/trace.go:171","msg":"trace[2096203597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1235; }","duration":"577.236621ms","start":"2026-07-17T03:47:12.184964Z","end":"2026-07-17T03:47:12.762201Z","steps":["trace[2096203597] 'agreement among raft nodes before linearized reading' (duration: 577.143069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:12.762258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:12.184937Z","time spent":"577.313874ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T03:47:12.76184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:11.898847Z","time spent":"862.915784ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:47:12.762422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.589585ms","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-17T03:47:12.762438Z","caller":"traceutil/trace.go:171","msg":"trace[1396956934] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1235; }","duration":"641.637177ms","start":"2026-07-17T03:47:12.120795Z","end":"2026-07-17T03:47:12.762432Z","steps":["trace[1396956934] 'agreement among raft nodes before linearized reading' (duration: 641.594715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:12.762452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:12.120775Z","time spent":"641.674258ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T03:47:12.762262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.211411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T03:47:12.7625Z","caller":"traceutil/trace.go:171","msg":"trace[41816858] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1235; }","duration":"860.491168ms","start":"2026-07-17T03:47:11.902005Z","end":"2026-07-17T03:47:12.762496Z","steps":["trace[41816858] 'agreement among raft nodes before linearized reading' (duration: 860.217741ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:12.762513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:11.90198Z","time spent":"860.52972ms","remote":"127.0.0.1:47928","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-17T03:47:28.358318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.539807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:47:28.358583Z","caller":"traceutil/trace.go:171","msg":"trace[508167897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1288; }","duration":"553.850475ms","start":"2026-07-17T03:47:27.804715Z","end":"2026-07-17T03:47:28.358565Z","steps":["trace[508167897] 'range keys from in-memory index tree' (duration: 553.463286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:28.358619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:27.804696Z","time spent":"553.914937ms","remote":"127.0.0.1:47614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T03:47:28.493583Z","caller":"traceutil/trace.go:171","msg":"trace[195078047] linearizableReadLoop","detail":"{readStateIndex:1341; appliedIndex:1340; }","duration":"277.679078ms","start":"2026-07-17T03:47:28.215864Z","end":"2026-07-17T03:47:28.493543Z","steps":["trace[195078047] 'read index received' (duration: 277.440291ms)","trace[195078047] 'applied index is now lower than readState.Index' (duration: 237.757µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:47:28.493638Z","caller":"traceutil/trace.go:171","msg":"trace[1396436157] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"486.402039ms","start":"2026-07-17T03:47:28.007206Z","end":"2026-07-17T03:47:28.493608Z","steps":["trace[1396436157] 'process raft request' (duration: 486.159213ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:28.493762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.889764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T03:47:28.493785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:28.007186Z","time spent":"486.512802ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:47:28.493821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.361552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:47:28.493847Z","caller":"traceutil/trace.go:171","msg":"trace[1095970196] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1289; }","duration":"278.022937ms","start":"2026-07-17T03:47:28.215806Z","end":"2026-07-17T03:47:28.493829Z","steps":["trace[1095970196] 'agreement among raft nodes before linearized reading' (duration: 277.896734ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:47:28.493893Z","caller":"traceutil/trace.go:171","msg":"trace[163572806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1289; }","duration":"133.480035ms","start":"2026-07-17T03:47:28.360395Z","end":"2026-07-17T03:47:28.493875Z","steps":["trace[163572806] 'agreement among raft nodes before linearized reading' (duration: 133.347662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:28.493933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.388463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:47:28.494017Z","caller":"traceutil/trace.go:171","msg":"trace[833892260] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1289; }","duration":"102.539317ms","start":"2026-07-17T03:47:28.391459Z","end":"2026-07-17T03:47:28.493998Z","steps":["trace[833892260] 'agreement among raft nodes before linearized reading' (duration: 102.364992ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:47:29.006456Z","caller":"traceutil/trace.go:171","msg":"trace[1061263818] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"189.472386ms","start":"2026-07-17T03:47:28.816964Z","end":"2026-07-17T03:47:29.006436Z","steps":["trace[1061263818] 'process raft request' (duration: 189.375123ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:47:29.006487Z","caller":"traceutil/trace.go:171","msg":"trace[1479874927] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1341; }","duration":"199.089463ms","start":"2026-07-17T03:47:28.807356Z","end":"2026-07-17T03:47:29.006445Z","steps":["trace[1479874927] 'read index received' (duration: 128.569714ms)","trace[1479874927] 'applied index is now lower than readState.Index' (duration: 70.514769ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:47:29.006635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.294979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:47:29.00667Z","caller":"traceutil/trace.go:171","msg":"trace[973494668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1290; }","duration":"199.376541ms","start":"2026-07-17T03:47:28.807285Z","end":"2026-07-17T03:47:29.006661Z","steps":["trace[973494668] 'agreement among raft nodes before linearized reading' (duration: 199.288998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:29.006859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.140857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-17T03:47:29.006879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.607541ms","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-17T03:47:29.006915Z","caller":"traceutil/trace.go:171","msg":"trace[1505946782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1290; }","duration":"167.24339ms","start":"2026-07-17T03:47:28.839658Z","end":"2026-07-17T03:47:29.006902Z","steps":["trace[1505946782] 'agreement among raft nodes before linearized reading' (duration: 167.016444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:29.006918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.012371ms","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-17T03:47:29.006993Z","caller":"traceutil/trace.go:171","msg":"trace[1055952317] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1290; }","duration":"170.768935ms","start":"2026-07-17T03:47:28.836191Z","end":"2026-07-17T03:47:29.00696Z","steps":["trace[1055952317] 'agreement among raft nodes before linearized reading' (duration: 170.437676ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:47:29.007032Z","caller":"traceutil/trace.go:171","msg":"trace[340019667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1290; }","duration":"146.234947ms","start":"2026-07-17T03:47:28.860774Z","end":"2026-07-17T03:47:29.007009Z","steps":["trace[340019667] 'agreement among raft nodes before linearized reading' (duration: 145.881598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:29.007081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.805582ms","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-17T03:47:29.007175Z","caller":"traceutil/trace.go:171","msg":"trace[601101284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1290; }","duration":"144.095739ms","start":"2026-07-17T03:47:28.863067Z","end":"2026-07-17T03:47:29.007163Z","steps":["trace[601101284] 'agreement among raft nodes before linearized reading' (duration: 143.720529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:29.007291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.822646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T03:47:29.007407Z","caller":"traceutil/trace.go:171","msg":"trace[1643487262] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1290; }","duration":"148.97557ms","start":"2026-07-17T03:47:28.85842Z","end":"2026-07-17T03:47:29.007396Z","steps":["trace[1643487262] 'agreement among raft nodes before linearized reading' (duration: 148.796985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:30.795085Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186506791625777,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T03:47:30.905758Z","caller":"traceutil/trace.go:171","msg":"trace[1364716893] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1349; }","duration":"610.77784ms","start":"2026-07-17T03:47:30.294949Z","end":"2026-07-17T03:47:30.905727Z","steps":["trace[1364716893] 'read index received' (duration: 610.461891ms)","trace[1364716893] 'applied index is now lower than readState.Index' (duration: 314.449µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:47:30.905798Z","caller":"traceutil/trace.go:171","msg":"trace[630153129] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"958.714319ms","start":"2026-07-17T03:47:29.947043Z","end":"2026-07-17T03:47:30.905757Z","steps":["trace[630153129] 'process raft request' (duration: 958.445482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:30.905935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.963045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:47:30.906005Z","caller":"traceutil/trace.go:171","msg":"trace[1952978860] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1297; }","duration":"611.086868ms","start":"2026-07-17T03:47:30.294901Z","end":"2026-07-17T03:47:30.905987Z","steps":["trace[1952978860] 'agreement among raft nodes before linearized reading' (duration: 610.917504ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:30.906122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:30.294876Z","time spent":"611.16436ms","remote":"127.0.0.1:47914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-07-17T03:47:30.906119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:29.947025Z","time spent":"958.852294ms","remote":"127.0.0.1:47706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T03:47:31.364918Z","caller":"traceutil/trace.go:171","msg":"trace[158837116] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"190.603065ms","start":"2026-07-17T03:47:31.17429Z","end":"2026-07-17T03:47:31.364893Z","steps":["trace[158837116] 'process raft request' (duration: 190.466951ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:47:31.476209Z","caller":"traceutil/trace.go:171","msg":"trace[228231872] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"301.768293ms","start":"2026-07-17T03:47:31.174428Z","end":"2026-07-17T03:47:31.476196Z","steps":["trace[228231872] 'process raft request' (duration: 301.581478ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:47:31.476271Z","caller":"traceutil/trace.go:171","msg":"trace[512769744] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"301.27653ms","start":"2026-07-17T03:47:31.174988Z","end":"2026-07-17T03:47:31.476265Z","steps":["trace[512769744] 'process raft request' (duration: 301.104526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:31.476452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:31.174417Z","time spent":"301.859356ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:47:31.476239Z","caller":"traceutil/trace.go:171","msg":"trace[1247709530] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"254.342883ms","start":"2026-07-17T03:47:31.221885Z","end":"2026-07-17T03:47:31.476228Z","steps":["trace[1247709530] 'process raft request' (duration: 254.303152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:31.47685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:31.174971Z","time spent":"301.317222ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:47:31.476848Z","caller":"traceutil/trace.go:171","msg":"trace[1390353219] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"301.849505ms","start":"2026-07-17T03:47:31.174289Z","end":"2026-07-17T03:47:31.476139Z","steps":["trace[1390353219] 'process raft request' (duration: 293.526372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:31.477084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:31.174278Z","time spent":"302.76254ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:47:32.271765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.428274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:47:32.27185Z","caller":"traceutil/trace.go:171","msg":"trace[1463267049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1305; }","duration":"463.598258ms","start":"2026-07-17T03:47:31.808231Z","end":"2026-07-17T03:47:32.27183Z","steps":["trace[1463267049] 'range keys from in-memory index tree' (duration: 463.336671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:32.271893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:31.808209Z","time spent":"463.67482ms","remote":"127.0.0.1:47614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T03:47:45.635314Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186506791625992,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T03:47:46.010223Z","caller":"traceutil/trace.go:171","msg":"trace[466857240] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"952.475399ms","start":"2026-07-17T03:47:45.057713Z","end":"2026-07-17T03:47:46.010189Z","steps":["trace[466857240] 'process raft request' (duration: 952.252753ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:46.010401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:45.057686Z","time spent":"952.615693ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:47:46.672396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.982735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T03:47:46.672598Z","caller":"traceutil/trace.go:171","msg":"trace[737099684] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"1.584428002s","start":"2026-07-17T03:47:45.088148Z","end":"2026-07-17T03:47:46.672576Z","steps":["trace[737099684] 'process raft request' (duration: 1.39818846s)","trace[737099684] 'compare' (duration: 185.844642ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:47:46.672673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:45.088121Z","time spent":"1.584509744s","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:47:46.672972Z","caller":"traceutil/trace.go:171","msg":"trace[1955211966] linearizableReadLoop","detail":"{readStateIndex:1462; appliedIndex:1460; }","duration":"1.537922296s","start":"2026-07-17T03:47:45.134999Z","end":"2026-07-17T03:47:46.672921Z","steps":["trace[1955211966] 'read index received' (duration: 874.950842ms)","trace[1955211966] 'applied index is now lower than readState.Index' (duration: 662.969264ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:47:46.673315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.300600007s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-07-17T03:47:46.673365Z","caller":"traceutil/trace.go:171","msg":"trace[599374114] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1407; }","duration":"1.30072483s","start":"2026-07-17T03:47:45.372625Z","end":"2026-07-17T03:47:46.67335Z","steps":["trace[599374114] 'agreement among raft nodes before linearized reading' (duration: 1.300539915s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:46.67336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.538312446s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-17T03:47:46.67338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.202505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T03:47:46.673387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.271147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-07-17T03:47:46.673411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131690717s","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-17T03:47:46.67344Z","caller":"traceutil/trace.go:171","msg":"trace[2103822410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1407; }","duration":"865.298208ms","start":"2026-07-17T03:47:45.808125Z","end":"2026-07-17T03:47:46.673423Z","steps":["trace[2103822410] 'agreement among raft nodes before linearized reading' (duration: 865.202894ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:47:46.673446Z","caller":"traceutil/trace.go:171","msg":"trace[885634940] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1407; }","duration":"748.38317ms","start":"2026-07-17T03:47:45.925047Z","end":"2026-07-17T03:47:46.67343Z","steps":["trace[885634940] 'agreement among raft nodes before linearized reading' (duration: 748.243515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:46.673485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:45.925021Z","time spent":"748.454932ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-17T03:47:46.673466Z","caller":"traceutil/trace.go:171","msg":"trace[276788395] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1407; }","duration":"1.13177331s","start":"2026-07-17T03:47:45.541678Z","end":"2026-07-17T03:47:46.673451Z","steps":["trace[276788395] 'agreement among raft nodes before linearized reading' (duration: 1.131593294s)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:47:46.67349Z","caller":"traceutil/trace.go:171","msg":"trace[858653805] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"847.258176ms","start":"2026-07-17T03:47:45.826218Z","end":"2026-07-17T03:47:46.673477Z","steps":["trace[858653805] 'process raft request' (duration: 846.28745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:46.673504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13326709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-17T03:47:46.673529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:45.541669Z","time spent":"1.131850402s","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-17T03:47:46.673552Z","caller":"traceutil/trace.go:171","msg":"trace[1357946872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1407; }","duration":"1.133312921s","start":"2026-07-17T03:47:45.540229Z","end":"2026-07-17T03:47:46.673542Z","steps":["trace[1357946872] 'agreement among raft nodes before linearized reading' (duration: 1.133201358s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:46.673586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:45.826184Z","time spent":"847.340668ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T03:47:46.673405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:45.372606Z","time spent":"1.300788231s","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-17T03:47:46.673439Z","caller":"traceutil/trace.go:171","msg":"trace[1476410262] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1407; }","duration":"1.53846915s","start":"2026-07-17T03:47:45.134951Z","end":"2026-07-17T03:47:46.67342Z","steps":["trace[1476410262] 'agreement among raft nodes before linearized reading' (duration: 1.538178312s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:46.673655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:45.134927Z","time spent":"1.538721248s","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T03:47:46.673667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.134573443s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-17T03:47:46.673585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:45.540165Z","time spent":"1.133413324s","remote":"127.0.0.1:47928","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-17T03:47:46.673694Z","caller":"traceutil/trace.go:171","msg":"trace[141653013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1407; }","duration":"1.134625425s","start":"2026-07-17T03:47:45.539061Z","end":"2026-07-17T03:47:46.673686Z","steps":["trace[141653013] 'agreement among raft nodes before linearized reading' (duration: 1.134552803s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:46.673488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:45.808107Z","time spent":"865.37089ms","remote":"127.0.0.1:47614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T03:47:46.673719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:45.539047Z","time spent":"1.134666965s","remote":"127.0.0.1:47928","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-17T03:47:46.673846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.248221367s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T03:47:46.673892Z","caller":"traceutil/trace.go:171","msg":"trace[591862370] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1407; }","duration":"1.248305869s","start":"2026-07-17T03:47:45.425573Z","end":"2026-07-17T03:47:46.673879Z","steps":["trace[591862370] 'agreement among raft nodes before linearized reading' (duration: 1.248193026s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:47:46.673932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:47:45.425555Z","time spent":"1.248369241s","remote":"127.0.0.1:47928","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-17T03:52:06.236056Z","caller":"traceutil/trace.go:171","msg":"trace[87086190] transaction","detail":"{read_only:false; response_revision:2627; number_of_response:1; }","duration":"283.681705ms","start":"2026-07-17T03:52:05.952331Z","end":"2026-07-17T03:52:06.236012Z","steps":["trace[87086190] 'process raft request' (duration: 278.925626ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:52:06.236432Z","caller":"traceutil/trace.go:171","msg":"trace[1241541732] transaction","detail":"{read_only:false; response_revision:2628; number_of_response:1; }","duration":"283.588771ms","start":"2026-07-17T03:52:05.952819Z","end":"2026-07-17T03:52:06.236407Z","steps":["trace[1241541732] 'process raft request' (duration: 283.12244ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:52:06.238406Z","caller":"traceutil/trace.go:171","msg":"trace[2090986805] transaction","detail":"{read_only:false; response_revision:2629; number_of_response:1; }","duration":"285.54299ms","start":"2026-07-17T03:52:05.952843Z","end":"2026-07-17T03:52:06.238386Z","steps":["trace[2090986805] 'process raft request' (duration: 283.52798ms)"],"step_count":1}