{"level":"warn","ts":"2026-07-17T11:04:00.391984Z","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-17T11:04:00.392073Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.54:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.54:2380","--initial-cluster=instance=https://199.19.213.54:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.54:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.54: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-17T11:04:00.392147Z","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-17T11:04:00.392163Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.54:2380"]} {"level":"info","ts":"2026-07-17T11:04:00.392185Z","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-17T11:04:00.393045Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.54:2379"]} {"level":"info","ts":"2026-07-17T11:04:00.393137Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.54:2380"],"listen-peer-urls":["https://199.19.213.54:2380"],"advertise-client-urls":["https://199.19.213.54:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.54:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.54: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-17T11:04:00.399699Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.311256ms"} {"level":"info","ts":"2026-07-17T11:04:00.407142Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"193b4dda7792855b","cluster-id":"dd6702a2f79ce697"} {"level":"info","ts":"2026-07-17T11:04:00.407239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b switched to configuration voters=()"} {"level":"info","ts":"2026-07-17T11:04:00.40727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became follower at term 0"} {"level":"info","ts":"2026-07-17T11:04:00.407289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 193b4dda7792855b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-17T11:04:00.407303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became follower at term 1"} {"level":"info","ts":"2026-07-17T11:04:00.407353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b switched to configuration voters=(1818132475278427483)"} {"level":"warn","ts":"2026-07-17T11:04:00.413676Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-17T11:04:00.415158Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-17T11:04:00.416082Z","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-17T11:04:00.418545Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"193b4dda7792855b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-17T11:04:00.418746Z","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-17T11:04:00.418842Z","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-17T11:04:00.418871Z","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-17T11:04:00.418804Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"193b4dda7792855b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-17T11:04:00.420818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b switched to configuration voters=(1818132475278427483)"} {"level":"info","ts":"2026-07-17T11:04:00.420968Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dd6702a2f79ce697","local-member-id":"193b4dda7792855b","added-peer-id":"193b4dda7792855b","added-peer-peer-urls":["https://199.19.213.54:2380"]} {"level":"info","ts":"2026-07-17T11:04:00.424713Z","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-17T11:04:00.42482Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.54:2380"} {"level":"info","ts":"2026-07-17T11:04:00.424852Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.54:2380"} {"level":"info","ts":"2026-07-17T11:04:00.425017Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"193b4dda7792855b","initial-advertise-peer-urls":["https://199.19.213.54:2380"],"listen-peer-urls":["https://199.19.213.54:2380"],"advertise-client-urls":["https://199.19.213.54:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.54:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-17T11:04:00.425076Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-17T11:04:01.208136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b is starting a new election at term 1"} {"level":"info","ts":"2026-07-17T11:04:01.208189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became pre-candidate at term 1"} {"level":"info","ts":"2026-07-17T11:04:01.208222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b received MsgPreVoteResp from 193b4dda7792855b at term 1"} {"level":"info","ts":"2026-07-17T11:04:01.208242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became candidate at term 2"} {"level":"info","ts":"2026-07-17T11:04:01.208252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b received MsgVoteResp from 193b4dda7792855b at term 2"} {"level":"info","ts":"2026-07-17T11:04:01.208263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became leader at term 2"} {"level":"info","ts":"2026-07-17T11:04:01.208275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 193b4dda7792855b elected leader 193b4dda7792855b at term 2"} {"level":"info","ts":"2026-07-17T11:04:01.247196Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T11:04:01.24736Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"193b4dda7792855b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.54:2379]}","request-path":"/0/members/193b4dda7792855b/attributes","cluster-id":"dd6702a2f79ce697","publish-timeout":"7s"} {"level":"info","ts":"2026-07-17T11:04:01.247564Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T11:04:01.248499Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dd6702a2f79ce697","local-member-id":"193b4dda7792855b","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T11:04:01.248577Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T11:04:01.248639Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T11:04:01.248678Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T11:04:01.251097Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-17T11:04:01.251155Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-17T11:04:01.253376Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.54:2379"} {"level":"info","ts":"2026-07-17T11:04:01.254092Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-17T11:04:06.491754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.786173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T11:04:06.491926Z","caller":"traceutil/trace.go:171","msg":"trace[364457659] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"440.178483ms","start":"2026-07-17T11:04:06.051734Z","end":"2026-07-17T11:04:06.491913Z","steps":["trace[364457659] 'process raft request' (duration: 440.126091ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:06.491946Z","caller":"traceutil/trace.go:171","msg":"trace[1335687963] linearizableReadLoop","detail":"{readStateIndex:285; appliedIndex:284; }","duration":"450.788347ms","start":"2026-07-17T11:04:06.041136Z","end":"2026-07-17T11:04:06.491924Z","steps":["trace[1335687963] 'read index received' (duration: 47.439109ms)","trace[1335687963] 'applied index is now lower than readState.Index' (duration: 403.346738ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:04:06.491926Z","caller":"traceutil/trace.go:171","msg":"trace[1842460881] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"467.814602ms","start":"2026-07-17T11:04:06.024046Z","end":"2026-07-17T11:04:06.491861Z","steps":["trace[1842460881] 'process raft request' (duration: 64.515446ms)","trace[1842460881] 'compare' (duration: 402.67751ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:04:06.49199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:06.051718Z","time spent":"440.252325ms","remote":"127.0.0.1:49588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:04:06.492054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.907772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"info","ts":"2026-07-17T11:04:06.492074Z","caller":"traceutil/trace.go:171","msg":"trace[700479496] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:280; }","duration":"450.956652ms","start":"2026-07-17T11:04:06.04111Z","end":"2026-07-17T11:04:06.492067Z","steps":["trace[700479496] 'agreement among raft nodes before linearized reading' (duration: 450.879311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:06.492112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:06.041098Z","time spent":"451.004934ms","remote":"127.0.0.1:49426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":138,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-07-17T11:04:06.492117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:06.024031Z","time spent":"467.992608ms","remote":"127.0.0.1:49442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:04:07.482162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.162139ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-07-17T11:04:07.482237Z","caller":"traceutil/trace.go:171","msg":"trace[51621453] transaction","detail":"{read_only:false; number_of_response:0; response_revision:280; }","duration":"1.367231949s","start":"2026-07-17T11:04:06.114992Z","end":"2026-07-17T11:04:07.482224Z","steps":["trace[51621453] 'process raft request' (duration: 784.964767ms)","trace[51621453] 'compare' (duration: 582.124799ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:04:07.482274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:06.114969Z","time spent":"1.367290611s","remote":"127.0.0.1:49552","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:04:07.835046Z","caller":"traceutil/trace.go:171","msg":"trace[290419978] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"1.700557274s","start":"2026-07-17T11:04:06.134446Z","end":"2026-07-17T11:04:07.835003Z","steps":["trace[290419978] 'process raft request' (duration: 1.700407111s)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:07.83512Z","caller":"traceutil/trace.go:171","msg":"trace[1051994525] linearizableReadLoop","detail":"{readStateIndex:293; appliedIndex:286; }","duration":"1.34035656s","start":"2026-07-17T11:04:06.494744Z","end":"2026-07-17T11:04:07.835101Z","steps":["trace[1051994525] 'read index received' (duration: 405.293441ms)","trace[1051994525] 'applied index is now lower than readState.Index' (duration: 935.062359ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:04:07.835112Z","caller":"traceutil/trace.go:171","msg":"trace[1451095041] transaction","detail":"{read_only:false; number_of_response:0; response_revision:282; }","duration":"1.700224967s","start":"2026-07-17T11:04:06.134854Z","end":"2026-07-17T11:04:07.835079Z","steps":["trace[1451095041] 'process raft request' (duration: 1.700128994s)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:07.835182Z","caller":"traceutil/trace.go:171","msg":"trace[1479737386] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"1.699839376s","start":"2026-07-17T11:04:06.135332Z","end":"2026-07-17T11:04:07.835172Z","steps":["trace[1479737386] 'process raft request' (duration: 1.699666821s)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:07.835204Z","caller":"traceutil/trace.go:171","msg":"trace[1820138744] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"1.339854177s","start":"2026-07-17T11:04:06.495344Z","end":"2026-07-17T11:04:07.835198Z","steps":["trace[1820138744] 'process raft request' (duration: 1.339728564s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:07.83521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:06.134423Z","time spent":"1.700720921s","remote":"127.0.0.1:49568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:04:07.835258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:06.134844Z","time spent":"1.70035609s","remote":"127.0.0.1:49568","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:04:07.835257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:06.135313Z","time spent":"1.699903517s","remote":"127.0.0.1:49568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:04:07.835332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.340581876s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T11:04:07.835273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:06.495331Z","time spent":"1.339895727s","remote":"127.0.0.1:49442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:04:07.835363Z","caller":"traceutil/trace.go:171","msg":"trace[1007166644] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:0; response_revision:285; }","duration":"1.340642009s","start":"2026-07-17T11:04:06.494715Z","end":"2026-07-17T11:04:07.835357Z","steps":["trace[1007166644] 'agreement among raft nodes before linearized reading' (duration: 1.340576866s)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:07.835355Z","caller":"traceutil/trace.go:171","msg":"trace[508449239] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"1.701162012s","start":"2026-07-17T11:04:06.133796Z","end":"2026-07-17T11:04:07.834958Z","steps":["trace[508449239] 'process raft request' (duration: 1.700960356s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:07.835382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:06.494702Z","time spent":"1.340676269s","remote":"127.0.0.1:49588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" "} {"level":"info","ts":"2026-07-17T11:04:07.835058Z","caller":"traceutil/trace.go:171","msg":"trace[1785759556] transaction","detail":"{read_only:false; number_of_response:0; response_revision:282; }","duration":"1.700258737s","start":"2026-07-17T11:04:06.134774Z","end":"2026-07-17T11:04:07.835033Z","steps":["trace[1785759556] 'process raft request' (duration: 1.700137644s)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:07.835144Z","caller":"traceutil/trace.go:171","msg":"trace[572125435] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"1.342087727s","start":"2026-07-17T11:04:06.49304Z","end":"2026-07-17T11:04:07.835128Z","steps":["trace[572125435] 'process raft request' (duration: 1.341995055s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:07.835537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:06.134763Z","time spent":"1.70074617s","remote":"127.0.0.1:49568","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:04:07.835632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.596859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:04:07.835666Z","caller":"traceutil/trace.go:171","msg":"trace[1765992825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:285; }","duration":"995.67094ms","start":"2026-07-17T11:04:06.839987Z","end":"2026-07-17T11:04:07.835658Z","steps":["trace[1765992825] 'agreement among raft nodes before linearized reading' (duration: 995.604619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:07.835688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:06.839964Z","time spent":"995.719072ms","remote":"127.0.0.1:49640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T11:04:07.835695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.29652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:04:07.835742Z","caller":"traceutil/trace.go:171","msg":"trace[657116163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:285; }","duration":"869.377153ms","start":"2026-07-17T11:04:06.966348Z","end":"2026-07-17T11:04:07.835726Z","steps":["trace[657116163] 'agreement among raft nodes before linearized reading' (duration: 869.299231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:07.835764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:06.493027Z","time spent":"1.342548469s","remote":"127.0.0.1:49426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:04:07.83579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:06.966329Z","time spent":"869.450814ms","remote":"127.0.0.1:49640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T11:04:07.835795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.912943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T11:04:07.835885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.983458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-07-17T11:04:07.835945Z","caller":"traceutil/trace.go:171","msg":"trace[445313553] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:285; }","duration":"351.076741ms","start":"2026-07-17T11:04:07.484855Z","end":"2026-07-17T11:04:07.835931Z","steps":["trace[445313553] 'agreement among raft nodes before linearized reading' (duration: 350.947707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:07.835981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:07.484843Z","time spent":"351.130852ms","remote":"127.0.0.1:49552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4039,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-17T11:04:07.835982Z","caller":"traceutil/trace.go:171","msg":"trace[285519702] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:285; }","duration":"656.130708ms","start":"2026-07-17T11:04:07.179763Z","end":"2026-07-17T11:04:07.835894Z","steps":["trace[285519702] 'agreement among raft nodes before linearized reading' (duration: 655.863581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:07.836183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:07.179742Z","time spent":"656.424536ms","remote":"127.0.0.1:49640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T11:04:07.835416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:06.133769Z","time spent":"1.701617374s","remote":"127.0.0.1:49568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:04:08.091926Z","caller":"traceutil/trace.go:171","msg":"trace[961549601] linearizableReadLoop","detail":"{readStateIndex:295; appliedIndex:294; }","duration":"252.569688ms","start":"2026-07-17T11:04:07.839331Z","end":"2026-07-17T11:04:08.0919Z","steps":["trace[961549601] 'read index received' (duration: 249.767444ms)","trace[961549601] 'applied index is now lower than readState.Index' (duration: 2.800244ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:04:08.091943Z","caller":"traceutil/trace.go:171","msg":"trace[317233238] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"253.996446ms","start":"2026-07-17T11:04:07.837922Z","end":"2026-07-17T11:04:08.091918Z","steps":["trace[317233238] 'process raft request' (duration: 251.11734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:08.0921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.754412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-07-17T11:04:08.092235Z","caller":"traceutil/trace.go:171","msg":"trace[1686120] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:287; }","duration":"252.894126ms","start":"2026-07-17T11:04:07.839326Z","end":"2026-07-17T11:04:08.09222Z","steps":["trace[1686120] 'agreement among raft nodes before linearized reading' (duration: 252.663419ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:08.092166Z","caller":"traceutil/trace.go:171","msg":"trace[1517247567] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"251.16866ms","start":"2026-07-17T11:04:07.84098Z","end":"2026-07-17T11:04:08.092149Z","steps":["trace[1517247567] 'process raft request' (duration: 250.877842ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:08.092636Z","caller":"traceutil/trace.go:171","msg":"trace[356268528] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"251.258132ms","start":"2026-07-17T11:04:07.841351Z","end":"2026-07-17T11:04:08.092609Z","steps":["trace[356268528] 'process raft request' (duration: 250.534063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:08.093542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.181587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"warn","ts":"2026-07-17T11:04:08.093632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.815038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T11:04:08.093612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.884158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3974"} {"level":"info","ts":"2026-07-17T11:04:08.093682Z","caller":"traceutil/trace.go:171","msg":"trace[2059697584] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:290; }","duration":"248.889679ms","start":"2026-07-17T11:04:07.844781Z","end":"2026-07-17T11:04:08.09367Z","steps":["trace[2059697584] 'agreement among raft nodes before linearized reading' (duration: 248.622002ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:08.093701Z","caller":"traceutil/trace.go:171","msg":"trace[1535928903] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"250.05498ms","start":"2026-07-17T11:04:07.843621Z","end":"2026-07-17T11:04:08.093676Z","steps":["trace[1535928903] 'process raft request' (duration: 249.641499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:08.09375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.412843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-07-17T11:04:08.093814Z","caller":"traceutil/trace.go:171","msg":"trace[1394673197] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:290; }","duration":"252.508254ms","start":"2026-07-17T11:04:07.84129Z","end":"2026-07-17T11:04:08.093798Z","steps":["trace[1394673197] 'agreement among raft nodes before linearized reading' (duration: 252.303459ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:08.0937Z","caller":"traceutil/trace.go:171","msg":"trace[2144985610] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:290; }","duration":"251.984731ms","start":"2026-07-17T11:04:07.841702Z","end":"2026-07-17T11:04:08.093687Z","steps":["trace[2144985610] 'agreement among raft nodes before linearized reading' (duration: 251.848468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:08.093877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.046204ms","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-17T11:04:08.093908Z","caller":"traceutil/trace.go:171","msg":"trace[121757645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:290; }","duration":"231.120027ms","start":"2026-07-17T11:04:07.862778Z","end":"2026-07-17T11:04:08.093898Z","steps":["trace[121757645] 'agreement among raft nodes before linearized reading' (duration: 231.056136ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:08.093627Z","caller":"traceutil/trace.go:171","msg":"trace[1383341534] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:290; }","duration":"252.29616ms","start":"2026-07-17T11:04:07.841317Z","end":"2026-07-17T11:04:08.093613Z","steps":["trace[1383341534] 'agreement among raft nodes before linearized reading' (duration: 252.115445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:08.093555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.917733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-17T11:04:08.094142Z","caller":"traceutil/trace.go:171","msg":"trace[1271505532] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:290; }","duration":"254.605821ms","start":"2026-07-17T11:04:07.839525Z","end":"2026-07-17T11:04:08.094131Z","steps":["trace[1271505532] 'agreement among raft nodes before linearized reading' (duration: 253.844171ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:08.094622Z","caller":"traceutil/trace.go:171","msg":"trace[147923163] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"250.299427ms","start":"2026-07-17T11:04:07.843078Z","end":"2026-07-17T11:04:08.093377Z","steps":["trace[147923163] 'process raft request' (duration: 249.413084ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:08.262406Z","caller":"traceutil/trace.go:171","msg":"trace[249221267] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"115.337096ms","start":"2026-07-17T11:04:08.147043Z","end":"2026-07-17T11:04:08.26238Z","steps":["trace[249221267] 'process raft request' (duration: 51.225832ms)","trace[249221267] 'compare' (duration: 63.906598ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:04:08.262847Z","caller":"traceutil/trace.go:171","msg":"trace[18156173] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:313; }","duration":"113.773155ms","start":"2026-07-17T11:04:08.14893Z","end":"2026-07-17T11:04:08.262703Z","steps":["trace[18156173] 'read index received' (duration: 49.351303ms)","trace[18156173] 'applied index is now lower than readState.Index' (duration: 64.411462ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:04:08.262936Z","caller":"traceutil/trace.go:171","msg":"trace[637461472] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"112.455439ms","start":"2026-07-17T11:04:08.150429Z","end":"2026-07-17T11:04:08.262885Z","steps":["trace[637461472] 'process raft request' (duration: 112.215913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:08.262961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.007541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:04:08.26304Z","caller":"traceutil/trace.go:171","msg":"trace[279541932] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:0; response_revision:306; }","duration":"114.104824ms","start":"2026-07-17T11:04:08.148913Z","end":"2026-07-17T11:04:08.263018Z","steps":["trace[279541932] 'agreement among raft nodes before linearized reading' (duration: 113.879177ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:12.521791Z","caller":"traceutil/trace.go:171","msg":"trace[2104717953] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"393.579869ms","start":"2026-07-17T11:04:12.128197Z","end":"2026-07-17T11:04:12.521776Z","steps":["trace[2104717953] 'process raft request' (duration: 393.440006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:12.521901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.56898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-17T11:04:12.521908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:12.128178Z","time spent":"393.664102ms","remote":"127.0.0.1:49640","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-17T11:04:12.521927Z","caller":"traceutil/trace.go:171","msg":"trace[1972153205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:385; }","duration":"388.631323ms","start":"2026-07-17T11:04:12.133288Z","end":"2026-07-17T11:04:12.521919Z","steps":["trace[1972153205] 'agreement among raft nodes before linearized reading' (duration: 388.53257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:12.521948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:12.133272Z","time spent":"388.669833ms","remote":"127.0.0.1:49640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-17T11:04:12.521779Z","caller":"traceutil/trace.go:171","msg":"trace[594921603] linearizableReadLoop","detail":"{readStateIndex:394; appliedIndex:393; }","duration":"388.424157ms","start":"2026-07-17T11:04:12.133333Z","end":"2026-07-17T11:04:12.521757Z","steps":["trace[594921603] 'read index received' (duration: 388.268094ms)","trace[594921603] 'applied index is now lower than readState.Index' (duration: 154.813µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:04:12.522096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.016596ms","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-17T11:04:12.522114Z","caller":"traceutil/trace.go:171","msg":"trace[1657968631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:385; }","duration":"381.054257ms","start":"2026-07-17T11:04:12.141054Z","end":"2026-07-17T11:04:12.522109Z","steps":["trace[1657968631] 'agreement among raft nodes before linearized reading' (duration: 380.976596ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:12.522128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:12.141024Z","time spent":"381.100388ms","remote":"127.0.0.1:49640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T11:04:12.522183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.748916ms","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-17T11:04:12.522205Z","caller":"traceutil/trace.go:171","msg":"trace[772083942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:385; }","duration":"376.795687ms","start":"2026-07-17T11:04:12.145403Z","end":"2026-07-17T11:04:12.522199Z","steps":["trace[772083942] 'agreement among raft nodes before linearized reading' (duration: 376.762857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:12.522221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:12.14539Z","time spent":"376.826378ms","remote":"127.0.0.1:49640","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-17T11:04:40.104675Z","caller":"traceutil/trace.go:171","msg":"trace[1434080876] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"109.274589ms","start":"2026-07-17T11:04:39.99537Z","end":"2026-07-17T11:04:40.104644Z","steps":["trace[1434080876] 'process raft request' (duration: 109.125285ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:40.524088Z","caller":"traceutil/trace.go:171","msg":"trace[451306546] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"514.555072ms","start":"2026-07-17T11:04:40.009484Z","end":"2026-07-17T11:04:40.524039Z","steps":["trace[451306546] 'process raft request' (duration: 514.281746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:40.524263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:40.009456Z","time spent":"514.731786ms","remote":"127.0.0.1:49422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:04:40.524336Z","caller":"traceutil/trace.go:171","msg":"trace[2043479426] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"458.294782ms","start":"2026-07-17T11:04:40.066021Z","end":"2026-07-17T11:04:40.524315Z","steps":["trace[2043479426] 'process raft request' (duration: 458.255701ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:40.524407Z","caller":"traceutil/trace.go:171","msg":"trace[1063551987] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"514.839667ms","start":"2026-07-17T11:04:40.009554Z","end":"2026-07-17T11:04:40.524394Z","steps":["trace[1063551987] 'process raft request' (duration: 514.337357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:40.52446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:40.066Z","time spent":"458.399074ms","remote":"127.0.0.1:49422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:04:40.524394Z","caller":"traceutil/trace.go:171","msg":"trace[1040540082] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"509.158241ms","start":"2026-07-17T11:04:40.01516Z","end":"2026-07-17T11:04:40.524318Z","steps":["trace[1040540082] 'process raft request' (duration: 508.949307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:40.52447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:40.009549Z","time spent":"514.886549ms","remote":"127.0.0.1:49422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:04:40.524544Z","caller":"traceutil/trace.go:171","msg":"trace[1769337284] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"513.407987ms","start":"2026-07-17T11:04:40.011105Z","end":"2026-07-17T11:04:40.524513Z","steps":["trace[1769337284] 'process raft request' (duration: 512.981387ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:40.524574Z","caller":"traceutil/trace.go:171","msg":"trace[1061206583] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"514.905069ms","start":"2026-07-17T11:04:40.00964Z","end":"2026-07-17T11:04:40.524545Z","steps":["trace[1061206583] 'process raft request' (duration: 514.335907ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:40.524338Z","caller":"traceutil/trace.go:171","msg":"trace[21268181] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"505.799477ms","start":"2026-07-17T11:04:40.018514Z","end":"2026-07-17T11:04:40.524313Z","steps":["trace[21268181] 'process raft request' (duration: 505.651063ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:40.524617Z","caller":"traceutil/trace.go:171","msg":"trace[1297638474] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"497.298447ms","start":"2026-07-17T11:04:40.027309Z","end":"2026-07-17T11:04:40.524608Z","steps":["trace[1297638474] 'process raft request' (duration: 496.892359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:40.524666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:40.027289Z","time spent":"497.353909ms","remote":"127.0.0.1:49422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:04:40.524675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:40.009635Z","time spent":"514.991051ms","remote":"127.0.0.1:49422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:04:40.524673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:40.018505Z","time spent":"506.128834ms","remote":"127.0.0.1:49422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:04:40.524673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:40.015139Z","time spent":"509.406736ms","remote":"127.0.0.1:49422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:04:40.524704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:40.011098Z","time spent":"513.528849ms","remote":"127.0.0.1:49422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:04:40.524579Z","caller":"traceutil/trace.go:171","msg":"trace[135104409] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"478.343746ms","start":"2026-07-17T11:04:40.046225Z","end":"2026-07-17T11:04:40.524568Z","steps":["trace[135104409] 'process raft request' (duration: 478.011929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:40.524793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:40.046202Z","time spent":"478.566722ms","remote":"127.0.0.1:49422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:04:40.524821Z","caller":"traceutil/trace.go:171","msg":"trace[460478981] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"514.410477ms","start":"2026-07-17T11:04:40.010386Z","end":"2026-07-17T11:04:40.524797Z","steps":["trace[460478981] 'process raft request' (duration: 513.669721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:40.52489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:40.010379Z","time spent":"514.478259ms","remote":"127.0.0.1:49422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:04:40.555953Z","caller":"traceutil/trace.go:171","msg":"trace[1735929701] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"445.617339ms","start":"2026-07-17T11:04:40.11032Z","end":"2026-07-17T11:04:40.555938Z","steps":["trace[1735929701] 'process raft request' (duration: 445.335123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:40.556056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:40.110295Z","time spent":"445.712402ms","remote":"127.0.0.1:49918","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-17T11:04:40.55625Z","caller":"traceutil/trace.go:171","msg":"trace[1107976744] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"443.893792ms","start":"2026-07-17T11:04:40.112349Z","end":"2026-07-17T11:04:40.556242Z","steps":["trace[1107976744] 'process raft request' (duration: 443.412512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:40.556305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:40.112334Z","time spent":"443.937953ms","remote":"127.0.0.1:49422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:04:40.556525Z","caller":"traceutil/trace.go:171","msg":"trace[1438850083] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"370.603114ms","start":"2026-07-17T11:04:40.185915Z","end":"2026-07-17T11:04:40.556518Z","steps":["trace[1438850083] 'process raft request' (duration: 370.479261ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:40.556563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:40.185886Z","time spent":"370.661345ms","remote":"127.0.0.1:49422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:04:40.711252Z","caller":"traceutil/trace.go:171","msg":"trace[1328542664] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"168.667117ms","start":"2026-07-17T11:04:40.54253Z","end":"2026-07-17T11:04:40.711197Z","steps":["trace[1328542664] 'process raft request' (duration: 168.456922ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:40.711593Z","caller":"traceutil/trace.go:171","msg":"trace[337200468] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"106.084296ms","start":"2026-07-17T11:04:40.605481Z","end":"2026-07-17T11:04:40.711565Z","steps":["trace[337200468] 'process raft request' (duration: 106.039986ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:40.711758Z","caller":"traceutil/trace.go:171","msg":"trace[868630303] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"176.168794ms","start":"2026-07-17T11:04:40.535569Z","end":"2026-07-17T11:04:40.711737Z","steps":["trace[868630303] 'process raft request' (duration: 126.644913ms)","trace[868630303] 'compare' (duration: 48.605981ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:04:40.711851Z","caller":"traceutil/trace.go:171","msg":"trace[190475680] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"169.233349ms","start":"2026-07-17T11:04:40.542608Z","end":"2026-07-17T11:04:40.711842Z","steps":["trace[190475680] 'process raft request' (duration: 168.436972ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:40.711964Z","caller":"traceutil/trace.go:171","msg":"trace[624535424] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"167.553762ms","start":"2026-07-17T11:04:40.544401Z","end":"2026-07-17T11:04:40.711954Z","steps":["trace[624535424] 'process raft request' (duration: 166.671303ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:40.712246Z","caller":"traceutil/trace.go:171","msg":"trace[1149409942] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"167.616364ms","start":"2026-07-17T11:04:40.54462Z","end":"2026-07-17T11:04:40.712236Z","steps":["trace[1149409942] 'process raft request' (duration: 166.484638ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:40.712354Z","caller":"traceutil/trace.go:171","msg":"trace[1094034139] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"167.664594ms","start":"2026-07-17T11:04:40.544682Z","end":"2026-07-17T11:04:40.712346Z","steps":["trace[1094034139] 'process raft request' (duration: 166.477858ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:40.712427Z","caller":"traceutil/trace.go:171","msg":"trace[1955113967] transaction","detail":"{read_only:false; number_of_response:1; response_revision:551; }","duration":"145.88085ms","start":"2026-07-17T11:04:40.56653Z","end":"2026-07-17T11:04:40.712411Z","steps":["trace[1955113967] 'process raft request' (duration: 144.917109ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:40.712569Z","caller":"traceutil/trace.go:171","msg":"trace[487353619] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"166.808866ms","start":"2026-07-17T11:04:40.545748Z","end":"2026-07-17T11:04:40.712557Z","steps":["trace[487353619] 'process raft request' (duration: 165.475406ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:40.712668Z","caller":"traceutil/trace.go:171","msg":"trace[588619004] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"165.224061ms","start":"2026-07-17T11:04:40.547432Z","end":"2026-07-17T11:04:40.712656Z","steps":["trace[588619004] 'process raft request' (duration: 163.83509ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:40.71285Z","caller":"traceutil/trace.go:171","msg":"trace[1683561466] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"158.830499ms","start":"2026-07-17T11:04:40.554005Z","end":"2026-07-17T11:04:40.712835Z","steps":["trace[1683561466] 'process raft request' (duration: 157.308565ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:40.713003Z","caller":"traceutil/trace.go:171","msg":"trace[857922885] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"149.105323ms","start":"2026-07-17T11:04:40.563884Z","end":"2026-07-17T11:04:40.712989Z","steps":["trace[857922885] 'process raft request' (duration: 147.520127ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:40.901657Z","caller":"traceutil/trace.go:171","msg":"trace[1732808178] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"105.054223ms","start":"2026-07-17T11:04:40.796573Z","end":"2026-07-17T11:04:40.901628Z","steps":["trace[1732808178] 'process raft request' (duration: 36.327936ms)","trace[1732808178] 'compare' (duration: 68.549644ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:04:41.092035Z","caller":"traceutil/trace.go:171","msg":"trace[374672897] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"114.562996ms","start":"2026-07-17T11:04:40.977464Z","end":"2026-07-17T11:04:41.092027Z","steps":["trace[374672897] 'process raft request' (duration: 114.319271ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:41.091942Z","caller":"traceutil/trace.go:171","msg":"trace[2008108840] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"116.53694ms","start":"2026-07-17T11:04:40.97537Z","end":"2026-07-17T11:04:41.091907Z","steps":["trace[2008108840] 'process raft request' (duration: 27.107743ms)","trace[2008108840] 'compare' (duration: 89.039128ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:04:44.8466Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9609449532225920013,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T11:04:44.881975Z","caller":"traceutil/trace.go:171","msg":"trace[1993708960] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"535.780223ms","start":"2026-07-17T11:04:44.34616Z","end":"2026-07-17T11:04:44.881941Z","steps":["trace[1993708960] 'read index received' (duration: 534.884013ms)","trace[1993708960] 'applied index is now lower than readState.Index' (duration: 894.77µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:04:44.882017Z","caller":"traceutil/trace.go:171","msg":"trace[1911653713] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"571.843781ms","start":"2026-07-17T11:04:44.310141Z","end":"2026-07-17T11:04:44.881985Z","steps":["trace[1911653713] 'process raft request' (duration: 571.215147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:44.882214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:44.310102Z","time spent":"572.026914ms","remote":"127.0.0.1:49458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:04:44.882292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.416075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-17T11:04:44.882282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.10268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.54\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-17T11:04:44.882337Z","caller":"traceutil/trace.go:171","msg":"trace[1996825569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"113.508217ms","start":"2026-07-17T11:04:44.768815Z","end":"2026-07-17T11:04:44.882323Z","steps":["trace[1996825569] 'agreement among raft nodes before linearized reading' (duration: 113.347394ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:44.882378Z","caller":"traceutil/trace.go:171","msg":"trace[145393131] range","detail":"{range_begin:/registry/masterleases/199.19.213.54; range_end:; response_count:1; response_revision:616; }","duration":"536.259974ms","start":"2026-07-17T11:04:44.346097Z","end":"2026-07-17T11:04:44.882357Z","steps":["trace[145393131] 'agreement among raft nodes before linearized reading' (duration: 536.031899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:44.882418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:44.346074Z","time spent":"536.336575ms","remote":"127.0.0.1:49398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.54\" "} {"level":"warn","ts":"2026-07-17T11:04:44.882978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.484021ms","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-17T11:04:44.883045Z","caller":"traceutil/trace.go:171","msg":"trace[158850869] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"110.590024ms","start":"2026-07-17T11:04:44.772446Z","end":"2026-07-17T11:04:44.883036Z","steps":["trace[158850869] 'agreement among raft nodes before linearized reading' (duration: 110.468912ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:04:47.642222Z","caller":"traceutil/trace.go:171","msg":"trace[785740461] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"271.570523ms","start":"2026-07-17T11:04:47.370578Z","end":"2026-07-17T11:04:47.642149Z","steps":["trace[785740461] 'process raft request' (duration: 270.924529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:48.922179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074405839s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:04:48.922254Z","caller":"traceutil/trace.go:171","msg":"trace[456209996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"1.074567453s","start":"2026-07-17T11:04:47.847671Z","end":"2026-07-17T11:04:48.922238Z","steps":["trace[456209996] 'range keys from in-memory index tree' (duration: 1.074255657s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:48.922279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:47.847646Z","time spent":"1.074627613s","remote":"127.0.0.1:49356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T11:04:48.924249Z","caller":"traceutil/trace.go:171","msg":"trace[1553358795] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"755.763148ms","start":"2026-07-17T11:04:48.168472Z","end":"2026-07-17T11:04:48.924235Z","steps":["trace[1553358795] 'process raft request' (duration: 750.927424ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:04:48.925357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:04:48.168443Z","time spent":"756.84599ms","remote":"127.0.0.1:49458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:04:50.588829Z","caller":"traceutil/trace.go:171","msg":"trace[903801144] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"218.142733ms","start":"2026-07-17T11:04:50.370663Z","end":"2026-07-17T11:04:50.588805Z","steps":["trace[903801144] 'process raft request' (duration: 218.023661ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:05:01.093385Z","caller":"traceutil/trace.go:171","msg":"trace[1309447559] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"105.466205ms","start":"2026-07-17T11:05:00.987889Z","end":"2026-07-17T11:05:01.093355Z","steps":["trace[1309447559] 'process raft request' (duration: 105.324482ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:05:01.103281Z","caller":"traceutil/trace.go:171","msg":"trace[1407817996] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:811; }","duration":"112.159222ms","start":"2026-07-17T11:05:00.991092Z","end":"2026-07-17T11:05:01.103252Z","steps":["trace[1407817996] 'read index received' (duration: 102.170367ms)","trace[1407817996] 'applied index is now lower than readState.Index' (duration: 9.986765ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:05:01.103353Z","caller":"traceutil/trace.go:171","msg":"trace[1945537784] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"115.08319ms","start":"2026-07-17T11:05:00.988249Z","end":"2026-07-17T11:05:01.103332Z","steps":["trace[1945537784] 'process raft request' (duration: 114.737294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:05:01.103722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.613191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-17T11:05:01.103727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.320894ms","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-17T11:05:01.103809Z","caller":"traceutil/trace.go:171","msg":"trace[1052358199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:788; }","duration":"112.740843ms","start":"2026-07-17T11:05:00.991051Z","end":"2026-07-17T11:05:01.103792Z","steps":["trace[1052358199] 'agreement among raft nodes before linearized reading' (duration: 112.385535ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:05:01.103818Z","caller":"traceutil/trace.go:171","msg":"trace[1331113297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:788; }","duration":"112.493858ms","start":"2026-07-17T11:05:00.991308Z","end":"2026-07-17T11:05:01.103802Z","steps":["trace[1331113297] 'agreement among raft nodes before linearized reading' (duration: 112.171741ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:05:04.198536Z","caller":"traceutil/trace.go:171","msg":"trace[1856956133] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"109.012103ms","start":"2026-07-17T11:05:04.089497Z","end":"2026-07-17T11:05:04.198509Z","steps":["trace[1856956133] 'process raft request' (duration: 108.789048ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:06:11.590708Z","caller":"traceutil/trace.go:171","msg":"trace[1950970210] linearizableReadLoop","detail":"{readStateIndex:1199; appliedIndex:1198; }","duration":"126.717767ms","start":"2026-07-17T11:06:11.463966Z","end":"2026-07-17T11:06:11.590684Z","steps":["trace[1950970210] 'read index received' (duration: 126.588465ms)","trace[1950970210] 'applied index is now lower than readState.Index' (duration: 128.322µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:06:11.590846Z","caller":"traceutil/trace.go:171","msg":"trace[1258543467] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"131.144893ms","start":"2026-07-17T11:06:11.459661Z","end":"2026-07-17T11:06:11.590806Z","steps":["trace[1258543467] 'process raft request' (duration: 130.860819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:11.591156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.096955ms","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-17T11:06:11.591252Z","caller":"traceutil/trace.go:171","msg":"trace[1864274924] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1158; }","duration":"127.310148ms","start":"2026-07-17T11:06:11.463923Z","end":"2026-07-17T11:06:11.591234Z","steps":["trace[1864274924] 'agreement among raft nodes before linearized reading' (duration: 126.88717ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:06:17.257634Z","caller":"traceutil/trace.go:171","msg":"trace[598285529] linearizableReadLoop","detail":"{readStateIndex:1219; appliedIndex:1218; }","duration":"410.309204ms","start":"2026-07-17T11:06:16.8473Z","end":"2026-07-17T11:06:17.257609Z","steps":["trace[598285529] 'read index received' (duration: 410.11655ms)","trace[598285529] 'applied index is now lower than readState.Index' (duration: 191.374µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:06:17.257756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.433866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:06:17.257784Z","caller":"traceutil/trace.go:171","msg":"trace[665531172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1177; }","duration":"410.506806ms","start":"2026-07-17T11:06:16.847267Z","end":"2026-07-17T11:06:17.257774Z","steps":["trace[665531172] 'agreement among raft nodes before linearized reading' (duration: 410.442275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:17.257822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:16.847251Z","time spent":"410.561257ms","remote":"127.0.0.1:49356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T11:06:17.257835Z","caller":"traceutil/trace.go:171","msg":"trace[230772794] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"543.287204ms","start":"2026-07-17T11:06:16.714399Z","end":"2026-07-17T11:06:17.257686Z","steps":["trace[230772794] 'process raft request' (duration: 543.087791ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:17.258047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:16.714377Z","time spent":"543.591268ms","remote":"127.0.0.1:49640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:07:12.409233Z","caller":"traceutil/trace.go:171","msg":"trace[1861727617] linearizableReadLoop","detail":"{readStateIndex:1457; appliedIndex:1456; }","duration":"252.987254ms","start":"2026-07-17T11:07:12.15622Z","end":"2026-07-17T11:07:12.409208Z","steps":["trace[1861727617] 'read index received' (duration: 252.852329ms)","trace[1861727617] 'applied index is now lower than readState.Index' (duration: 134.285µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:07:12.409321Z","caller":"traceutil/trace.go:171","msg":"trace[986632570] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"260.420997ms","start":"2026-07-17T11:07:12.148892Z","end":"2026-07-17T11:07:12.409313Z","steps":["trace[986632570] 'process raft request' (duration: 260.224748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:07:12.409404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.238772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-proxier\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-17T11:07:12.409635Z","caller":"traceutil/trace.go:171","msg":"trace[981580433] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-proxier; range_end:; response_count:1; response_revision:1401; }","duration":"241.316225ms","start":"2026-07-17T11:07:12.168119Z","end":"2026-07-17T11:07:12.409436Z","steps":["trace[981580433] 'agreement among raft nodes before linearized reading' (duration: 241.234312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:07:12.409827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.036008ms","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-17T11:07:12.409855Z","caller":"traceutil/trace.go:171","msg":"trace[943625079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1401; }","duration":"167.064099ms","start":"2026-07-17T11:07:12.242784Z","end":"2026-07-17T11:07:12.409848Z","steps":["trace[943625079] 'agreement among raft nodes before linearized reading' (duration: 166.976455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:07:12.410067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.547068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:node-controller\" ","response":"range_response_count:1 size:835"} {"level":"info","ts":"2026-07-17T11:07:12.410141Z","caller":"traceutil/trace.go:171","msg":"trace[2095695507] range","detail":"{range_begin:/registry/clusterroles/system:controller:node-controller; range_end:; response_count:1; response_revision:1401; }","duration":"253.912594ms","start":"2026-07-17T11:07:12.156211Z","end":"2026-07-17T11:07:12.410123Z","steps":["trace[2095695507] 'agreement among raft nodes before linearized reading' (duration: 253.465865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:07:12.410236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.643018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-17T11:07:12.410333Z","caller":"traceutil/trace.go:171","msg":"trace[548453293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1401; }","duration":"154.869698ms","start":"2026-07-17T11:07:12.255396Z","end":"2026-07-17T11:07:12.410266Z","steps":["trace[548453293] 'agreement among raft nodes before linearized reading' (duration: 154.035812ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:07:20.635299Z","caller":"traceutil/trace.go:171","msg":"trace[947083029] linearizableReadLoop","detail":"{readStateIndex:1487; appliedIndex:1485; }","duration":"181.49078ms","start":"2026-07-17T11:07:20.453784Z","end":"2026-07-17T11:07:20.635274Z","steps":["trace[947083029] 'read index received' (duration: 19.897758ms)","trace[947083029] 'applied index is now lower than readState.Index' (duration: 161.591902ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:07:20.635687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.875316ms","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-17T11:07:20.635727Z","caller":"traceutil/trace.go:171","msg":"trace[1349562801] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"185.644577ms","start":"2026-07-17T11:07:20.450051Z","end":"2026-07-17T11:07:20.635695Z","steps":["trace[1349562801] 'process raft request' (duration: 184.935799ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:07:20.635769Z","caller":"traceutil/trace.go:171","msg":"trace[1576447692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1429; }","duration":"182.007761ms","start":"2026-07-17T11:07:20.453745Z","end":"2026-07-17T11:07:20.635753Z","steps":["trace[1576447692] 'agreement among raft nodes before linearized reading' (duration: 181.73076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:07:20.635777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.34867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:6 size:2185"} {"level":"info","ts":"2026-07-17T11:07:20.635951Z","caller":"traceutil/trace.go:171","msg":"trace[1588166268] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:6; response_revision:1429; }","duration":"173.560019ms","start":"2026-07-17T11:07:20.462376Z","end":"2026-07-17T11:07:20.635936Z","steps":["trace[1588166268] 'agreement among raft nodes before linearized reading' (duration: 173.265977ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:07:22.275842Z","caller":"traceutil/trace.go:171","msg":"trace[223499050] transaction","detail":"{read_only:false; response_revision:1433; number_of_response:1; }","duration":"105.619007ms","start":"2026-07-17T11:07:22.170183Z","end":"2026-07-17T11:07:22.275802Z","steps":["trace[223499050] 'process raft request' (duration: 37.057871ms)","trace[223499050] 'compare' (duration: 68.273825ms)"],"step_count":2}