{"level":"warn","ts":"2026-07-24T04:40:34.040994Z","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-24T04:40:34.041102Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-24T04:40:34.04118Z","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-24T04:40:34.041194Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-07-24T04:40:34.041212Z","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-24T04:40:34.041936Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-07-24T04:40:34.042018Z","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.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-24T04:40:34.046909Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.580234ms"} {"level":"info","ts":"2026-07-24T04:40:34.0524Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-07-24T04:40:34.05251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-07-24T04:40:34.052553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-07-24T04:40:34.052582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-24T04:40:34.052609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-07-24T04:40:34.052674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-07-24T04:40:34.059531Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-24T04:40:34.061322Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-24T04:40:34.062751Z","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-24T04:40:34.065679Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-24T04:40:34.066169Z","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-24T04:40:34.066442Z","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-24T04:40:34.066489Z","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-24T04:40:34.067019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-07-24T04:40:34.067326Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-07-24T04:40:34.067408Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-24T04:40:34.071074Z","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-24T04:40:34.071345Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-24T04:40:34.071385Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-24T04:40:34.071525Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-07-24T04:40:34.071548Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-07-24T04:40:34.553399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-07-24T04:40:34.553459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-07-24T04:40:34.553484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-07-24T04:40:34.553504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-07-24T04:40:34.553516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-07-24T04:40:34.55354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-07-24T04:40:34.553558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-07-24T04:40:34.554593Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:40:34.555341Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-07-24T04:40:34.555332Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T04:40:34.555557Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T04:40:34.555747Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:40:34.555838Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:40:34.555873Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:40:34.557253Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-24T04:40:34.557299Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-24T04:40:34.557533Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-07-24T04:40:34.559631Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-24T04:40:58.573474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.424055ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:40:58.573548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.910307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:40:58.573617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.287806ms","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-24T04:40:58.573653Z","caller":"traceutil/trace.go:171","msg":"trace[834167979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:390; }","duration":"242.028921ms","start":"2026-07-24T04:40:58.331605Z","end":"2026-07-24T04:40:58.573634Z","steps":["trace[834167979] 'range keys from in-memory index tree' (duration: 241.865815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:58.57346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.066872ms","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-24T04:40:58.573702Z","caller":"traceutil/trace.go:171","msg":"trace[2004415823] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:390; }","duration":"166.369922ms","start":"2026-07-24T04:40:58.407323Z","end":"2026-07-24T04:40:58.573693Z","steps":["trace[2004415823] 'range keys from in-memory index tree' (duration: 165.925708ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:40:58.573706Z","caller":"traceutil/trace.go:171","msg":"trace[904998309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:390; }","duration":"266.427841ms","start":"2026-07-24T04:40:58.307254Z","end":"2026-07-24T04:40:58.573682Z","steps":["trace[904998309] 'range keys from in-memory index tree' (duration: 266.140731ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:40:58.573638Z","caller":"traceutil/trace.go:171","msg":"trace[196775680] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:390; }","duration":"320.609641ms","start":"2026-07-24T04:40:58.25301Z","end":"2026-07-24T04:40:58.57362Z","steps":["trace[196775680] 'range keys from in-memory index tree' (duration: 320.408315ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:58.573885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.732042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-24T04:40:58.573918Z","caller":"traceutil/trace.go:171","msg":"trace[2127682313] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:390; }","duration":"337.806284ms","start":"2026-07-24T04:40:58.236099Z","end":"2026-07-24T04:40:58.573905Z","steps":["trace[2127682313] 'range keys from in-memory index tree' (duration: 337.644268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:58.573947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:58.236079Z","time spent":"337.859676ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-24T04:40:59.08086Z","caller":"traceutil/trace.go:171","msg":"trace[2087861321] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"502.000737ms","start":"2026-07-24T04:40:58.578238Z","end":"2026-07-24T04:40:59.080239Z","steps":["trace[2087861321] 'process raft request' (duration: 501.878763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:59.081379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:58.578214Z","time spent":"502.703109ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:40:59.472478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.634018ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:40:59.472634Z","caller":"traceutil/trace.go:171","msg":"trace[2031239363] linearizableReadLoop","detail":"{readStateIndex:406; appliedIndex:403; }","duration":"397.965083ms","start":"2026-07-24T04:40:59.074656Z","end":"2026-07-24T04:40:59.472621Z","steps":["trace[2031239363] 'read index received' (duration: 5.520894ms)","trace[2031239363] 'applied index is now lower than readState.Index' (duration: 392.443369ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:40:59.472762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.105147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-07-24T04:40:59.472736Z","caller":"traceutil/trace.go:171","msg":"trace[550103964] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"891.72455ms","start":"2026-07-24T04:40:58.580983Z","end":"2026-07-24T04:40:59.472708Z","steps":["trace[550103964] 'process raft request' (duration: 891.595856ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:40:59.472793Z","caller":"traceutil/trace.go:171","msg":"trace[2085321723] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:393; }","duration":"398.17038ms","start":"2026-07-24T04:40:59.074615Z","end":"2026-07-24T04:40:59.472786Z","steps":["trace[2085321723] 'agreement among raft nodes before linearized reading' (duration: 398.095968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:40:59.47282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:59.074596Z","time spent":"398.217671ms","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4079,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-07-24T04:40:59.472882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:58.580962Z","time spent":"891.841874ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:40:59.472987Z","caller":"traceutil/trace.go:171","msg":"trace[1116918109] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"893.30571ms","start":"2026-07-24T04:40:58.579658Z","end":"2026-07-24T04:40:59.472963Z","steps":["trace[1116918109] 'process raft request' (duration: 747.026232ms)","trace[1116918109] 'compare' (duration: 145.500804ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:40:59.473033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.036182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:40:59.47309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:40:58.579637Z","time spent":"893.406963ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:40:59.473132Z","caller":"traceutil/trace.go:171","msg":"trace[1814943244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:393; }","duration":"141.199908ms","start":"2026-07-24T04:40:59.331908Z","end":"2026-07-24T04:40:59.473108Z","steps":["trace[1814943244] 'agreement among raft nodes before linearized reading' (duration: 141.010732ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:12.86238Z","caller":"traceutil/trace.go:171","msg":"trace[1237235681] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"103.974003ms","start":"2026-07-24T04:41:12.758378Z","end":"2026-07-24T04:41:12.862352Z","steps":["trace[1237235681] 'process raft request' (duration: 103.842378ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:12.907514Z","caller":"traceutil/trace.go:171","msg":"trace[1842527534] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"139.889046ms","start":"2026-07-24T04:41:12.767589Z","end":"2026-07-24T04:41:12.907478Z","steps":["trace[1842527534] 'process raft request' (duration: 139.404541ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:12.907569Z","caller":"traceutil/trace.go:171","msg":"trace[1510446362] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"138.459871ms","start":"2026-07-24T04:41:12.769083Z","end":"2026-07-24T04:41:12.907542Z","steps":["trace[1510446362] 'process raft request' (duration: 137.968636ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:12.907762Z","caller":"traceutil/trace.go:171","msg":"trace[444297979] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"145.297257ms","start":"2026-07-24T04:41:12.762447Z","end":"2026-07-24T04:41:12.907744Z","steps":["trace[444297979] 'process raft request' (duration: 144.301675ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:12.90788Z","caller":"traceutil/trace.go:171","msg":"trace[592435642] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"138.080309ms","start":"2026-07-24T04:41:12.769776Z","end":"2026-07-24T04:41:12.907856Z","steps":["trace[592435642] 'process raft request' (duration: 137.308415ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:12.907892Z","caller":"traceutil/trace.go:171","msg":"trace[403796175] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"133.842175ms","start":"2026-07-24T04:41:12.77402Z","end":"2026-07-24T04:41:12.907863Z","steps":["trace[403796175] 'process raft request' (duration: 133.102032ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:12.907917Z","caller":"traceutil/trace.go:171","msg":"trace[1756956164] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"140.520166ms","start":"2026-07-24T04:41:12.767376Z","end":"2026-07-24T04:41:12.907897Z","steps":["trace[1756956164] 'process raft request' (duration: 139.495184ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:12.907845Z","caller":"traceutil/trace.go:171","msg":"trace[857792464] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"140.31426ms","start":"2026-07-24T04:41:12.76747Z","end":"2026-07-24T04:41:12.907784Z","steps":["trace[857792464] 'process raft request' (duration: 139.442992ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:12.908065Z","caller":"traceutil/trace.go:171","msg":"trace[763724998] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"140.538366ms","start":"2026-07-24T04:41:12.767517Z","end":"2026-07-24T04:41:12.908055Z","steps":["trace[763724998] 'process raft request' (duration: 139.437092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:13.655046Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605830204685682754,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T04:41:14.15603Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605830204685682754,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T04:41:14.339098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.352298969s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:41:14.339337Z","caller":"traceutil/trace.go:171","msg":"trace[268190062] transaction","detail":"{read_only:false; number_of_response:1; response_revision:499; }","duration":"1.469619282s","start":"2026-07-24T04:41:12.869704Z","end":"2026-07-24T04:41:14.339323Z","steps":["trace[268190062] 'process raft request' (duration: 1.469592082s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:14.339404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:12.869699Z","time spent":"1.469669804s","remote":"127.0.0.1:41792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10112,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:41:14.339506Z","caller":"traceutil/trace.go:171","msg":"trace[479212641] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"1.472884635s","start":"2026-07-24T04:41:12.866594Z","end":"2026-07-24T04:41:14.339479Z","steps":["trace[479212641] 'process raft request' (duration: 1.472585976s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:14.339609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:12.866588Z","time spent":"1.472971669s","remote":"127.0.0.1:42260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T04:41:14.339339Z","caller":"traceutil/trace.go:171","msg":"trace[872473598] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"1.473125813s","start":"2026-07-24T04:41:12.866182Z","end":"2026-07-24T04:41:14.339308Z","steps":["trace[872473598] 'process raft request' (duration: 120.560055ms)","trace[872473598] 'compare' (duration: 1.351754432s)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:41:14.339693Z","caller":"traceutil/trace.go:171","msg":"trace[1855187047] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"1.470329595s","start":"2026-07-24T04:41:12.869353Z","end":"2026-07-24T04:41:14.339683Z","steps":["trace[1855187047] 'process raft request' (duration: 1.469889161s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:14.33989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:12.866166Z","time spent":"1.473571667s","remote":"127.0.0.1:42260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:41:14.339891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:12.869348Z","time spent":"1.470476819s","remote":"127.0.0.1:41792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:41:14.398262Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.358691571s","expected-duration":"1s"} {"level":"info","ts":"2026-07-24T04:41:14.398896Z","caller":"traceutil/trace.go:171","msg":"trace[1741254228] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"1.485074181s","start":"2026-07-24T04:41:12.913787Z","end":"2026-07-24T04:41:14.398861Z","steps":["trace[1741254228] 'process raft request' (duration: 1.484760161s)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:14.398977Z","caller":"traceutil/trace.go:171","msg":"trace[383134414] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"1.484881714s","start":"2026-07-24T04:41:12.914057Z","end":"2026-07-24T04:41:14.398939Z","steps":["trace[383134414] 'process raft request' (duration: 1.484687708s)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:14.399154Z","caller":"traceutil/trace.go:171","msg":"trace[1106454029] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"1.477705498s","start":"2026-07-24T04:41:12.921429Z","end":"2026-07-24T04:41:14.399134Z","steps":["trace[1106454029] 'process raft request' (duration: 1.477409759s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:14.399266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:12.91405Z","time spent":"1.485089861s","remote":"127.0.0.1:41792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:41:14.39928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:12.921411Z","time spent":"1.477824122s","remote":"127.0.0.1:42260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:41:14.39951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:12.91377Z","time spent":"1.485189284s","remote":"127.0.0.1:42260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T04:41:14.39953Z","caller":"traceutil/trace.go:171","msg":"trace[1242599141] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"1.47524458s","start":"2026-07-24T04:41:12.924266Z","end":"2026-07-24T04:41:14.39951Z","steps":["trace[1242599141] 'process raft request' (duration: 1.47461544s)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:14.399632Z","caller":"traceutil/trace.go:171","msg":"trace[2132952140] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"1.473733693s","start":"2026-07-24T04:41:12.925884Z","end":"2026-07-24T04:41:14.399618Z","steps":["trace[2132952140] 'process raft request' (duration: 1.473543917s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:14.399631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:12.924256Z","time spent":"1.475318582s","remote":"127.0.0.1:41792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:41:14.399708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:12.925877Z","time spent":"1.473786174s","remote":"127.0.0.1:41792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:41:14.400024Z","caller":"traceutil/trace.go:171","msg":"trace[1269460784] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"1.472813273s","start":"2026-07-24T04:41:12.927199Z","end":"2026-07-24T04:41:14.400012Z","steps":["trace[1269460784] 'process raft request' (duration: 1.472290247s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:14.400092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:12.927192Z","time spent":"1.472870976s","remote":"127.0.0.1:42260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T04:41:14.400927Z","caller":"traceutil/trace.go:171","msg":"trace[1116213538] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:517; }","duration":"1.246118277s","start":"2026-07-24T04:41:13.154797Z","end":"2026-07-24T04:41:14.400916Z","steps":["trace[1116213538] 'read index received' (duration: 1.243915947s)","trace[1116213538] 'applied index is now lower than readState.Index' (duration: 2.20003ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:41:14.400957Z","caller":"traceutil/trace.go:171","msg":"trace[81022987] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"1.468629721s","start":"2026-07-24T04:41:12.932311Z","end":"2026-07-24T04:41:14.40094Z","steps":["trace[81022987] 'process raft request' (duration: 1.467729493s)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:14.400993Z","caller":"traceutil/trace.go:171","msg":"trace[1548514192] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"1.433606926s","start":"2026-07-24T04:41:12.967381Z","end":"2026-07-24T04:41:14.400988Z","steps":["trace[1548514192] 'process raft request' (duration: 1.433481232s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:14.401035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:12.967362Z","time spent":"1.433650168s","remote":"127.0.0.1:41792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:41:14.40104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:12.932287Z","time spent":"1.468708664s","remote":"127.0.0.1:41792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:41:14.401204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246404766s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-24T04:41:14.401247Z","caller":"traceutil/trace.go:171","msg":"trace[1133085002] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:507; }","duration":"1.246481709s","start":"2026-07-24T04:41:13.154755Z","end":"2026-07-24T04:41:14.401236Z","steps":["trace[1133085002] 'agreement among raft nodes before linearized reading' (duration: 1.246363215s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:14.401288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:13.154724Z","time spent":"1.246554891s","remote":"127.0.0.1:42036","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":"info","ts":"2026-07-24T04:41:14.528602Z","caller":"traceutil/trace.go:171","msg":"trace[243761205] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"166.112474ms","start":"2026-07-24T04:41:14.362446Z","end":"2026-07-24T04:41:14.528558Z","steps":["trace[243761205] 'process raft request' (duration: 165.845976ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:14.528701Z","caller":"traceutil/trace.go:171","msg":"trace[1161766128] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"105.698067ms","start":"2026-07-24T04:41:14.422974Z","end":"2026-07-24T04:41:14.528672Z","steps":["trace[1161766128] 'process raft request' (duration: 105.628104ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:14.528966Z","caller":"traceutil/trace.go:171","msg":"trace[731292065] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:525; }","duration":"127.97107ms","start":"2026-07-24T04:41:14.400967Z","end":"2026-07-24T04:41:14.528938Z","steps":["trace[731292065] 'read index received' (duration: 48.029276ms)","trace[731292065] 'applied index is now lower than readState.Index' (duration: 79.937924ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:41:14.528961Z","caller":"traceutil/trace.go:171","msg":"trace[1341792979] transaction","detail":"{read_only:false; number_of_response:1; response_revision:507; }","duration":"174.488068ms","start":"2026-07-24T04:41:14.35446Z","end":"2026-07-24T04:41:14.528948Z","steps":["trace[1341792979] 'process raft request' (duration: 94.546535ms)","trace[1341792979] 'compare' (duration: 79.167119ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:41:14.529104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.197922235s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:41:14.529134Z","caller":"traceutil/trace.go:171","msg":"trace[1639622835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"1.198012419s","start":"2026-07-24T04:41:13.33111Z","end":"2026-07-24T04:41:14.529122Z","steps":["trace[1639622835] 'agreement among raft nodes before linearized reading' (duration: 1.197931866s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:14.529164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:13.331087Z","time spent":"1.19807109s","remote":"127.0.0.1:41728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T04:41:14.529388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.121247ms","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-24T04:41:14.529452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.565997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-24T04:41:14.529451Z","caller":"traceutil/trace.go:171","msg":"trace[1516358161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:511; }","duration":"647.235211ms","start":"2026-07-24T04:41:13.882201Z","end":"2026-07-24T04:41:14.529437Z","steps":["trace[1516358161] 'agreement among raft nodes before linearized reading' (duration: 647.110297ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:14.529472Z","caller":"traceutil/trace.go:171","msg":"trace[1068726683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:511; }","duration":"963.612619ms","start":"2026-07-24T04:41:13.565853Z","end":"2026-07-24T04:41:14.529466Z","steps":["trace[1068726683] 'agreement among raft nodes before linearized reading' (duration: 963.579258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:14.529494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:13.565835Z","time spent":"963.65407ms","remote":"127.0.0.1:42036","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-24T04:41:14.529466Z","caller":"traceutil/trace.go:171","msg":"trace[131679024] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"121.890708ms","start":"2026-07-24T04:41:14.407542Z","end":"2026-07-24T04:41:14.529433Z","steps":["trace[131679024] 'process raft request' (duration: 120.899886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:14.529597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:13.882181Z","time spent":"647.402346ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T04:41:14.529416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.657704ms","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-24T04:41:14.529723Z","caller":"traceutil/trace.go:171","msg":"trace[1452597953] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"110.21416ms","start":"2026-07-24T04:41:14.419476Z","end":"2026-07-24T04:41:14.529691Z","steps":["trace[1452597953] 'process raft request' (duration: 109.034143ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:14.52974Z","caller":"traceutil/trace.go:171","msg":"trace[97988086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:511; }","duration":"960.017575ms","start":"2026-07-24T04:41:13.569711Z","end":"2026-07-24T04:41:14.529729Z","steps":["trace[97988086] 'agreement among raft nodes before linearized reading' (duration: 959.663924ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:14.529784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:13.569692Z","time spent":"960.078267ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-24T04:41:14.702379Z","caller":"traceutil/trace.go:171","msg":"trace[1135138483] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"101.693091ms","start":"2026-07-24T04:41:14.600666Z","end":"2026-07-24T04:41:14.702359Z","steps":["trace[1135138483] 'process raft request' (duration: 101.35135ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:14.702545Z","caller":"traceutil/trace.go:171","msg":"trace[1867244650] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"120.177694ms","start":"2026-07-24T04:41:14.582302Z","end":"2026-07-24T04:41:14.70248Z","steps":["trace[1867244650] 'process raft request' (duration: 62.659228ms)","trace[1867244650] 'compare' (duration: 56.934497ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:41:15.249708Z","caller":"traceutil/trace.go:171","msg":"trace[869383618] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"109.732805ms","start":"2026-07-24T04:41:15.139943Z","end":"2026-07-24T04:41:15.249676Z","steps":["trace[869383618] 'process raft request' (duration: 96.110835ms)","trace[869383618] 'compare' (duration: 13.32562ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:41:15.249681Z","caller":"traceutil/trace.go:171","msg":"trace[206842754] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:555; }","duration":"102.092963ms","start":"2026-07-24T04:41:15.147537Z","end":"2026-07-24T04:41:15.24963Z","steps":["trace[206842754] 'read index received' (duration: 88.528845ms)","trace[206842754] 'applied index is now lower than readState.Index' (duration: 13.562618ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:41:15.249872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.29401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:41:15.249948Z","caller":"traceutil/trace.go:171","msg":"trace[2026875490] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:537; }","duration":"102.440244ms","start":"2026-07-24T04:41:15.147497Z","end":"2026-07-24T04:41:15.249937Z","steps":["trace[2026875490] 'agreement among raft nodes before linearized reading' (duration: 102.274369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:15.25869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.051116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:41:15.258804Z","caller":"traceutil/trace.go:171","msg":"trace[1226051663] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:538; }","duration":"111.199741ms","start":"2026-07-24T04:41:15.147577Z","end":"2026-07-24T04:41:15.258777Z","steps":["trace[1226051663] 'agreement among raft nodes before linearized reading' (duration: 111.007025ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:15.477462Z","caller":"traceutil/trace.go:171","msg":"trace[581746837] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"174.780507ms","start":"2026-07-24T04:41:15.302654Z","end":"2026-07-24T04:41:15.477434Z","steps":["trace[581746837] 'process raft request' (duration: 129.23619ms)","trace[581746837] 'compare' (duration: 45.069812ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:41:15.47784Z","caller":"traceutil/trace.go:171","msg":"trace[365259885] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"160.628771ms","start":"2026-07-24T04:41:15.317102Z","end":"2026-07-24T04:41:15.477731Z","steps":["trace[365259885] 'process raft request' (duration: 160.208538ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:15.693487Z","caller":"traceutil/trace.go:171","msg":"trace[1628131907] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"181.105317ms","start":"2026-07-24T04:41:15.512345Z","end":"2026-07-24T04:41:15.69345Z","steps":["trace[1628131907] 'process raft request' (duration: 168.52501ms)","trace[1628131907] 'compare' (duration: 12.290338ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:41:15.693803Z","caller":"traceutil/trace.go:171","msg":"trace[1140590475] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"154.938241ms","start":"2026-07-24T04:41:15.538839Z","end":"2026-07-24T04:41:15.693778Z","steps":["trace[1140590475] 'process raft request' (duration: 154.515298ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:15.857729Z","caller":"traceutil/trace.go:171","msg":"trace[1113472614] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:565; }","duration":"126.076911ms","start":"2026-07-24T04:41:15.731636Z","end":"2026-07-24T04:41:15.857713Z","steps":["trace[1113472614] 'read index received' (duration: 125.969567ms)","trace[1113472614] 'applied index is now lower than readState.Index' (duration: 106.674µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:41:15.857875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.226905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-07-24T04:41:15.857906Z","caller":"traceutil/trace.go:171","msg":"trace[274208308] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:547; }","duration":"126.298227ms","start":"2026-07-24T04:41:15.731598Z","end":"2026-07-24T04:41:15.857897Z","steps":["trace[274208308] 'agreement among raft nodes before linearized reading' (duration: 126.179534ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:15.857906Z","caller":"traceutil/trace.go:171","msg":"trace[1290525883] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"150.843172ms","start":"2026-07-24T04:41:15.707054Z","end":"2026-07-24T04:41:15.857897Z","steps":["trace[1290525883] 'process raft request' (duration: 150.571584ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:15.866519Z","caller":"traceutil/trace.go:171","msg":"trace[1228430020] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"102.000739ms","start":"2026-07-24T04:41:15.764493Z","end":"2026-07-24T04:41:15.866494Z","steps":["trace[1228430020] 'process raft request' (duration: 101.740451ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:21.334129Z","caller":"traceutil/trace.go:171","msg":"trace[485672222] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"194.819121ms","start":"2026-07-24T04:41:21.139258Z","end":"2026-07-24T04:41:21.334077Z","steps":["trace[485672222] 'process raft request' (duration: 193.828639ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:21.521599Z","caller":"traceutil/trace.go:171","msg":"trace[762698960] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"148.055944ms","start":"2026-07-24T04:41:21.373494Z","end":"2026-07-24T04:41:21.52155Z","steps":["trace[762698960] 'process raft request' (duration: 147.871198ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:21.543853Z","caller":"traceutil/trace.go:171","msg":"trace[2067987910] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"146.858106ms","start":"2026-07-24T04:41:21.396968Z","end":"2026-07-24T04:41:21.543826Z","steps":["trace[2067987910] 'process raft request' (duration: 146.721162ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:22.782373Z","caller":"traceutil/trace.go:171","msg":"trace[635311468] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"206.615332ms","start":"2026-07-24T04:41:22.575737Z","end":"2026-07-24T04:41:22.782352Z","steps":["trace[635311468] 'read index received' (duration: 206.123327ms)","trace[635311468] 'applied index is now lower than readState.Index' (duration: 491.095µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:41:22.782476Z","caller":"traceutil/trace.go:171","msg":"trace[2087619069] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"837.782717ms","start":"2026-07-24T04:41:21.944656Z","end":"2026-07-24T04:41:22.782438Z","steps":["trace[2087619069] 'process raft request' (duration: 836.639251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:22.78273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:21.944634Z","time spent":"838.006644ms","remote":"127.0.0.1:41846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:41:22.782749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.990985ms","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-24T04:41:22.782851Z","caller":"traceutil/trace.go:171","msg":"trace[1762873144] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"207.136949ms","start":"2026-07-24T04:41:22.575685Z","end":"2026-07-24T04:41:22.782822Z","steps":["trace[1762873144] 'agreement among raft nodes before linearized reading' (duration: 206.871821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:24.057078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.26422247s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-24T04:41:24.057371Z","caller":"traceutil/trace.go:171","msg":"trace[1729516774] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"1.274832985s","start":"2026-07-24T04:41:22.782516Z","end":"2026-07-24T04:41:24.057349Z","steps":["trace[1729516774] 'read index received' (duration: 10.311576ms)","trace[1729516774] 'applied index is now lower than readState.Index' (duration: 1.264520109s)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:41:24.057759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.388184833s","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-24T04:41:24.057811Z","caller":"traceutil/trace.go:171","msg":"trace[1820764489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:592; }","duration":"1.388272406s","start":"2026-07-24T04:41:22.669521Z","end":"2026-07-24T04:41:24.057793Z","steps":["trace[1820764489] 'agreement among raft nodes before linearized reading' (duration: 1.388045969s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:24.057857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:22.669507Z","time spent":"1.388336038s","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T04:41:24.057922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.479445133s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-24T04:41:24.057984Z","caller":"traceutil/trace.go:171","msg":"trace[683601861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:592; }","duration":"1.479551187s","start":"2026-07-24T04:41:22.578414Z","end":"2026-07-24T04:41:24.057965Z","steps":["trace[683601861] 'agreement among raft nodes before linearized reading' (duration: 1.479366602s)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:24.057991Z","caller":"traceutil/trace.go:171","msg":"trace[309486348] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"1.271481509s","start":"2026-07-24T04:41:22.786488Z","end":"2026-07-24T04:41:24.057969Z","steps":["trace[309486348] 'process raft request' (duration: 1.270719865s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:24.058015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:22.578394Z","time spent":"1.479614739s","remote":"127.0.0.1:42036","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-24T04:41:24.058016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.392546281s","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-24T04:41:24.058154Z","caller":"traceutil/trace.go:171","msg":"trace[945784567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:592; }","duration":"1.392725477s","start":"2026-07-24T04:41:22.665403Z","end":"2026-07-24T04:41:24.058128Z","steps":["trace[945784567] 'agreement among raft nodes before linearized reading' (duration: 1.392421887s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:24.058157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:22.786464Z","time spent":"1.271579702s","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:41:24.058209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:22.665388Z","time spent":"1.392799718s","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-24T04:41:25.093683Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.03080602s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-24T04:41:25.239528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181471166s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:41:25.23982Z","caller":"traceutil/trace.go:171","msg":"trace[1502512745] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"1.175530059s","start":"2026-07-24T04:41:24.064266Z","end":"2026-07-24T04:41:25.239796Z","steps":["trace[1502512745] 'process raft request' (duration: 1.175475487s)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:25.240031Z","caller":"traceutil/trace.go:171","msg":"trace[1471489401] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"1.182509889s","start":"2026-07-24T04:41:24.057494Z","end":"2026-07-24T04:41:25.240004Z","steps":["trace[1471489401] 'read index received' (duration: 333.96µs)","trace[1471489401] 'applied index is now lower than readState.Index' (duration: 1.182173099s)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:41:25.240098Z","caller":"traceutil/trace.go:171","msg":"trace[1941798783] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"1.177287495s","start":"2026-07-24T04:41:24.062778Z","end":"2026-07-24T04:41:25.240066Z","steps":["trace[1941798783] 'process raft request' (duration: 1.176853001s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:25.240152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.909342694s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:41:25.240189Z","caller":"traceutil/trace.go:171","msg":"trace[2026951476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"1.909456558s","start":"2026-07-24T04:41:23.33072Z","end":"2026-07-24T04:41:25.240177Z","steps":["trace[2026951476] 'agreement among raft nodes before linearized reading' (duration: 1.909368095s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:25.240224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:23.3307Z","time spent":"1.909515479s","remote":"127.0.0.1:41732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T04:41:25.240212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:24.064256Z","time spent":"1.175616981s","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:41:25.240279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:24.062755Z","time spent":"1.177421228s","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:41:25.2403Z","caller":"traceutil/trace.go:171","msg":"trace[1294147957] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.1771643s","start":"2026-07-24T04:41:24.063112Z","end":"2026-07-24T04:41:25.240277Z","steps":["trace[1294147957] 'process raft request' (duration: 1.176585222s)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:25.240342Z","caller":"traceutil/trace.go:171","msg":"trace[1986544040] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"2.384775483s","start":"2026-07-24T04:41:22.855547Z","end":"2026-07-24T04:41:25.240323Z","steps":["trace[1986544040] 'process raft request' (duration: 1.202409298s)","trace[1986544040] 'compare' (duration: 1.181243869s)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:41:25.240414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:22.85552Z","time spent":"2.384859955s","remote":"127.0.0.1:42106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:41:25.240446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:24.063096Z","time spent":"1.177272254s","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:41:26.878403Z","caller":"traceutil/trace.go:171","msg":"trace[283332051] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"338.244707ms","start":"2026-07-24T04:41:26.540131Z","end":"2026-07-24T04:41:26.878376Z","steps":["trace[283332051] 'process raft request' (duration: 337.676459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:26.87858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:26.540116Z","time spent":"338.370362ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:41:26.878645Z","caller":"traceutil/trace.go:171","msg":"trace[371875187] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:636; }","duration":"336.982808ms","start":"2026-07-24T04:41:26.541637Z","end":"2026-07-24T04:41:26.878619Z","steps":["trace[371875187] 'read index received' (duration: 336.971758ms)","trace[371875187] 'applied index is now lower than readState.Index' (duration: 9.53µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:41:26.878808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.145993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:41:26.878836Z","caller":"traceutil/trace.go:171","msg":"trace[1362667393] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:615; }","duration":"337.212396ms","start":"2026-07-24T04:41:26.541616Z","end":"2026-07-24T04:41:26.878828Z","steps":["trace[1362667393] 'agreement among raft nodes before linearized reading' (duration: 337.112502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:26.878861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:26.541608Z","time spent":"337.247096ms","remote":"127.0.0.1:41792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"info","ts":"2026-07-24T04:41:26.895723Z","caller":"traceutil/trace.go:171","msg":"trace[67838245] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"352.23988ms","start":"2026-07-24T04:41:26.543452Z","end":"2026-07-24T04:41:26.895691Z","steps":["trace[67838245] 'process raft request' (duration: 352.071295ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:26.8958Z","caller":"traceutil/trace.go:171","msg":"trace[1714966573] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"351.223378ms","start":"2026-07-24T04:41:26.544557Z","end":"2026-07-24T04:41:26.89578Z","steps":["trace[1714966573] 'process raft request' (duration: 351.051863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:26.895893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:26.544547Z","time spent":"351.29933ms","remote":"127.0.0.1:41792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:41:26.89621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:26.543443Z","time spent":"352.363104ms","remote":"127.0.0.1:41792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:41:26.896437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.1571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:41:26.896508Z","caller":"traceutil/trace.go:171","msg":"trace[719712889] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:618; }","duration":"354.253523ms","start":"2026-07-24T04:41:26.542236Z","end":"2026-07-24T04:41:26.89649Z","steps":["trace[719712889] 'agreement among raft nodes before linearized reading' (duration: 354.132249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:26.89654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:26.542229Z","time spent":"354.304395ms","remote":"127.0.0.1:41792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-07-24T04:41:26.896792Z","caller":"traceutil/trace.go:171","msg":"trace[984431030] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"211.335791ms","start":"2026-07-24T04:41:26.685438Z","end":"2026-07-24T04:41:26.896774Z","steps":["trace[984431030] 'process raft request' (duration: 210.495215ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:26.896842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.668476ms","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-24T04:41:26.896882Z","caller":"traceutil/trace.go:171","msg":"trace[1687337226] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:618; }","duration":"156.737008ms","start":"2026-07-24T04:41:26.740129Z","end":"2026-07-24T04:41:26.896866Z","steps":["trace[1687337226] 'agreement among raft nodes before linearized reading' (duration: 156.692517ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:26.896984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.764275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:41:26.897017Z","caller":"traceutil/trace.go:171","msg":"trace[2017894195] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:618; }","duration":"351.794866ms","start":"2026-07-24T04:41:26.545213Z","end":"2026-07-24T04:41:26.897008Z","steps":["trace[2017894195] 'agreement among raft nodes before linearized reading' (duration: 351.752534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:26.897079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.246872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:41:26.897117Z","caller":"traceutil/trace.go:171","msg":"trace[817330669] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:618; }","duration":"353.328884ms","start":"2026-07-24T04:41:26.543781Z","end":"2026-07-24T04:41:26.89711Z","steps":["trace[817330669] 'agreement among raft nodes before linearized reading' (duration: 353.272192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:26.897136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:26.54376Z","time spent":"353.371415ms","remote":"127.0.0.1:41792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-07-24T04:41:26.897191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.123444ms","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":"info","ts":"2026-07-24T04:41:26.897212Z","caller":"traceutil/trace.go:171","msg":"trace[636642383] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:618; }","duration":"351.143135ms","start":"2026-07-24T04:41:26.546064Z","end":"2026-07-24T04:41:26.897207Z","steps":["trace[636642383] 'agreement among raft nodes before linearized reading' (duration: 351.115764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:26.897291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:26.546033Z","time spent":"351.195107ms","remote":"127.0.0.1:41792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-07-24T04:41:26.897319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:26.545197Z","time spent":"351.843157ms","remote":"127.0.0.1:41792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-07-24T04:41:26.896791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.210045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:41:26.897421Z","caller":"traceutil/trace.go:171","msg":"trace[59932209] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:618; }","duration":"350.848875ms","start":"2026-07-24T04:41:26.546563Z","end":"2026-07-24T04:41:26.897412Z","steps":["trace[59932209] 'agreement among raft nodes before linearized reading' (duration: 350.176914ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:26.897443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:26.546533Z","time spent":"350.902557ms","remote":"127.0.0.1:41792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-07-24T04:41:36.763414Z","caller":"traceutil/trace.go:171","msg":"trace[860271528] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"296.14219ms","start":"2026-07-24T04:41:36.467254Z","end":"2026-07-24T04:41:36.763396Z","steps":["trace[860271528] 'process raft request' (duration: 296.020906ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:39.865757Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605830204685683281,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-24T04:41:40.308883Z","caller":"traceutil/trace.go:171","msg":"trace[552052313] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"989.78858ms","start":"2026-07-24T04:41:39.319073Z","end":"2026-07-24T04:41:40.308861Z","steps":["trace[552052313] 'process raft request' (duration: 989.652395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:40.309026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:39.319053Z","time spent":"989.903134ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:41:40.540921Z","caller":"traceutil/trace.go:171","msg":"trace[1899548571] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"1.220656659s","start":"2026-07-24T04:41:39.320233Z","end":"2026-07-24T04:41:40.540889Z","steps":["trace[1899548571] 'process raft request' (duration: 1.220567936s)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:41:40.541022Z","caller":"traceutil/trace.go:171","msg":"trace[484098595] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:785; }","duration":"1.176158674s","start":"2026-07-24T04:41:39.364834Z","end":"2026-07-24T04:41:40.540993Z","steps":["trace[484098595] 'read index received' (duration: 943.942992ms)","trace[484098595] 'applied index is now lower than readState.Index' (duration: 232.213711ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:41:40.541055Z","caller":"traceutil/trace.go:171","msg":"trace[333129953] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"1.051734455s","start":"2026-07-24T04:41:39.489299Z","end":"2026-07-24T04:41:40.541034Z","steps":["trace[333129953] 'process raft request' (duration: 1.051535689s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:40.54117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:39.489276Z","time spent":"1.051826568s","remote":"127.0.0.1:42008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:41:40.541184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.176335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:41:40.541143Z","caller":"traceutil/trace.go:171","msg":"trace[1501307209] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"1.221827086s","start":"2026-07-24T04:41:39.319137Z","end":"2026-07-24T04:41:40.540964Z","steps":["trace[1501307209] 'process raft request' (duration: 1.221528666s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:40.541307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.152528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:41:40.54138Z","caller":"traceutil/trace.go:171","msg":"trace[704845874] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:764; }","duration":"670.258281ms","start":"2026-07-24T04:41:39.871105Z","end":"2026-07-24T04:41:40.541363Z","steps":["trace[704845874] 'agreement among raft nodes before linearized reading' (duration: 670.168398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:40.541439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:39.871085Z","time spent":"670.336304ms","remote":"127.0.0.1:41914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:41:40.541441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.511555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:41:40.541464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:39.319119Z","time spent":"1.222179297s","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:41:40.541238Z","caller":"traceutil/trace.go:171","msg":"trace[29087502] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:764; }","duration":"1.176438433s","start":"2026-07-24T04:41:39.364781Z","end":"2026-07-24T04:41:40.54122Z","steps":["trace[29087502] 'agreement among raft nodes before linearized reading' (duration: 1.17634407s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:40.541596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.323258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-24T04:41:40.54152Z","caller":"traceutil/trace.go:171","msg":"trace[1565873061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:764; }","duration":"209.630638ms","start":"2026-07-24T04:41:40.331869Z","end":"2026-07-24T04:41:40.541499Z","steps":["trace[1565873061] 'agreement among raft nodes before linearized reading' (duration: 209.510485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:40.541719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:39.364758Z","time spent":"1.176848586s","remote":"127.0.0.1:42142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:41:40.541756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:39.320219Z","time spent":"1.220788163s","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:41:40.541941Z","caller":"traceutil/trace.go:171","msg":"trace[397226447] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:764; }","duration":"412.441062ms","start":"2026-07-24T04:41:40.129188Z","end":"2026-07-24T04:41:40.541629Z","steps":["trace[397226447] 'agreement among raft nodes before linearized reading' (duration: 412.283027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:41:40.541986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:41:40.129169Z","time spent":"412.807303ms","remote":"127.0.0.1:42036","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-24T04:42:16.628823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.90831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-24T04:42:16.628926Z","caller":"traceutil/trace.go:171","msg":"trace[107441958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:978; }","duration":"142.025474ms","start":"2026-07-24T04:42:16.48688Z","end":"2026-07-24T04:42:16.628906Z","steps":["trace[107441958] 'range keys from in-memory index tree' (duration: 141.737415ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:20.255418Z","caller":"traceutil/trace.go:171","msg":"trace[1681774434] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"115.464786ms","start":"2026-07-24T04:42:20.139932Z","end":"2026-07-24T04:42:20.255397Z","steps":["trace[1681774434] 'process raft request' (duration: 115.325231ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:40.620765Z","caller":"traceutil/trace.go:171","msg":"trace[1198778318] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"306.632121ms","start":"2026-07-24T04:42:40.314112Z","end":"2026-07-24T04:42:40.620744Z","steps":["trace[1198778318] 'process raft request' (duration: 306.520588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:40.620887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:40.314052Z","time spent":"306.772326ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:42:40.992912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.605026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-24T04:42:40.992948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.08078ms","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-24T04:42:40.992987Z","caller":"traceutil/trace.go:171","msg":"trace[1092702727] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1058; }","duration":"142.733051ms","start":"2026-07-24T04:42:40.850234Z","end":"2026-07-24T04:42:40.992967Z","steps":["trace[1092702727] 'range keys from in-memory index tree' (duration: 142.449551ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:40.993002Z","caller":"traceutil/trace.go:171","msg":"trace[1782304933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1058; }","duration":"130.165112ms","start":"2026-07-24T04:42:40.862824Z","end":"2026-07-24T04:42:40.99299Z","steps":["trace[1782304933] 'range keys from in-memory index tree' (duration: 129.973527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:40.993278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.606951ms","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-24T04:42:40.99325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.554585ms","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-24T04:42:40.993303Z","caller":"traceutil/trace.go:171","msg":"trace[1775201494] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1058; }","duration":"253.665213ms","start":"2026-07-24T04:42:40.739633Z","end":"2026-07-24T04:42:40.993298Z","steps":["trace[1775201494] 'range keys from in-memory index tree' (duration: 253.468408ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:40.993319Z","caller":"traceutil/trace.go:171","msg":"trace[359803730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1058; }","duration":"130.645977ms","start":"2026-07-24T04:42:40.86266Z","end":"2026-07-24T04:42:40.993306Z","steps":["trace[359803730] 'range keys from in-memory index tree' (duration: 130.449122ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:42.464121Z","caller":"traceutil/trace.go:171","msg":"trace[1506441699] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1102; }","duration":"131.37439ms","start":"2026-07-24T04:42:42.332722Z","end":"2026-07-24T04:42:42.464096Z","steps":["trace[1506441699] 'read index received' (duration: 131.136023ms)","trace[1506441699] 'applied index is now lower than readState.Index' (duration: 237.287µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:42:42.464277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.539716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:42:42.464318Z","caller":"traceutil/trace.go:171","msg":"trace[298552973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1064; }","duration":"131.634689ms","start":"2026-07-24T04:42:42.33267Z","end":"2026-07-24T04:42:42.464305Z","steps":["trace[298552973] 'agreement among raft nodes before linearized reading' (duration: 131.530126ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:42.464521Z","caller":"traceutil/trace.go:171","msg":"trace[1518669182] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"243.425949ms","start":"2026-07-24T04:42:42.221073Z","end":"2026-07-24T04:42:42.464498Z","steps":["trace[1518669182] 'process raft request' (duration: 242.879052ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:42.816728Z","caller":"traceutil/trace.go:171","msg":"trace[1574671648] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"190.70198ms","start":"2026-07-24T04:42:42.625995Z","end":"2026-07-24T04:42:42.816697Z","steps":["trace[1574671648] 'process raft request' (duration: 122.363225ms)","trace[1574671648] 'compare' (duration: 68.007664ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:42:44.951666Z","caller":"traceutil/trace.go:171","msg":"trace[602875288] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"130.733121ms","start":"2026-07-24T04:42:44.820903Z","end":"2026-07-24T04:42:44.951636Z","steps":["trace[602875288] 'process raft request' (duration: 130.434211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:45.183362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.409936ms","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-24T04:42:45.18509Z","caller":"traceutil/trace.go:171","msg":"trace[1242123516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1071; }","duration":"159.216083ms","start":"2026-07-24T04:42:45.025854Z","end":"2026-07-24T04:42:45.185071Z","steps":["trace[1242123516] 'range keys from in-memory index tree' (duration: 156.61676ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:01.168709Z","caller":"traceutil/trace.go:171","msg":"trace[1310306359] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"150.081213ms","start":"2026-07-24T04:43:01.018604Z","end":"2026-07-24T04:43:01.168685Z","steps":["trace[1310306359] 'process raft request' (duration: 149.938998ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:13.491444Z","caller":"traceutil/trace.go:171","msg":"trace[776778840] linearizableReadLoop","detail":"{readStateIndex:1264; appliedIndex:1263; }","duration":"127.384575ms","start":"2026-07-24T04:43:13.363952Z","end":"2026-07-24T04:43:13.491337Z","steps":["trace[776778840] 'read index received' (duration: 37.26136ms)","trace[776778840] 'applied index is now lower than readState.Index' (duration: 90.121935ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:43:13.49172Z","caller":"traceutil/trace.go:171","msg":"trace[1072573765] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"154.737011ms","start":"2026-07-24T04:43:13.336958Z","end":"2026-07-24T04:43:13.491695Z","steps":["trace[1072573765] 'process raft request' (duration: 64.374059ms)","trace[1072573765] 'compare' (duration: 89.689871ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:43:13.491823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.841359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T04:43:13.492321Z","caller":"traceutil/trace.go:171","msg":"trace[1067907481] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1217; }","duration":"128.353395ms","start":"2026-07-24T04:43:13.363948Z","end":"2026-07-24T04:43:13.492301Z","steps":["trace[1067907481] 'agreement among raft nodes before linearized reading' (duration: 127.794137ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:17.670677Z","caller":"traceutil/trace.go:171","msg":"trace[310845035] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1295; }","duration":"341.508115ms","start":"2026-07-24T04:43:17.32915Z","end":"2026-07-24T04:43:17.670658Z","steps":["trace[310845035] 'read index received' (duration: 341.3248ms)","trace[310845035] 'applied index is now lower than readState.Index' (duration: 182.385µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:43:17.670722Z","caller":"traceutil/trace.go:171","msg":"trace[2007059321] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"367.739367ms","start":"2026-07-24T04:43:17.302962Z","end":"2026-07-24T04:43:17.670701Z","steps":["trace[2007059321] 'process raft request' (duration: 367.559141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:17.670819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.660491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:43:17.670848Z","caller":"traceutil/trace.go:171","msg":"trace[1899494759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1248; }","duration":"341.698822ms","start":"2026-07-24T04:43:17.329141Z","end":"2026-07-24T04:43:17.67084Z","steps":["trace[1899494759] 'agreement among raft nodes before linearized reading' (duration: 341.62865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:17.670848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:17.302946Z","time spent":"367.825079ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:43:17.670873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:17.329109Z","time spent":"341.758274ms","remote":"127.0.0.1:41732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T04:43:17.670947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.820567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-24T04:43:17.670994Z","caller":"traceutil/trace.go:171","msg":"trace[900995029] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:1248; }","duration":"150.92097ms","start":"2026-07-24T04:43:17.520061Z","end":"2026-07-24T04:43:17.670982Z","steps":["trace[900995029] 'agreement among raft nodes before linearized reading' (duration: 150.799116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:17.671219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.793494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:43:17.671235Z","caller":"traceutil/trace.go:171","msg":"trace[1457615409] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1248; }","duration":"119.829645ms","start":"2026-07-24T04:43:17.5514Z","end":"2026-07-24T04:43:17.67123Z","steps":["trace[1457615409] 'agreement among raft nodes before linearized reading' (duration: 119.798624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:18.637832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.965973ms","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-24T04:43:18.637968Z","caller":"traceutil/trace.go:171","msg":"trace[1672577801] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1248; }","duration":"379.115347ms","start":"2026-07-24T04:43:18.258835Z","end":"2026-07-24T04:43:18.63795Z","steps":["trace[1672577801] 'range keys from in-memory index tree' (duration: 378.951042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:18.638978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.697254ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-24T04:43:18.639091Z","caller":"traceutil/trace.go:171","msg":"trace[1653561578] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1296; }","duration":"966.028175ms","start":"2026-07-24T04:43:17.673039Z","end":"2026-07-24T04:43:18.639067Z","steps":["trace[1653561578] 'read index received' (duration: 221.509535ms)","trace[1653561578] 'applied index is now lower than readState.Index' (duration: 744.51666ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:43:18.639204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.145188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:43:18.639245Z","caller":"traceutil/trace.go:171","msg":"trace[1296960870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1248; }","duration":"966.207541ms","start":"2026-07-24T04:43:17.673022Z","end":"2026-07-24T04:43:18.639229Z","steps":["trace[1296960870] 'agreement among raft nodes before linearized reading' (duration: 966.104837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:18.639285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:17.67301Z","time spent":"966.264602ms","remote":"127.0.0.1:41728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T04:43:18.639492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:17.672941Z","time spent":"966.542041ms","remote":"127.0.0.1:41772","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-24T04:43:18.640763Z","caller":"traceutil/trace.go:171","msg":"trace[600093767] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"959.793918ms","start":"2026-07-24T04:43:17.68095Z","end":"2026-07-24T04:43:18.640744Z","steps":["trace[600093767] 'process raft request' (duration: 959.753906ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:18.640871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:17.68094Z","time spent":"959.863859ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:43:18.640976Z","caller":"traceutil/trace.go:171","msg":"trace[1197440992] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"960.052715ms","start":"2026-07-24T04:43:17.680865Z","end":"2026-07-24T04:43:18.640917Z","steps":["trace[1197440992] 'process raft request' (duration: 959.735985ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:18.641142Z","caller":"traceutil/trace.go:171","msg":"trace[1689021328] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"960.231321ms","start":"2026-07-24T04:43:17.680895Z","end":"2026-07-24T04:43:18.641127Z","steps":["trace[1689021328] 'process raft request' (duration: 959.747405ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:18.641226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:17.680847Z","time spent":"960.245682ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:43:18.641252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:17.680891Z","time spent":"960.296694ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:43:18.641418Z","caller":"traceutil/trace.go:171","msg":"trace[1534688154] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"960.50381ms","start":"2026-07-24T04:43:17.680904Z","end":"2026-07-24T04:43:18.641407Z","steps":["trace[1534688154] 'process raft request' (duration: 959.768706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:18.641488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:17.680882Z","time spent":"960.569962ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:43:18.641792Z","caller":"traceutil/trace.go:171","msg":"trace[958652773] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"960.930233ms","start":"2026-07-24T04:43:17.680851Z","end":"2026-07-24T04:43:18.641781Z","steps":["trace[958652773] 'process raft request' (duration: 959.625712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:18.64185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:17.680837Z","time spent":"960.988865ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:43:19.827944Z","caller":"traceutil/trace.go:171","msg":"trace[1878545156] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1304; }","duration":"312.869408ms","start":"2026-07-24T04:43:19.515053Z","end":"2026-07-24T04:43:19.827923Z","steps":["trace[1878545156] 'read index received' (duration: 312.746374ms)","trace[1878545156] 'applied index is now lower than readState.Index' (duration: 122.084µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:43:19.827992Z","caller":"traceutil/trace.go:171","msg":"trace[290678460] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"387.316026ms","start":"2026-07-24T04:43:19.440641Z","end":"2026-07-24T04:43:19.827957Z","steps":["trace[290678460] 'process raft request' (duration: 387.12439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:19.828104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.036304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T04:43:19.828146Z","caller":"traceutil/trace.go:171","msg":"trace[1186831324] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1256; }","duration":"313.129217ms","start":"2026-07-24T04:43:19.515006Z","end":"2026-07-24T04:43:19.828135Z","steps":["trace[1186831324] 'agreement among raft nodes before linearized reading' (duration: 313.021164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:19.828157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:19.440614Z","time spent":"387.451861ms","remote":"127.0.0.1:41828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:43:19.828191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:19.514986Z","time spent":"313.192689ms","remote":"127.0.0.1:41954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:43:20.328106Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605830204685684446,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-24T04:43:20.61674Z","caller":"traceutil/trace.go:171","msg":"trace[1521054555] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"942.445708ms","start":"2026-07-24T04:43:19.674269Z","end":"2026-07-24T04:43:20.616714Z","steps":["trace[1521054555] 'process raft request' (duration: 942.399806ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:20.616799Z","caller":"traceutil/trace.go:171","msg":"trace[1472781464] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"966.956374ms","start":"2026-07-24T04:43:19.649821Z","end":"2026-07-24T04:43:20.616777Z","steps":["trace[1472781464] 'process raft request' (duration: 966.694076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:20.616891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:19.674255Z","time spent":"942.560141ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:43:20.616941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:19.649794Z","time spent":"967.095238ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:43:20.726427Z","caller":"traceutil/trace.go:171","msg":"trace[975269727] linearizableReadLoop","detail":"{readStateIndex:1308; appliedIndex:1305; }","duration":"898.402043ms","start":"2026-07-24T04:43:19.828002Z","end":"2026-07-24T04:43:20.726404Z","steps":["trace[975269727] 'read index received' (duration: 788.601895ms)","trace[975269727] 'applied index is now lower than readState.Index' (duration: 109.799138ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:43:20.726446Z","caller":"traceutil/trace.go:171","msg":"trace[268247850] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"516.107145ms","start":"2026-07-24T04:43:20.210314Z","end":"2026-07-24T04:43:20.726422Z","steps":["trace[268247850] 'process raft request' (duration: 496.519845ms)","trace[268247850] 'compare' (duration: 19.447756ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:43:20.726604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209092743s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:43:20.726643Z","caller":"traceutil/trace.go:171","msg":"trace[1551525455] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1259; }","duration":"1.209214597s","start":"2026-07-24T04:43:19.517419Z","end":"2026-07-24T04:43:20.726633Z","steps":["trace[1551525455] 'agreement among raft nodes before linearized reading' (duration: 1.209101633s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:20.726676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:19.517408Z","time spent":"1.209258748s","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-24T04:43:20.726763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:20.210292Z","time spent":"516.215329ms","remote":"127.0.0.1:41828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:43:20.726908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.798469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:43:20.726945Z","caller":"traceutil/trace.go:171","msg":"trace[946946042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1259; }","duration":"397.86792ms","start":"2026-07-24T04:43:20.329064Z","end":"2026-07-24T04:43:20.726932Z","steps":["trace[946946042] 'agreement among raft nodes before linearized reading' (duration: 397.820409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:20.726971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:20.32904Z","time spent":"397.925683ms","remote":"127.0.0.1:41732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T04:43:20.727329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.619506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:43:20.727364Z","caller":"traceutil/trace.go:171","msg":"trace[194760084] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1259; }","duration":"183.696758ms","start":"2026-07-24T04:43:20.543658Z","end":"2026-07-24T04:43:20.727355Z","steps":["trace[194760084] 'agreement among raft nodes before linearized reading' (duration: 183.646956ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:20.872595Z","caller":"traceutil/trace.go:171","msg":"trace[31522556] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"140.026205ms","start":"2026-07-24T04:43:20.732545Z","end":"2026-07-24T04:43:20.872571Z","steps":["trace[31522556] 'process raft request' (duration: 139.952883ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:20.87258Z","caller":"traceutil/trace.go:171","msg":"trace[1959029223] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"140.143288ms","start":"2026-07-24T04:43:20.732402Z","end":"2026-07-24T04:43:20.872545Z","steps":["trace[1959029223] 'process raft request' (duration: 139.990563ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:20.872798Z","caller":"traceutil/trace.go:171","msg":"trace[742542712] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"140.331105ms","start":"2026-07-24T04:43:20.732451Z","end":"2026-07-24T04:43:20.872782Z","steps":["trace[742542712] 'process raft request' (duration: 139.996484ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:20.872801Z","caller":"traceutil/trace.go:171","msg":"trace[2032107710] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"140.473889ms","start":"2026-07-24T04:43:20.732283Z","end":"2026-07-24T04:43:20.872757Z","steps":["trace[2032107710] 'process raft request' (duration: 107.096972ms)","trace[2032107710] 'compare' (duration: 32.866581ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:43:20.872989Z","caller":"traceutil/trace.go:171","msg":"trace[1236295936] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"140.419638ms","start":"2026-07-24T04:43:20.732551Z","end":"2026-07-24T04:43:20.872971Z","steps":["trace[1236295936] 'process raft request' (duration: 139.987274ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:20.873106Z","caller":"traceutil/trace.go:171","msg":"trace[154208624] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"140.485899ms","start":"2026-07-24T04:43:20.732607Z","end":"2026-07-24T04:43:20.873093Z","steps":["trace[154208624] 'process raft request' (duration: 140.276323ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:31.33871Z","caller":"traceutil/trace.go:171","msg":"trace[398842502] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"416.939875ms","start":"2026-07-24T04:43:30.921743Z","end":"2026-07-24T04:43:31.338683Z","steps":["trace[398842502] 'process raft request' (duration: 416.829131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:31.338871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:30.92172Z","time spent":"417.065619ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:43:31.339095Z","caller":"traceutil/trace.go:171","msg":"trace[2013238162] linearizableReadLoop","detail":"{readStateIndex:1386; appliedIndex:1385; }","duration":"419.32307ms","start":"2026-07-24T04:43:30.919746Z","end":"2026-07-24T04:43:31.339069Z","steps":["trace[2013238162] 'read index received' (duration: 415.502959ms)","trace[2013238162] 'applied index is now lower than readState.Index' (duration: 3.817951ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:43:31.339274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.069229ms","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-24T04:43:31.339297Z","caller":"traceutil/trace.go:171","msg":"trace[1909647188] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1336; }","duration":"417.148292ms","start":"2026-07-24T04:43:30.922142Z","end":"2026-07-24T04:43:31.33929Z","steps":["trace[1909647188] 'agreement among raft nodes before linearized reading' (duration: 417.038288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:31.339321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:30.922125Z","time spent":"417.188403ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-24T04:43:31.339321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.536397ms","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-24T04:43:31.339387Z","caller":"traceutil/trace.go:171","msg":"trace[940854859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1336; }","duration":"419.650331ms","start":"2026-07-24T04:43:30.919721Z","end":"2026-07-24T04:43:31.339371Z","steps":["trace[940854859] 'agreement among raft nodes before linearized reading' (duration: 419.431744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:31.339431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:30.91971Z","time spent":"419.710912ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T04:43:31.339524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.64675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-24T04:43:31.339546Z","caller":"traceutil/trace.go:171","msg":"trace[1199928034] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1336; }","duration":"365.700052ms","start":"2026-07-24T04:43:30.973839Z","end":"2026-07-24T04:43:31.339539Z","steps":["trace[1199928034] 'agreement among raft nodes before linearized reading' (duration: 365.63002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:31.339572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:30.973822Z","time spent":"365.744064ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-24T04:43:31.339672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.699407ms","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-24T04:43:31.339689Z","caller":"traceutil/trace.go:171","msg":"trace[840968320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1336; }","duration":"416.728768ms","start":"2026-07-24T04:43:30.922954Z","end":"2026-07-24T04:43:31.339683Z","steps":["trace[840968320] 'agreement among raft nodes before linearized reading' (duration: 416.669957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:31.339705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:30.922946Z","time spent":"416.754259ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-24T04:43:31.339697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.715073ms","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:524"} {"level":"info","ts":"2026-07-24T04:43:31.339781Z","caller":"traceutil/trace.go:171","msg":"trace[1759193236] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1336; }","duration":"258.829788ms","start":"2026-07-24T04:43:31.080924Z","end":"2026-07-24T04:43:31.339754Z","steps":["trace[1759193236] 'agreement among raft nodes before linearized reading' (duration: 258.394354ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:31.338899Z","caller":"traceutil/trace.go:171","msg":"trace[1636638772] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"420.373504ms","start":"2026-07-24T04:43:30.918504Z","end":"2026-07-24T04:43:31.338877Z","steps":["trace[1636638772] 'process raft request' (duration: 416.732358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:31.339986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:30.918476Z","time spent":"421.449587ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:43:32.238553Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605830204685684596,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-24T04:43:32.28188Z","caller":"traceutil/trace.go:171","msg":"trace[1751912963] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"937.283694ms","start":"2026-07-24T04:43:31.344577Z","end":"2026-07-24T04:43:32.281861Z","steps":["trace[1751912963] 'process raft request' (duration: 937.233882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:32.282016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:31.344571Z","time spent":"937.376158ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:43:32.282281Z","caller":"traceutil/trace.go:171","msg":"trace[62535501] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"937.879543ms","start":"2026-07-24T04:43:31.344393Z","end":"2026-07-24T04:43:32.282273Z","steps":["trace[62535501] 'process raft request' (duration: 937.302355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:32.282327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:31.344372Z","time spent":"937.931865ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:43:32.282445Z","caller":"traceutil/trace.go:171","msg":"trace[755160469] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"937.231213ms","start":"2026-07-24T04:43:31.345162Z","end":"2026-07-24T04:43:32.282393Z","steps":["trace[755160469] 'process raft request' (duration: 936.676865ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:32.28265Z","caller":"traceutil/trace.go:171","msg":"trace[1468160498] linearizableReadLoop","detail":"{readStateIndex:1392; appliedIndex:1388; }","duration":"544.453174ms","start":"2026-07-24T04:43:31.738188Z","end":"2026-07-24T04:43:32.282641Z","steps":["trace[1468160498] 'read index received' (duration: 543.592286ms)","trace[1468160498] 'applied index is now lower than readState.Index' (duration: 860.158µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:43:32.282648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:31.345154Z","time spent":"937.388487ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:43:32.282792Z","caller":"traceutil/trace.go:171","msg":"trace[750161520] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"936.773628ms","start":"2026-07-24T04:43:31.34601Z","end":"2026-07-24T04:43:32.282784Z","steps":["trace[750161520] 'process raft request' (duration: 936.271082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:32.282846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:31.346002Z","time spent":"936.819509ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:43:32.282946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.765163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:43:32.28298Z","caller":"traceutil/trace.go:171","msg":"trace[1373496741] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1341; }","duration":"544.839266ms","start":"2026-07-24T04:43:31.738134Z","end":"2026-07-24T04:43:32.282973Z","steps":["trace[1373496741] 'agreement among raft nodes before linearized reading' (duration: 544.788955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:32.283003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:31.738113Z","time spent":"544.884647ms","remote":"127.0.0.1:59804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-07-24T04:43:42.444719Z","caller":"traceutil/trace.go:171","msg":"trace[1736303083] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"110.171744ms","start":"2026-07-24T04:43:42.334519Z","end":"2026-07-24T04:43:42.444691Z","steps":["trace[1736303083] 'process raft request' (duration: 100.200987ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:42.447944Z","caller":"traceutil/trace.go:171","msg":"trace[501585568] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"112.886121ms","start":"2026-07-24T04:43:42.335032Z","end":"2026-07-24T04:43:42.447919Z","steps":["trace[501585568] 'process raft request' (duration: 112.138237ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:42.448172Z","caller":"traceutil/trace.go:171","msg":"trace[494026585] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"113.059817ms","start":"2026-07-24T04:43:42.335099Z","end":"2026-07-24T04:43:42.448159Z","steps":["trace[494026585] 'process raft request' (duration: 112.363185ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:44:19.77558Z","caller":"traceutil/trace.go:171","msg":"trace[1474549720] transaction","detail":"{read_only:false; response_revision:1558; number_of_response:1; }","duration":"155.572738ms","start":"2026-07-24T04:44:19.619986Z","end":"2026-07-24T04:44:19.775558Z","steps":["trace[1474549720] 'process raft request' (duration: 89.028231ms)","trace[1474549720] 'compare' (duration: 66.453914ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:44:50.933713Z","caller":"traceutil/trace.go:171","msg":"trace[400881502] transaction","detail":"{read_only:false; response_revision:1699; number_of_response:1; }","duration":"107.949689ms","start":"2026-07-24T04:44:50.82573Z","end":"2026-07-24T04:44:50.93368Z","steps":["trace[400881502] 'process raft request' (duration: 107.734472ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:44:51.126341Z","caller":"traceutil/trace.go:171","msg":"trace[436980769] transaction","detail":"{read_only:false; response_revision:1702; number_of_response:1; }","duration":"176.182873ms","start":"2026-07-24T04:44:50.950133Z","end":"2026-07-24T04:44:51.126316Z","steps":["trace[436980769] 'process raft request' (duration: 176.136961ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:44:51.126358Z","caller":"traceutil/trace.go:171","msg":"trace[1377583571] transaction","detail":"{read_only:false; response_revision:1701; number_of_response:1; }","duration":"187.581073ms","start":"2026-07-24T04:44:50.938751Z","end":"2026-07-24T04:44:51.126332Z","steps":["trace[1377583571] 'process raft request' (duration: 187.345126ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:44:55.203659Z","caller":"traceutil/trace.go:171","msg":"trace[1999461071] transaction","detail":"{read_only:false; response_revision:1717; number_of_response:1; }","duration":"202.539825ms","start":"2026-07-24T04:44:55.001094Z","end":"2026-07-24T04:44:55.203633Z","steps":["trace[1999461071] 'process raft request' (duration: 202.349689ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:44:55.219287Z","caller":"traceutil/trace.go:171","msg":"trace[911256054] transaction","detail":"{read_only:false; response_revision:1719; number_of_response:1; }","duration":"216.769584ms","start":"2026-07-24T04:44:55.002473Z","end":"2026-07-24T04:44:55.219243Z","steps":["trace[911256054] 'process raft request' (duration: 216.448104ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:44:55.219483Z","caller":"traceutil/trace.go:171","msg":"trace[1559214008] transaction","detail":"{read_only:false; response_revision:1718; number_of_response:1; }","duration":"217.035222ms","start":"2026-07-24T04:44:55.002436Z","end":"2026-07-24T04:44:55.219471Z","steps":["trace[1559214008] 'process raft request' (duration: 216.364161ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:44:55.219463Z","caller":"traceutil/trace.go:171","msg":"trace[969420098] transaction","detail":"{read_only:false; response_revision:1720; number_of_response:1; }","duration":"176.496883ms","start":"2026-07-24T04:44:55.042943Z","end":"2026-07-24T04:44:55.21944Z","steps":["trace[969420098] 'process raft request' (duration: 176.048569ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:07.495106Z","caller":"traceutil/trace.go:171","msg":"trace[469577806] linearizableReadLoop","detail":"{readStateIndex:1843; appliedIndex:1842; }","duration":"215.581247ms","start":"2026-07-24T04:45:07.279492Z","end":"2026-07-24T04:45:07.495073Z","steps":["trace[469577806] 'read index received' (duration: 215.376601ms)","trace[469577806] 'applied index is now lower than readState.Index' (duration: 203.356µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:45:07.495343Z","caller":"traceutil/trace.go:171","msg":"trace[738169821] transaction","detail":"{read_only:false; response_revision:1773; number_of_response:1; }","duration":"236.952671ms","start":"2026-07-24T04:45:07.258287Z","end":"2026-07-24T04:45:07.49524Z","steps":["trace[738169821] 'process raft request' (duration: 236.616311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:07.495434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.894747ms","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-24T04:45:07.495506Z","caller":"traceutil/trace.go:171","msg":"trace[826738559] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1773; }","duration":"216.040961ms","start":"2026-07-24T04:45:07.279448Z","end":"2026-07-24T04:45:07.495489Z","steps":["trace[826738559] 'agreement among raft nodes before linearized reading' (duration: 215.790773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:07.495582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.264859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-24T04:45:07.495637Z","caller":"traceutil/trace.go:171","msg":"trace[414454345] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1773; }","duration":"210.340231ms","start":"2026-07-24T04:45:07.285281Z","end":"2026-07-24T04:45:07.495621Z","steps":["trace[414454345] 'agreement among raft nodes before linearized reading' (duration: 210.219817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:07.495443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.62376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-24T04:45:07.496203Z","caller":"traceutil/trace.go:171","msg":"trace[142763996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1773; }","duration":"211.417395ms","start":"2026-07-24T04:45:07.284773Z","end":"2026-07-24T04:45:07.496191Z","steps":["trace[142763996] 'agreement among raft nodes before linearized reading' (duration: 210.60691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:07.495744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.601065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:45:07.49637Z","caller":"traceutil/trace.go:171","msg":"trace[1859709316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1773; }","duration":"164.3455ms","start":"2026-07-24T04:45:07.332012Z","end":"2026-07-24T04:45:07.496357Z","steps":["trace[1859709316] 'agreement among raft nodes before linearized reading' (duration: 163.447951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:08.001111Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605830204685685669,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-24T04:45:08.050191Z","caller":"traceutil/trace.go:171","msg":"trace[2127565165] transaction","detail":"{read_only:false; response_revision:1774; number_of_response:1; }","duration":"550.090379ms","start":"2026-07-24T04:45:07.5Z","end":"2026-07-24T04:45:08.05009Z","steps":["trace[2127565165] 'process raft request' (duration: 549.878352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:08.050379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:07.49998Z","time spent":"550.305005ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:45:08.456714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.490585ms","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-24T04:45:08.456996Z","caller":"traceutil/trace.go:171","msg":"trace[1507779301] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1774; }","duration":"203.781534ms","start":"2026-07-24T04:45:08.253189Z","end":"2026-07-24T04:45:08.45697Z","steps":["trace[1507779301] 'range keys from in-memory index tree' (duration: 203.470944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:08.45683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.066116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:45:08.457522Z","caller":"traceutil/trace.go:171","msg":"trace[2060764639] linearizableReadLoop","detail":"{readStateIndex:1846; appliedIndex:1844; }","duration":"957.43843ms","start":"2026-07-24T04:45:07.500064Z","end":"2026-07-24T04:45:08.457502Z","steps":["trace[2060764639] 'read index received' (duration: 550.090549ms)","trace[2060764639] 'applied index is now lower than readState.Index' (duration: 407.346691ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:45:08.457532Z","caller":"traceutil/trace.go:171","msg":"trace[2122310812] transaction","detail":"{read_only:false; response_revision:1775; number_of_response:1; }","duration":"956.852081ms","start":"2026-07-24T04:45:07.500648Z","end":"2026-07-24T04:45:08.4575Z","steps":["trace[2122310812] 'process raft request' (duration: 642.039291ms)","trace[2122310812] 'compare' (duration: 313.773777ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:45:08.457772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.695608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-07-24T04:45:08.457779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.137852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-24T04:45:08.457588Z","caller":"traceutil/trace.go:171","msg":"trace[399753061] transaction","detail":"{read_only:false; response_revision:1776; number_of_response:1; }","duration":"956.260512ms","start":"2026-07-24T04:45:07.501293Z","end":"2026-07-24T04:45:08.457553Z","steps":["trace[399753061] 'process raft request' (duration: 956.075597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:08.457736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:07.500637Z","time spent":"956.993456ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:45:08.457834Z","caller":"traceutil/trace.go:171","msg":"trace[188363265] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1776; }","duration":"957.787151ms","start":"2026-07-24T04:45:07.500031Z","end":"2026-07-24T04:45:08.457818Z","steps":["trace[188363265] 'agreement among raft nodes before linearized reading' (duration: 957.593415ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:08.457851Z","caller":"traceutil/trace.go:171","msg":"trace[521640009] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:1776; }","duration":"933.224055ms","start":"2026-07-24T04:45:07.524601Z","end":"2026-07-24T04:45:08.457825Z","steps":["trace[521640009] 'agreement among raft nodes before linearized reading' (duration: 933.06122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:08.457883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:07.500018Z","time spent":"957.852082ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-24T04:45:08.457893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:07.524585Z","time spent":"933.300527ms","remote":"127.0.0.1:41772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.169\" "} {"level":"warn","ts":"2026-07-24T04:45:08.457908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.897433ms","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-24T04:45:08.457974Z","caller":"traceutil/trace.go:171","msg":"trace[900216744] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1776; }","duration":"506.001956ms","start":"2026-07-24T04:45:07.951956Z","end":"2026-07-24T04:45:08.457958Z","steps":["trace[900216744] 'agreement among raft nodes before linearized reading' (duration: 505.8123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:08.458021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:07.951931Z","time spent":"506.077689ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-24T04:45:08.458128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.086157ms","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-24T04:45:08.458255Z","caller":"traceutil/trace.go:171","msg":"trace[1350365793] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1776; }","duration":"352.417257ms","start":"2026-07-24T04:45:08.10582Z","end":"2026-07-24T04:45:08.458237Z","steps":["trace[1350365793] 'agreement among raft nodes before linearized reading' (duration: 351.87621ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:08.458392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:08.1058Z","time spent":"352.573531ms","remote":"127.0.0.1:42036","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-24T04:45:08.458316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:07.501261Z","time spent":"956.579823ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:45:08.617088Z","caller":"traceutil/trace.go:171","msg":"trace[1756045795] transaction","detail":"{read_only:false; response_revision:1778; number_of_response:1; }","duration":"154.089856ms","start":"2026-07-24T04:45:08.462948Z","end":"2026-07-24T04:45:08.617038Z","steps":["trace[1756045795] 'process raft request' (duration: 153.90781ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:08.61721Z","caller":"traceutil/trace.go:171","msg":"trace[212212670] transaction","detail":"{read_only:false; response_revision:1777; number_of_response:1; }","duration":"154.513099ms","start":"2026-07-24T04:45:08.462593Z","end":"2026-07-24T04:45:08.617106Z","steps":["trace[212212670] 'process raft request' (duration: 117.394037ms)","trace[212212670] 'compare' (duration: 36.644437ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:45:08.617407Z","caller":"traceutil/trace.go:171","msg":"trace[951673127] transaction","detail":"{read_only:false; response_revision:1779; number_of_response:1; }","duration":"154.366014ms","start":"2026-07-24T04:45:08.463015Z","end":"2026-07-24T04:45:08.617381Z","steps":["trace[951673127] 'process raft request' (duration: 153.979681ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:08.617824Z","caller":"traceutil/trace.go:171","msg":"trace[430492264] transaction","detail":"{read_only:false; response_revision:1780; number_of_response:1; }","duration":"124.648836ms","start":"2026-07-24T04:45:08.493152Z","end":"2026-07-24T04:45:08.617801Z","steps":["trace[430492264] 'process raft request' (duration: 124.243473ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:14.656746Z","caller":"traceutil/trace.go:171","msg":"trace[1433502589] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"166.175767ms","start":"2026-07-24T04:45:14.490546Z","end":"2026-07-24T04:45:14.656722Z","steps":["trace[1433502589] 'process raft request' (duration: 164.659449ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:16.88802Z","caller":"traceutil/trace.go:171","msg":"trace[190777923] linearizableReadLoop","detail":"{readStateIndex:1885; appliedIndex:1884; }","duration":"226.870202ms","start":"2026-07-24T04:45:16.661127Z","end":"2026-07-24T04:45:16.887997Z","steps":["trace[190777923] 'read index received' (duration: 226.632955ms)","trace[190777923] 'applied index is now lower than readState.Index' (duration: 236.367µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:45:16.888045Z","caller":"traceutil/trace.go:171","msg":"trace[547672793] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"331.492186ms","start":"2026-07-24T04:45:16.556522Z","end":"2026-07-24T04:45:16.888014Z","steps":["trace[547672793] 'process raft request' (duration: 331.334731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:16.88849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.865967ms","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-24T04:45:16.888539Z","caller":"traceutil/trace.go:171","msg":"trace[955624388] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1813; }","duration":"133.917448ms","start":"2026-07-24T04:45:16.754607Z","end":"2026-07-24T04:45:16.888525Z","steps":["trace[955624388] 'agreement among raft nodes before linearized reading' (duration: 133.746153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:16.888364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.216644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-07-24T04:45:16.888381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.906015ms","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:525"} {"level":"info","ts":"2026-07-24T04:45:16.888681Z","caller":"traceutil/trace.go:171","msg":"trace[1596116273] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1813; }","duration":"227.541164ms","start":"2026-07-24T04:45:16.661082Z","end":"2026-07-24T04:45:16.888624Z","steps":["trace[1596116273] 'agreement among raft nodes before linearized reading' (duration: 227.014388ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:16.888739Z","caller":"traceutil/trace.go:171","msg":"trace[2024961994] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1813; }","duration":"183.320468ms","start":"2026-07-24T04:45:16.705404Z","end":"2026-07-24T04:45:16.888724Z","steps":["trace[2024961994] 'agreement among raft nodes before linearized reading' (duration: 182.72267ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:16.888557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:45:16.556503Z","time spent":"331.985402ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:45:17.007823Z","caller":"traceutil/trace.go:171","msg":"trace[1970601101] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"114.758573ms","start":"2026-07-24T04:45:16.893017Z","end":"2026-07-24T04:45:17.007776Z","steps":["trace[1970601101] 'process raft request' (duration: 88.232156ms)","trace[1970601101] 'compare' (duration: 26.392843ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:45:17.040964Z","caller":"traceutil/trace.go:171","msg":"trace[189189523] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"147.153906ms","start":"2026-07-24T04:45:16.893737Z","end":"2026-07-24T04:45:17.040891Z","steps":["trace[189189523] 'process raft request' (duration: 147.109955ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:17.041019Z","caller":"traceutil/trace.go:171","msg":"trace[601340832] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"147.90128ms","start":"2026-07-24T04:45:16.893088Z","end":"2026-07-24T04:45:17.04099Z","steps":["trace[601340832] 'process raft request' (duration: 147.56944ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:17.041161Z","caller":"traceutil/trace.go:171","msg":"trace[33899139] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"147.641661ms","start":"2026-07-24T04:45:16.893476Z","end":"2026-07-24T04:45:17.041117Z","steps":["trace[33899139] 'process raft request' (duration: 147.331091ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:17.694414Z","caller":"traceutil/trace.go:171","msg":"trace[926567879] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"150.888763ms","start":"2026-07-24T04:45:17.543495Z","end":"2026-07-24T04:45:17.694384Z","steps":["trace[926567879] 'process raft request' (duration: 62.249825ms)","trace[926567879] 'compare' (duration: 88.465653ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:45:17.812416Z","caller":"traceutil/trace.go:171","msg":"trace[1931588079] linearizableReadLoop","detail":"{readStateIndex:1893; appliedIndex:1892; }","duration":"115.883529ms","start":"2026-07-24T04:45:17.696496Z","end":"2026-07-24T04:45:17.81238Z","steps":["trace[1931588079] 'read index received' (duration: 100.744671ms)","trace[1931588079] 'applied index is now lower than readState.Index' (duration: 15.137718ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:45:17.812559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.039134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-24T04:45:17.812594Z","caller":"traceutil/trace.go:171","msg":"trace[1495137086] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1820; }","duration":"116.124346ms","start":"2026-07-24T04:45:17.696458Z","end":"2026-07-24T04:45:17.812583Z","steps":["trace[1495137086] 'agreement among raft nodes before linearized reading' (duration: 116.029013ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:17.812736Z","caller":"traceutil/trace.go:171","msg":"trace[171524056] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"168.098347ms","start":"2026-07-24T04:45:17.644604Z","end":"2026-07-24T04:45:17.812702Z","steps":["trace[171524056] 'process raft request' (duration: 152.581647ms)","trace[171524056] 'compare' (duration: 14.86388ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:45:44.114632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.013598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:45:44.114778Z","caller":"traceutil/trace.go:171","msg":"trace[210620576] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"110.8478ms","start":"2026-07-24T04:45:44.003918Z","end":"2026-07-24T04:45:44.114766Z","steps":["trace[210620576] 'process raft request' (duration: 110.803418ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:44.114851Z","caller":"traceutil/trace.go:171","msg":"trace[1151954394] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"155.682075ms","start":"2026-07-24T04:45:43.95913Z","end":"2026-07-24T04:45:44.114812Z","steps":["trace[1151954394] 'process raft request' (duration: 36.415289ms)","trace[1151954394] 'compare' (duration: 118.902105ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:45:45.345249Z","caller":"traceutil/trace.go:171","msg":"trace[131136314] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"122.340123ms","start":"2026-07-24T04:45:45.222881Z","end":"2026-07-24T04:45:45.345221Z","steps":["trace[131136314] 'process raft request' (duration: 99.420689ms)","trace[131136314] 'compare' (duration: 22.719857ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:45:45.365822Z","caller":"traceutil/trace.go:171","msg":"trace[45915095] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"142.327244ms","start":"2026-07-24T04:45:45.223469Z","end":"2026-07-24T04:45:45.365796Z","steps":["trace[45915095] 'process raft request' (duration: 142.162088ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:48.819308Z","caller":"traceutil/trace.go:171","msg":"trace[1770153042] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"101.91598ms","start":"2026-07-24T04:45:48.717369Z","end":"2026-07-24T04:45:48.819285Z","steps":["trace[1770153042] 'process raft request' (duration: 101.753365ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:56.121063Z","caller":"traceutil/trace.go:171","msg":"trace[1521232975] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"105.125642ms","start":"2026-07-24T04:45:56.015911Z","end":"2026-07-24T04:45:56.121037Z","steps":["trace[1521232975] 'process raft request' (duration: 104.944096ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:59.751896Z","caller":"traceutil/trace.go:171","msg":"trace[1708874530] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"119.226448ms","start":"2026-07-24T04:45:59.632646Z","end":"2026-07-24T04:45:59.751872Z","steps":["trace[1708874530] 'process raft request' (duration: 109.850842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:46:01.551093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.716314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:46:01.551238Z","caller":"traceutil/trace.go:171","msg":"trace[2113215958] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"231.259567ms","start":"2026-07-24T04:46:01.319946Z","end":"2026-07-24T04:46:01.551206Z","steps":["trace[2113215958] 'process raft request' (duration: 123.184772ms)","trace[2113215958] 'compare' (duration: 107.534968ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:46:01.855864Z","caller":"traceutil/trace.go:171","msg":"trace[806337197] linearizableReadLoop","detail":"{readStateIndex:2100; appliedIndex:2099; }","duration":"191.794381ms","start":"2026-07-24T04:46:01.664047Z","end":"2026-07-24T04:46:01.855841Z","steps":["trace[806337197] 'read index received' (duration: 191.602834ms)","trace[806337197] 'applied index is now lower than readState.Index' (duration: 190.647µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:46:01.85594Z","caller":"traceutil/trace.go:171","msg":"trace[2006702094] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"299.7112ms","start":"2026-07-24T04:46:01.556189Z","end":"2026-07-24T04:46:01.8559Z","steps":["trace[2006702094] 'process raft request' (duration: 299.516134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:46:01.856151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.077479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T04:46:01.856262Z","caller":"traceutil/trace.go:171","msg":"trace[2079115736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2019; }","duration":"192.242894ms","start":"2026-07-24T04:46:01.664001Z","end":"2026-07-24T04:46:01.856244Z","steps":["trace[2079115736] 'agreement among raft nodes before linearized reading' (duration: 191.963075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:46:01.856403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.439145ms","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-24T04:46:01.856551Z","caller":"traceutil/trace.go:171","msg":"trace[257585078] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2019; }","duration":"101.624931ms","start":"2026-07-24T04:46:01.754909Z","end":"2026-07-24T04:46:01.856534Z","steps":["trace[257585078] 'agreement among raft nodes before linearized reading' (duration: 101.132946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:46:02.09425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.320261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:46:02.094444Z","caller":"traceutil/trace.go:171","msg":"trace[596736330] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"234.014624ms","start":"2026-07-24T04:46:01.860406Z","end":"2026-07-24T04:46:02.094421Z","steps":["trace[596736330] 'process raft request' (duration: 45.430275ms)","trace[596736330] 'compare' (duration: 188.166706ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:46:02.094452Z","caller":"traceutil/trace.go:171","msg":"trace[1331494357] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"231.390261ms","start":"2026-07-24T04:46:01.863032Z","end":"2026-07-24T04:46:02.094422Z","steps":["trace[1331494357] 'process raft request' (duration: 231.322879ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:46:06.224511Z","caller":"traceutil/trace.go:171","msg":"trace[796320186] transaction","detail":"{read_only:false; response_revision:2040; number_of_response:1; }","duration":"111.168543ms","start":"2026-07-24T04:46:06.113313Z","end":"2026-07-24T04:46:06.224482Z","steps":["trace[796320186] 'process raft request' (duration: 111.047359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:46:19.631807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.444915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-24T04:46:19.63189Z","caller":"traceutil/trace.go:171","msg":"trace[1119970243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2100; }","duration":"238.568079ms","start":"2026-07-24T04:46:19.393305Z","end":"2026-07-24T04:46:19.631873Z","steps":["trace[1119970243] 'range keys from in-memory index tree' (duration: 238.281219ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:46:19.832609Z","caller":"traceutil/trace.go:171","msg":"trace[1985384013] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"106.678701ms","start":"2026-07-24T04:46:19.725896Z","end":"2026-07-24T04:46:19.832574Z","steps":["trace[1985384013] 'process raft request' (duration: 29.932975ms)","trace[1985384013] 'compare' (duration: 76.58268ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:46:20.117811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.920877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:46:20.117888Z","caller":"traceutil/trace.go:171","msg":"trace[836898962] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2102; }","duration":"131.02099ms","start":"2026-07-24T04:46:19.98685Z","end":"2026-07-24T04:46:20.117871Z","steps":["trace[836898962] 'count revisions from in-memory index tree' (duration: 130.838474ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:46:20.471303Z","caller":"traceutil/trace.go:171","msg":"trace[2128747350] linearizableReadLoop","detail":"{readStateIndex:2189; appliedIndex:2188; }","duration":"138.519047ms","start":"2026-07-24T04:46:20.332766Z","end":"2026-07-24T04:46:20.471285Z","steps":["trace[2128747350] 'read index received' (duration: 138.459375ms)","trace[2128747350] 'applied index is now lower than readState.Index' (duration: 58.882µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:46:20.471359Z","caller":"traceutil/trace.go:171","msg":"trace[1372900014] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"178.584263ms","start":"2026-07-24T04:46:20.292753Z","end":"2026-07-24T04:46:20.471338Z","steps":["trace[1372900014] 'process raft request' (duration: 178.410258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:46:20.471514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.727164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:46:20.471686Z","caller":"traceutil/trace.go:171","msg":"trace[242070085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2104; }","duration":"138.901789ms","start":"2026-07-24T04:46:20.332741Z","end":"2026-07-24T04:46:20.471643Z","steps":["trace[242070085] 'agreement among raft nodes before linearized reading' (duration: 138.631271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:46:20.474592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.073049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T04:46:20.474682Z","caller":"traceutil/trace.go:171","msg":"trace[1657130501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2105; }","duration":"136.173813ms","start":"2026-07-24T04:46:20.338478Z","end":"2026-07-24T04:46:20.474652Z","steps":["trace[1657130501] 'agreement among raft nodes before linearized reading' (duration: 135.989377ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:46:34.423594Z","caller":"traceutil/trace.go:171","msg":"trace[1711861534] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"215.573032ms","start":"2026-07-24T04:46:34.207996Z","end":"2026-07-24T04:46:34.423569Z","steps":["trace[1711861534] 'process raft request' (duration: 214.995043ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:46:34.542447Z","caller":"traceutil/trace.go:171","msg":"trace[1350922016] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"114.220919ms","start":"2026-07-24T04:46:34.4282Z","end":"2026-07-24T04:46:34.542421Z","steps":["trace[1350922016] 'process raft request' (duration: 62.713171ms)","trace[1350922016] 'compare' (duration: 51.400705ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:46:35.253901Z","caller":"traceutil/trace.go:171","msg":"trace[1572611652] linearizableReadLoop","detail":"{readStateIndex:2259; appliedIndex:2258; }","duration":"136.061269ms","start":"2026-07-24T04:46:35.117815Z","end":"2026-07-24T04:46:35.253876Z","steps":["trace[1572611652] 'read index received' (duration: 135.932345ms)","trace[1572611652] 'applied index is now lower than readState.Index' (duration: 127.764µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:46:35.253973Z","caller":"traceutil/trace.go:171","msg":"trace[225968986] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"418.468253ms","start":"2026-07-24T04:46:34.835485Z","end":"2026-07-24T04:46:35.253953Z","steps":["trace[225968986] 'process raft request' (duration: 418.226616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:46:35.254113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.271655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T04:46:35.254214Z","caller":"traceutil/trace.go:171","msg":"trace[1388165409] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2171; }","duration":"136.433091ms","start":"2026-07-24T04:46:35.117746Z","end":"2026-07-24T04:46:35.254179Z","steps":["trace[1388165409] 'agreement among raft nodes before linearized reading' (duration: 136.251566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:46:35.254285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:46:34.835469Z","time spent":"418.647248ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:46:54.769785Z","caller":"traceutil/trace.go:171","msg":"trace[850118008] transaction","detail":"{read_only:false; response_revision:2259; number_of_response:1; }","duration":"136.113547ms","start":"2026-07-24T04:46:54.633641Z","end":"2026-07-24T04:46:54.769755Z","steps":["trace[850118008] 'process raft request' (duration: 38.237054ms)","trace[850118008] 'compare' (duration: 97.567333ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:47:09.981685Z","caller":"traceutil/trace.go:171","msg":"trace[451782649] transaction","detail":"{read_only:false; response_revision:2330; number_of_response:1; }","duration":"145.566295ms","start":"2026-07-24T04:47:09.836096Z","end":"2026-07-24T04:47:09.981663Z","steps":["trace[451782649] 'process raft request' (duration: 145.433371ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:47:10.543518Z","caller":"traceutil/trace.go:171","msg":"trace[1160246569] transaction","detail":"{read_only:false; response_revision:2332; number_of_response:1; }","duration":"104.520373ms","start":"2026-07-24T04:47:10.438968Z","end":"2026-07-24T04:47:10.543488Z","steps":["trace[1160246569] 'process raft request' (duration: 104.368038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:47:15.80992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.047391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-24T04:47:15.810009Z","caller":"traceutil/trace.go:171","msg":"trace[2099510707] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2355; }","duration":"126.177505ms","start":"2026-07-24T04:47:15.683808Z","end":"2026-07-24T04:47:15.809986Z","steps":["trace[2099510707] 'range keys from in-memory index tree' (duration: 125.868274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:47:33.176514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.2275ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-24T04:47:33.177067Z","caller":"traceutil/trace.go:171","msg":"trace[1140906414] transaction","detail":"{read_only:false; response_revision:2435; number_of_response:1; }","duration":"193.039461ms","start":"2026-07-24T04:47:32.984005Z","end":"2026-07-24T04:47:33.177044Z","steps":["trace[1140906414] 'process raft request' (duration: 192.629938ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:47:33.303422Z","caller":"traceutil/trace.go:171","msg":"trace[1559632492] transaction","detail":"{read_only:false; response_revision:2437; number_of_response:1; }","duration":"121.654402ms","start":"2026-07-24T04:47:33.18174Z","end":"2026-07-24T04:47:33.303394Z","steps":["trace[1559632492] 'process raft request' (duration: 120.927629ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:47:36.193313Z","caller":"traceutil/trace.go:171","msg":"trace[2001400757] transaction","detail":"{read_only:false; response_revision:2448; number_of_response:1; }","duration":"220.699351ms","start":"2026-07-24T04:47:35.972586Z","end":"2026-07-24T04:47:36.193286Z","steps":["trace[2001400757] 'process raft request' (duration: 220.514195ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:47:37.787635Z","caller":"traceutil/trace.go:171","msg":"trace[1926643584] linearizableReadLoop","detail":"{readStateIndex:2557; appliedIndex:2556; }","duration":"102.296132ms","start":"2026-07-24T04:47:37.685316Z","end":"2026-07-24T04:47:37.787612Z","steps":["trace[1926643584] 'read index received' (duration: 102.151157ms)","trace[1926643584] 'applied index is now lower than readState.Index' (duration: 144.045µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:47:37.78781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.484198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-24T04:47:37.787838Z","caller":"traceutil/trace.go:171","msg":"trace[875923319] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2456; }","duration":"102.566761ms","start":"2026-07-24T04:47:37.685264Z","end":"2026-07-24T04:47:37.78783Z","steps":["trace[875923319] 'agreement among raft nodes before linearized reading' (duration: 102.429696ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:47:51.829999Z","caller":"traceutil/trace.go:171","msg":"trace[1850040086] linearizableReadLoop","detail":"{readStateIndex:2644; appliedIndex:2643; }","duration":"207.035211ms","start":"2026-07-24T04:47:51.62294Z","end":"2026-07-24T04:47:51.829975Z","steps":["trace[1850040086] 'read index received' (duration: 206.867076ms)","trace[1850040086] 'applied index is now lower than readState.Index' (duration: 166.875µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:47:51.830128Z","caller":"traceutil/trace.go:171","msg":"trace[1837665119] transaction","detail":"{read_only:false; response_revision:2540; number_of_response:1; }","duration":"304.392078ms","start":"2026-07-24T04:47:51.525701Z","end":"2026-07-24T04:47:51.830094Z","steps":["trace[1837665119] 'process raft request' (duration: 304.108129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:47:51.830321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.358662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T04:47:51.830365Z","caller":"traceutil/trace.go:171","msg":"trace[1739018387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2540; }","duration":"207.456485ms","start":"2026-07-24T04:47:51.622896Z","end":"2026-07-24T04:47:51.830353Z","steps":["trace[1739018387] 'agreement among raft nodes before linearized reading' (duration: 207.253209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:47:51.830356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:47:51.525647Z","time spent":"304.591004ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:47:51.830384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.190827ms","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-24T04:47:51.830451Z","caller":"traceutil/trace.go:171","msg":"trace[836345584] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2540; }","duration":"201.302502ms","start":"2026-07-24T04:47:51.629133Z","end":"2026-07-24T04:47:51.830435Z","steps":["trace[836345584] 'agreement among raft nodes before linearized reading' (duration: 201.068284ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:47:57.937134Z","caller":"traceutil/trace.go:171","msg":"trace[1214071663] linearizableReadLoop","detail":"{readStateIndex:2676; appliedIndex:2675; }","duration":"438.702639ms","start":"2026-07-24T04:47:57.498404Z","end":"2026-07-24T04:47:57.937106Z","steps":["trace[1214071663] 'read index received' (duration: 438.197073ms)","trace[1214071663] 'applied index is now lower than readState.Index' (duration: 504.266µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:47:57.937486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.06379ms","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:525"} {"level":"info","ts":"2026-07-24T04:47:57.937544Z","caller":"traceutil/trace.go:171","msg":"trace[1763336284] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2571; }","duration":"439.174613ms","start":"2026-07-24T04:47:57.498352Z","end":"2026-07-24T04:47:57.937527Z","steps":["trace[1763336284] 'agreement among raft nodes before linearized reading' (duration: 438.975097ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:47:57.937583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:47:57.498332Z","time spent":"439.242695ms","remote":"127.0.0.1:42036","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-24T04:47:57.937227Z","caller":"traceutil/trace.go:171","msg":"trace[574087193] transaction","detail":"{read_only:false; response_revision:2571; number_of_response:1; }","duration":"617.023983ms","start":"2026-07-24T04:47:57.320182Z","end":"2026-07-24T04:47:57.937206Z","steps":["trace[574087193] 'process raft request' (duration: 616.732594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:47:57.937769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:47:57.320158Z","time spent":"617.477677ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:47:58.008134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.472603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-24T04:47:58.008201Z","caller":"traceutil/trace.go:171","msg":"trace[1427003589] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:2572; }","duration":"472.579547ms","start":"2026-07-24T04:47:57.535605Z","end":"2026-07-24T04:47:58.008184Z","steps":["trace[1427003589] 'agreement among raft nodes before linearized reading' (duration: 472.410881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:47:58.008232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:47:57.535587Z","time spent":"472.638479ms","remote":"127.0.0.1:41772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.169\" "} {"level":"info","ts":"2026-07-24T04:47:58.008194Z","caller":"traceutil/trace.go:171","msg":"trace[1438977676] transaction","detail":"{read_only:false; response_revision:2572; number_of_response:1; }","duration":"161.888596ms","start":"2026-07-24T04:47:57.846277Z","end":"2026-07-24T04:47:58.008166Z","steps":["trace[1438977676] 'process raft request' (duration: 161.629158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:47:58.008445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.35828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T04:47:58.008484Z","caller":"traceutil/trace.go:171","msg":"trace[1312960127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2572; }","duration":"148.407341ms","start":"2026-07-24T04:47:57.860068Z","end":"2026-07-24T04:47:58.008475Z","steps":["trace[1312960127] 'agreement among raft nodes before linearized reading' (duration: 148.324058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:47:58.008498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.781935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:47:58.008547Z","caller":"traceutil/trace.go:171","msg":"trace[341920634] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2572; }","duration":"454.835957ms","start":"2026-07-24T04:47:57.55369Z","end":"2026-07-24T04:47:58.008526Z","steps":["trace[341920634] 'agreement among raft nodes before linearized reading' (duration: 454.786426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:47:58.008593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:47:57.553679Z","time spent":"454.906539ms","remote":"127.0.0.1:41902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-07-24T04:47:58.008426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.804509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-24T04:47:58.008647Z","caller":"traceutil/trace.go:171","msg":"trace[2090804544] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2572; }","duration":"120.079648ms","start":"2026-07-24T04:47:57.888553Z","end":"2026-07-24T04:47:58.008633Z","steps":["trace[2090804544] 'agreement among raft nodes before linearized reading' (duration: 119.541101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:47:58.008738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.689692ms","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-24T04:47:58.00879Z","caller":"traceutil/trace.go:171","msg":"trace[1642322928] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2572; }","duration":"149.782814ms","start":"2026-07-24T04:47:57.858992Z","end":"2026-07-24T04:47:58.008775Z","steps":["trace[1642322928] 'agreement among raft nodes before linearized reading' (duration: 149.630419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:47:58.351349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.493687ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:47:58.351443Z","caller":"traceutil/trace.go:171","msg":"trace[1917563696] linearizableReadLoop","detail":"{readStateIndex:2678; appliedIndex:2677; }","duration":"342.06294ms","start":"2026-07-24T04:47:58.009365Z","end":"2026-07-24T04:47:58.351428Z","steps":["trace[1917563696] 'read index received' (duration: 126.419128ms)","trace[1917563696] 'applied index is now lower than readState.Index' (duration: 215.642902ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:47:58.351541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.166933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:47:58.351573Z","caller":"traceutil/trace.go:171","msg":"trace[235820614] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2573; }","duration":"342.201124ms","start":"2026-07-24T04:47:58.009359Z","end":"2026-07-24T04:47:58.351561Z","steps":["trace[235820614] 'agreement among raft nodes before linearized reading' (duration: 342.143412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:47:58.351607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:47:58.009328Z","time spent":"342.269626ms","remote":"127.0.0.1:59816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-07-24T04:47:58.351559Z","caller":"traceutil/trace.go:171","msg":"trace[1313524561] transaction","detail":"{read_only:false; response_revision:2573; number_of_response:1; }","duration":"409.641771ms","start":"2026-07-24T04:47:57.94186Z","end":"2026-07-24T04:47:58.351502Z","steps":["trace[1313524561] 'process raft request' (duration: 193.912706ms)","trace[1313524561] 'compare' (duration: 215.209349ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:47:58.351955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:47:57.941845Z","time spent":"409.888159ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:47:58.410259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:47:58.009636Z","time spent":"400.617686ms","remote":"127.0.0.1:41772","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-24T04:47:58.410318Z","caller":"traceutil/trace.go:171","msg":"trace[101214089] transaction","detail":"{read_only:false; response_revision:2575; number_of_response:1; }","duration":"397.472316ms","start":"2026-07-24T04:47:58.012823Z","end":"2026-07-24T04:47:58.410296Z","steps":["trace[101214089] 'process raft request' (duration: 397.404734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:47:58.410535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:47:58.012814Z","time spent":"397.641421ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:47:58.410487Z","caller":"traceutil/trace.go:171","msg":"trace[892230735] transaction","detail":"{read_only:false; response_revision:2574; number_of_response:1; }","duration":"397.727755ms","start":"2026-07-24T04:47:58.012668Z","end":"2026-07-24T04:47:58.410396Z","steps":["trace[892230735] 'process raft request' (duration: 397.456896ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:47:58.410573Z","caller":"traceutil/trace.go:171","msg":"trace[1237630487] transaction","detail":"{read_only:false; response_revision:2576; number_of_response:1; }","duration":"397.390663ms","start":"2026-07-24T04:47:58.013155Z","end":"2026-07-24T04:47:58.410546Z","steps":["trace[1237630487] 'process raft request' (duration: 397.107984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:47:58.410689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:47:58.013131Z","time spent":"397.488287ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:47:58.410659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.302201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:47:58.410712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:47:58.012651Z","time spent":"397.957551ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:47:58.410791Z","caller":"traceutil/trace.go:171","msg":"trace[1439980497] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2576; }","duration":"397.430025ms","start":"2026-07-24T04:47:58.01331Z","end":"2026-07-24T04:47:58.41074Z","steps":["trace[1439980497] 'agreement among raft nodes before linearized reading' (duration: 397.24946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:47:58.410889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:47:58.013284Z","time spent":"397.555549ms","remote":"127.0.0.1:41902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-07-24T04:47:58.791347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.149267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-24T04:47:58.791583Z","caller":"traceutil/trace.go:171","msg":"trace[1429808744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2577; }","duration":"157.55028ms","start":"2026-07-24T04:47:58.633979Z","end":"2026-07-24T04:47:58.79153Z","steps":["trace[1429808744] 'range keys from in-memory index tree' (duration: 156.93839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:47:59.474996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.677896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-24T04:47:59.475073Z","caller":"traceutil/trace.go:171","msg":"trace[162743697] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2579; }","duration":"191.794729ms","start":"2026-07-24T04:47:59.283261Z","end":"2026-07-24T04:47:59.475055Z","steps":["trace[162743697] 'range keys from in-memory index tree' (duration: 191.49084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:47:59.475069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.00746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:47:59.475143Z","caller":"traceutil/trace.go:171","msg":"trace[1806590633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2579; }","duration":"143.128154ms","start":"2026-07-24T04:47:59.331993Z","end":"2026-07-24T04:47:59.475121Z","steps":["trace[1806590633] 'range keys from in-memory index tree' (duration: 142.947139ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:47:59.475326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.202812ms","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-24T04:47:59.47536Z","caller":"traceutil/trace.go:171","msg":"trace[209713143] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:2579; }","duration":"263.277764ms","start":"2026-07-24T04:47:59.212073Z","end":"2026-07-24T04:47:59.475351Z","steps":["trace[209713143] 'count revisions from in-memory index tree' (duration: 263.116009ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:47:59.597506Z","caller":"traceutil/trace.go:171","msg":"trace[817667520] transaction","detail":"{read_only:false; response_revision:2580; number_of_response:1; }","duration":"118.427525ms","start":"2026-07-24T04:47:59.479059Z","end":"2026-07-24T04:47:59.597487Z","steps":["trace[817667520] 'process raft request' (duration: 118.287301ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:47:59.603116Z","caller":"traceutil/trace.go:171","msg":"trace[1507633810] transaction","detail":"{read_only:false; response_revision:2581; number_of_response:1; }","duration":"123.426383ms","start":"2026-07-24T04:47:59.479676Z","end":"2026-07-24T04:47:59.603103Z","steps":["trace[1507633810] 'process raft request' (duration: 123.354791ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:48:00.182393Z","caller":"traceutil/trace.go:171","msg":"trace[1639747650] transaction","detail":"{read_only:false; response_revision:2582; number_of_response:1; }","duration":"207.611558ms","start":"2026-07-24T04:47:59.97476Z","end":"2026-07-24T04:48:00.182371Z","steps":["trace[1639747650] 'process raft request' (duration: 207.464543ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:48:00.240762Z","caller":"traceutil/trace.go:171","msg":"trace[477384060] transaction","detail":"{read_only:false; response_revision:2583; number_of_response:1; }","duration":"227.527377ms","start":"2026-07-24T04:48:00.013206Z","end":"2026-07-24T04:48:00.240733Z","steps":["trace[477384060] 'process raft request' (duration: 227.347861ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:48:04.573177Z","caller":"traceutil/trace.go:171","msg":"trace[36932361] transaction","detail":"{read_only:false; response_revision:2605; number_of_response:1; }","duration":"106.190229ms","start":"2026-07-24T04:48:04.466965Z","end":"2026-07-24T04:48:04.573155Z","steps":["trace[36932361] 'process raft request' (duration: 106.011743ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:48:04.600866Z","caller":"traceutil/trace.go:171","msg":"trace[549935942] transaction","detail":"{read_only:false; response_revision:2606; number_of_response:1; }","duration":"133.371817ms","start":"2026-07-24T04:48:04.467474Z","end":"2026-07-24T04:48:04.600846Z","steps":["trace[549935942] 'process raft request' (duration: 133.248183ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:48:04.601088Z","caller":"traceutil/trace.go:171","msg":"trace[1472614677] transaction","detail":"{read_only:false; response_revision:2607; number_of_response:1; }","duration":"133.549723ms","start":"2026-07-24T04:48:04.46748Z","end":"2026-07-24T04:48:04.60103Z","steps":["trace[1472614677] 'process raft request' (duration: 133.335536ms)"],"step_count":1}