{"level":"warn","ts":"2026-10-13T15:22:50.709675Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-13T15:22:50.709779Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.93: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://162.253.55.93:2380","--initial-cluster=instance=https://162.253.55.93:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.93:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.93: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-10-13T15:22:50.709854Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-13T15:22:50.709871Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.93:2380"]} {"level":"info","ts":"2026-10-13T15:22:50.709894Z","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-10-13T15:22:50.710862Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"]} {"level":"info","ts":"2026-10-13T15:22:50.710953Z","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://162.253.55.93:2380"],"listen-peer-urls":["https://162.253.55.93:2380"],"advertise-client-urls":["https://162.253.55.93:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.93: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-10-13T15:22:50.714012Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.781911ms"} {"level":"info","ts":"2026-10-13T15:22:50.725164Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ea06c5748459930b","cluster-id":"49d4e1d71c75bc5"} {"level":"info","ts":"2026-10-13T15:22:50.725255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=()"} {"level":"info","ts":"2026-10-13T15:22:50.725276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became follower at term 0"} {"level":"info","ts":"2026-10-13T15:22:50.72529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ea06c5748459930b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-13T15:22:50.725299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became follower at term 1"} {"level":"info","ts":"2026-10-13T15:22:50.72534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=(16863382958962742027)"} {"level":"warn","ts":"2026-10-13T15:22:50.734974Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-13T15:22:50.736451Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-13T15:22:50.737646Z","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-10-13T15:22:50.741635Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ea06c5748459930b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-13T15:22:50.741856Z","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-10-13T15:22:50.741941Z","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-10-13T15:22:50.741969Z","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-10-13T15:22:50.743658Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ea06c5748459930b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-13T15:22:50.745843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=(16863382958962742027)"} {"level":"info","ts":"2026-10-13T15:22:50.745937Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49d4e1d71c75bc5","local-member-id":"ea06c5748459930b","added-peer-id":"ea06c5748459930b","added-peer-peer-urls":["https://162.253.55.93:2380"]} {"level":"info","ts":"2026-10-13T15:22:50.746672Z","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-10-13T15:22:50.746866Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ea06c5748459930b","initial-advertise-peer-urls":["https://162.253.55.93:2380"],"listen-peer-urls":["https://162.253.55.93:2380"],"advertise-client-urls":["https://162.253.55.93:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-13T15:22:50.746889Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-13T15:22:50.746973Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.93:2380"} {"level":"info","ts":"2026-10-13T15:22:50.746983Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.93:2380"} {"level":"info","ts":"2026-10-13T15:22:51.125723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b is starting a new election at term 1"} {"level":"info","ts":"2026-10-13T15:22:51.125802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became pre-candidate at term 1"} {"level":"info","ts":"2026-10-13T15:22:51.125829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b received MsgPreVoteResp from ea06c5748459930b at term 1"} {"level":"info","ts":"2026-10-13T15:22:51.12584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became candidate at term 2"} {"level":"info","ts":"2026-10-13T15:22:51.125847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b received MsgVoteResp from ea06c5748459930b at term 2"} {"level":"info","ts":"2026-10-13T15:22:51.125856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became leader at term 2"} {"level":"info","ts":"2026-10-13T15:22:51.125879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ea06c5748459930b elected leader ea06c5748459930b at term 2"} {"level":"info","ts":"2026-10-13T15:22:51.126976Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ea06c5748459930b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.93:2379]}","request-path":"/0/members/ea06c5748459930b/attributes","cluster-id":"49d4e1d71c75bc5","publish-timeout":"7s"} {"level":"info","ts":"2026-10-13T15:22:51.127075Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T15:22:51.127224Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T15:22:51.1273Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-13T15:22:51.127407Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-13T15:22:51.12738Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T15:22:51.129856Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49d4e1d71c75bc5","local-member-id":"ea06c5748459930b","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T15:22:51.130012Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T15:22:51.130059Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T15:22:51.130751Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-13T15:22:51.13184Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.93:2379"} {"level":"info","ts":"2026-10-13T15:23:11.00004Z","caller":"traceutil/trace.go:171","msg":"trace[1679618166] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"188.03445ms","start":"2026-10-13T15:23:10.811964Z","end":"2026-10-13T15:23:10.999998Z","steps":["trace[1679618166] 'process raft request' (duration: 187.695843ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:23:17.256959Z","caller":"traceutil/trace.go:171","msg":"trace[2143049287] linearizableReadLoop","detail":"{readStateIndex:453; appliedIndex:452; }","duration":"148.768339ms","start":"2026-10-13T15:23:17.108169Z","end":"2026-10-13T15:23:17.256937Z","steps":["trace[2143049287] 'read index received' (duration: 148.625636ms)","trace[2143049287] 'applied index is now lower than readState.Index' (duration: 142.073µs)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:23:17.257057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.877721ms","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-10-13T15:23:17.257106Z","caller":"traceutil/trace.go:171","msg":"trace[552987099] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:439; }","duration":"148.980064ms","start":"2026-10-13T15:23:17.108117Z","end":"2026-10-13T15:23:17.257097Z","steps":["trace[552987099] 'agreement among raft nodes before linearized reading' (duration: 148.895952ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:23:17.257103Z","caller":"traceutil/trace.go:171","msg":"trace[489708509] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"230.482941ms","start":"2026-10-13T15:23:17.026604Z","end":"2026-10-13T15:23:17.257087Z","steps":["trace[489708509] 'process raft request' (duration: 230.237456ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:23:21.782041Z","caller":"traceutil/trace.go:171","msg":"trace[1675494772] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"175.888471ms","start":"2026-10-13T15:23:21.606123Z","end":"2026-10-13T15:23:21.782012Z","steps":["trace[1675494772] 'process raft request' (duration: 175.761448ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:23:36.363786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.395237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T15:23:36.363893Z","caller":"traceutil/trace.go:171","msg":"trace[846455129] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:548; }","duration":"361.56251ms","start":"2026-10-13T15:23:36.002306Z","end":"2026-10-13T15:23:36.363869Z","steps":["trace[846455129] 'range keys from in-memory index tree' (duration: 361.297815ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:23:36.363938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:23:36.002282Z","time spent":"361.645202ms","remote":"127.0.0.1:59030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-10-13T15:23:36.737635Z","caller":"traceutil/trace.go:171","msg":"trace[2105061404] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"102.64686ms","start":"2026-10-13T15:23:36.634906Z","end":"2026-10-13T15:23:36.737552Z","steps":["trace[2105061404] 'process raft request' (duration: 102.345094ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:23:36.749395Z","caller":"traceutil/trace.go:171","msg":"trace[1277004401] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"113.734968ms","start":"2026-10-13T15:23:36.635637Z","end":"2026-10-13T15:23:36.749372Z","steps":["trace[1277004401] 'process raft request' (duration: 113.534683ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:23:36.749454Z","caller":"traceutil/trace.go:171","msg":"trace[1074927982] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"104.416709ms","start":"2026-10-13T15:23:36.645001Z","end":"2026-10-13T15:23:36.749418Z","steps":["trace[1074927982] 'process raft request' (duration: 104.286096ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:23:38.426621Z","caller":"traceutil/trace.go:171","msg":"trace[949979022] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"440.524333ms","start":"2026-10-13T15:23:37.986084Z","end":"2026-10-13T15:23:38.426609Z","steps":["trace[949979022] 'process raft request' (duration: 439.867659ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:23:38.426966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.35022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10870"} {"level":"info","ts":"2026-10-13T15:23:38.427062Z","caller":"traceutil/trace.go:171","msg":"trace[1005790392] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:624; }","duration":"440.473762ms","start":"2026-10-13T15:23:37.986564Z","end":"2026-10-13T15:23:38.427037Z","steps":["trace[1005790392] 'agreement among raft nodes before linearized reading' (duration: 440.153866ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:23:38.427109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:23:37.986547Z","time spent":"440.551955ms","remote":"127.0.0.1:58986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":10893,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-10-13T15:23:38.427193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:23:37.986065Z","time spent":"440.620076ms","remote":"127.0.0.1:58986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:23:38.42656Z","caller":"traceutil/trace.go:171","msg":"trace[798156631] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"439.949472ms","start":"2026-10-13T15:23:37.986588Z","end":"2026-10-13T15:23:38.426537Z","steps":["trace[798156631] 'read index received' (duration: 439.141174ms)","trace[798156631] 'applied index is now lower than readState.Index' (duration: 807.198µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:23:38.435771Z","caller":"traceutil/trace.go:171","msg":"trace[251323896] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"445.126522ms","start":"2026-10-13T15:23:37.990626Z","end":"2026-10-13T15:23:38.435753Z","steps":["trace[251323896] 'process raft request' (duration: 444.708553ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:23:38.435892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:23:37.990607Z","time spent":"445.219234ms","remote":"127.0.0.1:59168","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-10-13T15:23:38.436375Z","caller":"traceutil/trace.go:171","msg":"trace[1214347427] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"421.075297ms","start":"2026-10-13T15:23:38.015262Z","end":"2026-10-13T15:23:38.436338Z","steps":["trace[1214347427] 'process raft request' (duration: 420.207728ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:23:38.436636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:23:38.015236Z","time spent":"421.204429ms","remote":"127.0.0.1:58986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:23:38.437096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.862817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T15:23:38.437171Z","caller":"traceutil/trace.go:171","msg":"trace[431157518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"208.965699ms","start":"2026-10-13T15:23:38.228186Z","end":"2026-10-13T15:23:38.437152Z","steps":["trace[431157518] 'agreement among raft nodes before linearized reading' (duration: 207.837604ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:23:43.066233Z","caller":"traceutil/trace.go:171","msg":"trace[1750697268] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"383.798667ms","start":"2026-10-13T15:23:42.682405Z","end":"2026-10-13T15:23:43.066204Z","steps":["trace[1750697268] 'process raft request' (duration: 383.102183ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:23:43.066405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:23:42.682376Z","time spent":"383.938671ms","remote":"127.0.0.1:59030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:23:43.635659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.921174ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-13T15:23:43.63577Z","caller":"traceutil/trace.go:171","msg":"trace[1836449268] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"235.416797ms","start":"2026-10-13T15:23:43.400336Z","end":"2026-10-13T15:23:43.635753Z","steps":["trace[1836449268] 'read index received' (duration: 85.110525ms)","trace[1836449268] 'applied index is now lower than readState.Index' (duration: 150.304572ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:23:43.635851Z","caller":"traceutil/trace.go:171","msg":"trace[1275767540] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"482.197906ms","start":"2026-10-13T15:23:43.153643Z","end":"2026-10-13T15:23:43.635841Z","steps":["trace[1275767540] 'process raft request' (duration: 331.771442ms)","trace[1275767540] 'compare' (duration: 149.688819ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:23:43.635905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:23:43.153618Z","time spent":"482.260797ms","remote":"127.0.0.1:59256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-13T15:23:43.636293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.955398ms","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-10-13T15:23:43.636334Z","caller":"traceutil/trace.go:171","msg":"trace[1982040710] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:652; }","duration":"236.03837ms","start":"2026-10-13T15:23:43.400284Z","end":"2026-10-13T15:23:43.636322Z","steps":["trace[1982040710] 'agreement among raft nodes before linearized reading' (duration: 235.846555ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:23:43.636369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.581792ms","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-10-13T15:23:43.636499Z","caller":"traceutil/trace.go:171","msg":"trace[2034403631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:652; }","duration":"215.736325ms","start":"2026-10-13T15:23:43.420712Z","end":"2026-10-13T15:23:43.636449Z","steps":["trace[2034403631] 'agreement among raft nodes before linearized reading' (duration: 215.477999ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:23:44.727652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10595739801015020136,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-13T15:23:44.787214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.229982ms","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-10-13T15:23:44.787298Z","caller":"traceutil/trace.go:171","msg":"trace[1770716412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:654; }","duration":"799.410247ms","start":"2026-10-13T15:23:43.987865Z","end":"2026-10-13T15:23:44.787276Z","steps":["trace[1770716412] 'range keys from in-memory index tree' (duration: 799.056199ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:23:44.787343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:23:43.987842Z","time spent":"799.489318ms","remote":"127.0.0.1:59168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-13T15:23:45.228153Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10595739801015020136,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-13T15:23:45.332698Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.27777981s","expected-duration":"1s"} {"level":"info","ts":"2026-10-13T15:23:45.333171Z","caller":"traceutil/trace.go:171","msg":"trace[589537301] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"1.278391365s","start":"2026-10-13T15:23:44.054752Z","end":"2026-10-13T15:23:45.333143Z","steps":["trace[589537301] 'process raft request' (duration: 1.278214721s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:23:45.333338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:23:44.054727Z","time spent":"1.278516038s","remote":"127.0.0.1:59168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:23:45.367897Z","caller":"traceutil/trace.go:171","msg":"trace[949713346] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"1.286727493s","start":"2026-10-13T15:23:44.081131Z","end":"2026-10-13T15:23:45.367858Z","steps":["trace[949713346] 'process raft request' (duration: 1.284555687s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:23:45.36808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:23:44.0811Z","time spent":"1.286880097s","remote":"127.0.0.1:59030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:23:45.368177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140620701s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T15:23:45.368208Z","caller":"traceutil/trace.go:171","msg":"trace[1442345279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:657; }","duration":"1.140708272s","start":"2026-10-13T15:23:44.227493Z","end":"2026-10-13T15:23:45.368201Z","steps":["trace[1442345279] 'agreement among raft nodes before linearized reading' (duration: 1.140617781s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:23:45.368227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:23:44.227454Z","time spent":"1.140768775s","remote":"127.0.0.1:58928","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-10-13T15:23:45.368088Z","caller":"traceutil/trace.go:171","msg":"trace[280085507] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:678; }","duration":"1.140516879s","start":"2026-10-13T15:23:44.227552Z","end":"2026-10-13T15:23:45.368069Z","steps":["trace[280085507] 'read index received' (duration: 1.105629451s)","trace[280085507] 'applied index is now lower than readState.Index' (duration: 34.886148ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:23:45.368353Z","caller":"traceutil/trace.go:171","msg":"trace[430640678] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"577.064611ms","start":"2026-10-13T15:23:44.791281Z","end":"2026-10-13T15:23:45.368345Z","steps":["trace[430640678] 'process raft request' (duration: 576.487869ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:23:45.36853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.543236ms","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-10-13T15:23:45.368549Z","caller":"traceutil/trace.go:171","msg":"trace[847415432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:657; }","duration":"908.563307ms","start":"2026-10-13T15:23:44.45998Z","end":"2026-10-13T15:23:45.368543Z","steps":["trace[847415432] 'agreement among raft nodes before linearized reading' (duration: 908.453514ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:23:45.368537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:23:44.791256Z","time spent":"577.246346ms","remote":"127.0.0.1:59168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:23:45.368565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:23:44.459926Z","time spent":"908.635128ms","remote":"127.0.0.1:59168","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-10-13T15:23:45.368701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.51669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.93\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-13T15:23:45.368772Z","caller":"traceutil/trace.go:171","msg":"trace[655124933] range","detail":"{range_begin:/registry/masterleases/162.253.55.93; range_end:; response_count:1; response_revision:657; }","duration":"807.629913ms","start":"2026-10-13T15:23:44.561124Z","end":"2026-10-13T15:23:45.368754Z","steps":["trace[655124933] 'agreement among raft nodes before linearized reading' (duration: 807.47645ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:23:45.368823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:23:44.561102Z","time spent":"807.709505ms","remote":"127.0.0.1:58968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.93\" "} {"level":"info","ts":"2026-10-13T15:23:45.81068Z","caller":"traceutil/trace.go:171","msg":"trace[1603957071] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"118.119472ms","start":"2026-10-13T15:23:45.692529Z","end":"2026-10-13T15:23:45.810649Z","steps":["trace[1603957071] 'process raft request' (duration: 109.63226ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:23:46.99159Z","caller":"traceutil/trace.go:171","msg":"trace[803041110] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"332.932497ms","start":"2026-10-13T15:23:46.658636Z","end":"2026-10-13T15:23:46.991568Z","steps":["trace[803041110] 'process raft request' (duration: 332.777114ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:23:46.99175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:23:46.658619Z","time spent":"333.05561ms","remote":"127.0.0.1:59016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-13T15:23:57.855669Z","caller":"traceutil/trace.go:171","msg":"trace[244238069] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"137.555149ms","start":"2026-10-13T15:23:57.718091Z","end":"2026-10-13T15:23:57.855647Z","steps":["trace[244238069] 'process raft request' (duration: 137.417926ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:24:01.018925Z","caller":"traceutil/trace.go:171","msg":"trace[1058219564] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"383.95862ms","start":"2026-10-13T15:24:00.634935Z","end":"2026-10-13T15:24:01.018894Z","steps":["trace[1058219564] 'process raft request' (duration: 383.783057ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:24:01.019113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:24:00.634908Z","time spent":"384.110515ms","remote":"127.0.0.1:59148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:24:42.060778Z","caller":"traceutil/trace.go:171","msg":"trace[828193904] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1150; }","duration":"392.313446ms","start":"2026-10-13T15:24:41.668444Z","end":"2026-10-13T15:24:42.060757Z","steps":["trace[828193904] 'read index received' (duration: 392.199703ms)","trace[828193904] 'applied index is now lower than readState.Index' (duration: 112.723µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:24:42.060873Z","caller":"traceutil/trace.go:171","msg":"trace[1003422052] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"673.772097ms","start":"2026-10-13T15:24:41.387065Z","end":"2026-10-13T15:24:42.060837Z","steps":["trace[1003422052] 'process raft request' (duration: 673.532192ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:24:42.061013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.583081ms","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-10-13T15:24:42.061056Z","caller":"traceutil/trace.go:171","msg":"trace[559815941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1112; }","duration":"392.676213ms","start":"2026-10-13T15:24:41.668367Z","end":"2026-10-13T15:24:42.061043Z","steps":["trace[559815941] 'agreement among raft nodes before linearized reading' (duration: 392.506699ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:24:42.061087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:24:41.668344Z","time spent":"392.733945ms","remote":"127.0.0.1:59168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-13T15:24:42.061079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:24:41.387047Z","time spent":"673.920011ms","remote":"127.0.0.1:59168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:24:42.56185Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10595739801015021138,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-13T15:24:42.855763Z","caller":"traceutil/trace.go:171","msg":"trace[1173467610] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"927.605787ms","start":"2026-10-13T15:24:41.928134Z","end":"2026-10-13T15:24:42.85574Z","steps":["trace[1173467610] 'process raft request' (duration: 927.471384ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:24:42.855909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:24:41.928109Z","time spent":"927.7219ms","remote":"127.0.0.1:59168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:24:42.85703Z","caller":"traceutil/trace.go:171","msg":"trace[351221748] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1151; }","duration":"796.164244ms","start":"2026-10-13T15:24:42.06085Z","end":"2026-10-13T15:24:42.857014Z","steps":["trace[351221748] 'read index received' (duration: 794.787844ms)","trace[351221748] 'applied index is now lower than readState.Index' (duration: 1.37518ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:24:42.857154Z","caller":"traceutil/trace.go:171","msg":"trace[1902321511] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"792.455783ms","start":"2026-10-13T15:24:42.064657Z","end":"2026-10-13T15:24:42.857113Z","steps":["trace[1902321511] 'process raft request' (duration: 791.907401ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:24:42.857222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158418982s","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-10-13T15:24:42.857259Z","caller":"traceutil/trace.go:171","msg":"trace[2084601536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1114; }","duration":"1.158495954s","start":"2026-10-13T15:24:41.698752Z","end":"2026-10-13T15:24:42.857248Z","steps":["trace[2084601536] 'agreement among raft nodes before linearized reading' (duration: 1.158382831s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:24:42.857285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:24:41.69873Z","time spent":"1.158549634s","remote":"127.0.0.1:59168","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-10-13T15:24:42.857323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:24:42.064595Z","time spent":"792.629146ms","remote":"127.0.0.1:59168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:24:42.85731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.913722ms","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-10-13T15:24:42.857374Z","caller":"traceutil/trace.go:171","msg":"trace[171418884] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1114; }","duration":"760.024264ms","start":"2026-10-13T15:24:42.097338Z","end":"2026-10-13T15:24:42.857363Z","steps":["trace[171418884] 'agreement among raft nodes before linearized reading' (duration: 759.81061ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:24:42.857393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.584332ms","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-10-13T15:24:42.857411Z","caller":"traceutil/trace.go:171","msg":"trace[1793116333] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1114; }","duration":"747.632343ms","start":"2026-10-13T15:24:42.109774Z","end":"2026-10-13T15:24:42.857406Z","steps":["trace[1793116333] 'agreement among raft nodes before linearized reading' (duration: 747.582152ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:24:42.857407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:24:42.097318Z","time spent":"760.079235ms","remote":"127.0.0.1:59168","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-10-13T15:24:42.857443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:24:42.10975Z","time spent":"747.689834ms","remote":"127.0.0.1:59168","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-10-13T15:24:42.858869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.040374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T15:24:42.858934Z","caller":"traceutil/trace.go:171","msg":"trace[1499953497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1114; }","duration":"632.805803ms","start":"2026-10-13T15:24:42.226111Z","end":"2026-10-13T15:24:42.858917Z","steps":["trace[1499953497] 'agreement among raft nodes before linearized reading' (duration: 631.040215ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:24:42.858975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:24:42.226094Z","time spent":"632.868704ms","remote":"127.0.0.1:58928","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-10-13T15:24:56.360109Z","caller":"traceutil/trace.go:171","msg":"trace[547917916] linearizableReadLoop","detail":"{readStateIndex:1200; appliedIndex:1199; }","duration":"132.383004ms","start":"2026-10-13T15:24:56.227707Z","end":"2026-10-13T15:24:56.36009Z","steps":["trace[547917916] 'read index received' (duration: 132.242861ms)","trace[547917916] 'applied index is now lower than readState.Index' (duration: 139.203µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:24:56.360165Z","caller":"traceutil/trace.go:171","msg":"trace[232631814] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"209.708014ms","start":"2026-10-13T15:24:56.15043Z","end":"2026-10-13T15:24:56.360138Z","steps":["trace[232631814] 'process raft request' (duration: 209.541001ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:24:56.36025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.535577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T15:24:56.360299Z","caller":"traceutil/trace.go:171","msg":"trace[614925256] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1158; }","duration":"132.622729ms","start":"2026-10-13T15:24:56.227651Z","end":"2026-10-13T15:24:56.360274Z","steps":["trace[614925256] 'agreement among raft nodes before linearized reading' (duration: 132.558268ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:24:58.731052Z","caller":"traceutil/trace.go:171","msg":"trace[1220419306] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"362.34544ms","start":"2026-10-13T15:24:58.368678Z","end":"2026-10-13T15:24:58.731023Z","steps":["trace[1220419306] 'process raft request' (duration: 362.177537ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:24:58.731368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:24:58.368599Z","time spent":"362.522143ms","remote":"127.0.0.1:59168","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-10-13T15:24:58.810749Z","caller":"traceutil/trace.go:171","msg":"trace[1297224286] linearizableReadLoop","detail":"{readStateIndex:1208; appliedIndex:1207; }","duration":"234.466605ms","start":"2026-10-13T15:24:58.576262Z","end":"2026-10-13T15:24:58.810729Z","steps":["trace[1297224286] 'read index received' (duration: 155.136371ms)","trace[1297224286] 'applied index is now lower than readState.Index' (duration: 79.329174ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:24:58.810961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.676699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-13T15:24:58.81099Z","caller":"traceutil/trace.go:171","msg":"trace[1005931927] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1166; }","duration":"238.007543ms","start":"2026-10-13T15:24:58.572973Z","end":"2026-10-13T15:24:58.810981Z","steps":["trace[1005931927] 'agreement among raft nodes before linearized reading' (duration: 237.904651ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:24:58.811479Z","caller":"traceutil/trace.go:171","msg":"trace[1654747528] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"437.064703ms","start":"2026-10-13T15:24:58.374405Z","end":"2026-10-13T15:24:58.811469Z","steps":["trace[1654747528] 'process raft request' (duration: 436.045231ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:24:58.81155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:24:58.374381Z","time spent":"437.126415ms","remote":"127.0.0.1:59168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:24:59.174194Z","caller":"traceutil/trace.go:171","msg":"trace[103796293] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"129.822478ms","start":"2026-10-13T15:24:59.044355Z","end":"2026-10-13T15:24:59.174178Z","steps":["trace[103796293] 'process raft request' (duration: 70.107122ms)","trace[103796293] 'compare' (duration: 59.531482ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:24:59.174191Z","caller":"traceutil/trace.go:171","msg":"trace[2117541308] linearizableReadLoop","detail":"{readStateIndex:1213; appliedIndex:1211; }","duration":"114.999223ms","start":"2026-10-13T15:24:59.059168Z","end":"2026-10-13T15:24:59.174167Z","steps":["trace[2117541308] 'read index received' (duration: 55.202416ms)","trace[2117541308] 'applied index is now lower than readState.Index' (duration: 59.795807ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:24:59.174437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.225898ms","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-10-13T15:24:59.1745Z","caller":"traceutil/trace.go:171","msg":"trace[452879508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1170; }","duration":"115.341551ms","start":"2026-10-13T15:24:59.059141Z","end":"2026-10-13T15:24:59.174483Z","steps":["trace[452879508] 'agreement among raft nodes before linearized reading' (duration: 115.095956ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:24:59.174531Z","caller":"traceutil/trace.go:171","msg":"trace[1465110233] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"129.233985ms","start":"2026-10-13T15:24:59.04529Z","end":"2026-10-13T15:24:59.174524Z","steps":["trace[1465110233] 'process raft request' (duration: 128.837587ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:24:59.174519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.30029ms","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-10-13T15:24:59.174568Z","caller":"traceutil/trace.go:171","msg":"trace[843689163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1170; }","duration":"115.363832ms","start":"2026-10-13T15:24:59.059191Z","end":"2026-10-13T15:24:59.174555Z","steps":["trace[843689163] 'agreement among raft nodes before linearized reading' (duration: 115.229619ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:24:59.322202Z","caller":"traceutil/trace.go:171","msg":"trace[1074753778] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"144.155871ms","start":"2026-10-13T15:24:59.178023Z","end":"2026-10-13T15:24:59.322179Z","steps":["trace[1074753778] 'process raft request' (duration: 108.555152ms)","trace[1074753778] 'compare' (duration: 35.456855ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:24:59.322649Z","caller":"traceutil/trace.go:171","msg":"trace[1370854579] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"144.465848ms","start":"2026-10-13T15:24:59.178161Z","end":"2026-10-13T15:24:59.322626Z","steps":["trace[1370854579] 'process raft request' (duration: 143.982547ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:25:00.752532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.50781ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-13T15:25:00.752793Z","caller":"traceutil/trace.go:171","msg":"trace[820082725] linearizableReadLoop","detail":"{readStateIndex:1217; appliedIndex:1215; }","duration":"665.531898ms","start":"2026-10-13T15:25:00.087238Z","end":"2026-10-13T15:25:00.75277Z","steps":["trace[820082725] 'read index received' (duration: 78.833553ms)","trace[820082725] 'applied index is now lower than readState.Index' (duration: 586.697215ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:25:00.752951Z","caller":"traceutil/trace.go:171","msg":"trace[158771462] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"797.294718ms","start":"2026-10-13T15:24:59.955616Z","end":"2026-10-13T15:25:00.75291Z","steps":["trace[158771462] 'process raft request' (duration: 797.034232ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:25:00.753079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:24:59.955592Z","time spent":"797.429012ms","remote":"127.0.0.1:59016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-13T15:25:00.753216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.054684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-13T15:25:00.753291Z","caller":"traceutil/trace.go:171","msg":"trace[247644361] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1173; }","duration":"572.163667ms","start":"2026-10-13T15:25:00.181108Z","end":"2026-10-13T15:25:00.753272Z","steps":["trace[247644361] 'agreement among raft nodes before linearized reading' (duration: 571.998854ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:25:00.753341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:25:00.181093Z","time spent":"572.234568ms","remote":"127.0.0.1:59126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-10-13T15:25:00.753401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.371803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T15:25:00.753468Z","caller":"traceutil/trace.go:171","msg":"trace[2004818661] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1173; }","duration":"524.436904ms","start":"2026-10-13T15:25:00.22902Z","end":"2026-10-13T15:25:00.753457Z","steps":["trace[2004818661] 'agreement among raft nodes before linearized reading' (duration: 524.350302ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:25:00.753494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:25:00.22896Z","time spent":"524.527036ms","remote":"127.0.0.1:58928","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-10-13T15:25:00.753757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.272754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"info","ts":"2026-10-13T15:25:00.753822Z","caller":"traceutil/trace.go:171","msg":"trace[530736802] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1173; }","duration":"666.603812ms","start":"2026-10-13T15:25:00.087203Z","end":"2026-10-13T15:25:00.753806Z","steps":["trace[530736802] 'agreement among raft nodes before linearized reading' (duration: 665.690941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:25:00.753864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:25:00.087187Z","time spent":"666.663492ms","remote":"127.0.0.1:59106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5353,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-10-13T15:25:00.996545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.894763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-13T15:25:00.996682Z","caller":"traceutil/trace.go:171","msg":"trace[493994416] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"164.314933ms","start":"2026-10-13T15:25:00.832347Z","end":"2026-10-13T15:25:00.996662Z","steps":["trace[493994416] 'process raft request' (duration: 59.237095ms)","trace[493994416] 'compare' (duration: 104.676238ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:25:01.941695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.783866ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T15:25:01.941799Z","caller":"traceutil/trace.go:171","msg":"trace[2116699885] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"178.698757ms","start":"2026-10-13T15:25:01.763083Z","end":"2026-10-13T15:25:01.941782Z","steps":["trace[2116699885] 'read index received' (duration: 36.101µs)","trace[2116699885] 'applied index is now lower than readState.Index' (duration: 178.661316ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:25:01.941876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.799019ms","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-10-13T15:25:01.94191Z","caller":"traceutil/trace.go:171","msg":"trace[420789390] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1181; }","duration":"178.87881ms","start":"2026-10-13T15:25:01.76302Z","end":"2026-10-13T15:25:01.941899Z","steps":["trace[420789390] 'agreement among raft nodes before linearized reading' (duration: 178.808169ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:25:01.941879Z","caller":"traceutil/trace.go:171","msg":"trace[2083036575] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"590.334114ms","start":"2026-10-13T15:25:01.351498Z","end":"2026-10-13T15:25:01.941832Z","steps":["trace[2083036575] 'process raft request' (duration: 186.320982ms)","trace[2083036575] 'compare' (duration: 403.662564ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:25:01.942084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:25:01.351491Z","time spent":"590.492008ms","remote":"127.0.0.1:59168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:25:02.338817Z","caller":"traceutil/trace.go:171","msg":"trace[1652581070] linearizableReadLoop","detail":"{readStateIndex:1226; appliedIndex:1225; }","duration":"110.540597ms","start":"2026-10-13T15:25:02.228247Z","end":"2026-10-13T15:25:02.338788Z","steps":["trace[1652581070] 'read index received' (duration: 110.429994ms)","trace[1652581070] 'applied index is now lower than readState.Index' (duration: 109.383µs)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:25:02.338993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.70781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T15:25:02.339045Z","caller":"traceutil/trace.go:171","msg":"trace[2033711341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1182; }","duration":"110.832463ms","start":"2026-10-13T15:25:02.228198Z","end":"2026-10-13T15:25:02.33903Z","steps":["trace[2033711341] 'agreement among raft nodes before linearized reading' (duration: 110.69851ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:25:02.339331Z","caller":"traceutil/trace.go:171","msg":"trace[1717553547] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"192.60573ms","start":"2026-10-13T15:25:02.146709Z","end":"2026-10-13T15:25:02.339315Z","steps":["trace[1717553547] 'process raft request' (duration: 191.910905ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:25:13.261471Z","caller":"traceutil/trace.go:171","msg":"trace[64882812] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"379.747631ms","start":"2026-10-13T15:25:12.881699Z","end":"2026-10-13T15:25:13.261447Z","steps":["trace[64882812] 'process raft request' (duration: 379.575947ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:25:13.26163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:25:12.881673Z","time spent":"379.875823ms","remote":"127.0.0.1:59168","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-10-13T15:25:13.399041Z","caller":"traceutil/trace.go:171","msg":"trace[113246563] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1320; }","duration":"169.68908ms","start":"2026-10-13T15:25:13.229309Z","end":"2026-10-13T15:25:13.398998Z","steps":["trace[113246563] 'read index received' (duration: 32.127693ms)","trace[113246563] 'applied index is now lower than readState.Index' (duration: 137.559777ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:25:13.399211Z","caller":"traceutil/trace.go:171","msg":"trace[1178317922] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"333.237574ms","start":"2026-10-13T15:25:13.065956Z","end":"2026-10-13T15:25:13.399193Z","steps":["trace[1178317922] 'process raft request' (duration: 233.196666ms)","trace[1178317922] 'compare' (duration: 99.373963ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:25:13.399346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:25:13.065936Z","time spent":"333.315046ms","remote":"127.0.0.1:59168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:25:13.399528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.228902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T15:25:13.399564Z","caller":"traceutil/trace.go:171","msg":"trace[831563056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1275; }","duration":"170.297573ms","start":"2026-10-13T15:25:13.229256Z","end":"2026-10-13T15:25:13.399554Z","steps":["trace[831563056] 'agreement among raft nodes before linearized reading' (duration: 170.229112ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:25:14.442552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.961211ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T15:25:14.442675Z","caller":"traceutil/trace.go:171","msg":"trace[1188843053] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1321; }","duration":"1.007645286s","start":"2026-10-13T15:25:13.435007Z","end":"2026-10-13T15:25:14.442653Z","steps":["trace[1188843053] 'read index received' (duration: 8.106007ms)","trace[1188843053] 'applied index is now lower than readState.Index' (duration: 999.537809ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:25:14.442764Z","caller":"traceutil/trace.go:171","msg":"trace[1829306401] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"1.037163662s","start":"2026-10-13T15:25:13.405561Z","end":"2026-10-13T15:25:14.442725Z","steps":["trace[1829306401] 'process raft request' (duration: 863.934105ms)","trace[1829306401] 'compare' (duration: 172.812178ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:25:14.442854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:25:13.405533Z","time spent":"1.037276964s","remote":"127.0.0.1:59168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:25:14.442909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007905671s","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-10-13T15:25:14.442959Z","caller":"traceutil/trace.go:171","msg":"trace[1674339583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1277; }","duration":"1.008011293s","start":"2026-10-13T15:25:13.434934Z","end":"2026-10-13T15:25:14.442945Z","steps":["trace[1674339583] 'agreement among raft nodes before linearized reading' (duration: 1.007823779s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:25:14.443006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:25:13.434906Z","time spent":"1.008089125s","remote":"127.0.0.1:59168","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-10-13T15:25:14.443455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.761248ms","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:530"} {"level":"warn","ts":"2026-10-13T15:25:14.443421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.239518ms","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-10-13T15:25:14.443505Z","caller":"traceutil/trace.go:171","msg":"trace[545054670] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1277; }","duration":"297.83357ms","start":"2026-10-13T15:25:14.145658Z","end":"2026-10-13T15:25:14.443492Z","steps":["trace[545054670] 'agreement among raft nodes before linearized reading' (duration: 297.684667ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:25:14.443565Z","caller":"traceutil/trace.go:171","msg":"trace[1040397291] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1277; }","duration":"922.412992ms","start":"2026-10-13T15:25:13.521089Z","end":"2026-10-13T15:25:14.443502Z","steps":["trace[1040397291] 'agreement among raft nodes before linearized reading' (duration: 922.012474ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:25:14.443641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.101351ms","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-10-13T15:25:14.443681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:25:13.521055Z","time spent":"922.611748ms","remote":"127.0.0.1:59168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-10-13T15:25:14.443767Z","caller":"traceutil/trace.go:171","msg":"trace[1251584182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1277; }","duration":"446.287475ms","start":"2026-10-13T15:25:13.99745Z","end":"2026-10-13T15:25:14.443737Z","steps":["trace[1251584182] 'agreement among raft nodes before linearized reading' (duration: 445.902446ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:25:14.44383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:25:13.997427Z","time spent":"446.392388ms","remote":"127.0.0.1:59168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-13T15:29:54.389477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.982093ms","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-10-13T15:29:54.389572Z","caller":"traceutil/trace.go:171","msg":"trace[300126596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2587; }","duration":"220.127836ms","start":"2026-10-13T15:29:54.169419Z","end":"2026-10-13T15:29:54.389547Z","steps":["trace[300126596] 'range keys from in-memory index tree' (duration: 219.775638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:29:54.38979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.671038ms","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-10-13T15:29:54.389838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.300408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T15:29:54.389898Z","caller":"traceutil/trace.go:171","msg":"trace[1338155851] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2587; }","duration":"133.810491ms","start":"2026-10-13T15:29:54.256058Z","end":"2026-10-13T15:29:54.389868Z","steps":["trace[1338155851] 'range keys from in-memory index tree' (duration: 133.485205ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:29:54.389952Z","caller":"traceutil/trace.go:171","msg":"trace[1435536990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2587; }","duration":"161.505053ms","start":"2026-10-13T15:29:54.228419Z","end":"2026-10-13T15:29:54.389924Z","steps":["trace[1435536990] 'range keys from in-memory index tree' (duration: 161.182646ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:29:54.39005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.499456ms","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-10-13T15:29:54.390101Z","caller":"traceutil/trace.go:171","msg":"trace[1079152920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2587; }","duration":"201.580317ms","start":"2026-10-13T15:29:54.188507Z","end":"2026-10-13T15:29:54.390087Z","steps":["trace[1079152920] 'range keys from in-memory index tree' (duration: 201.297509ms)"],"step_count":1}