{"level":"warn","ts":"2026-08-06T00:57:30.487305Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-06T00:57:30.487402Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.128: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.128:2380","--initial-cluster=instance=https://199.19.213.128:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.128:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.128: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-08-06T00:57:30.487482Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-06T00:57:30.4875Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.128:2380"]} {"level":"info","ts":"2026-08-06T00:57:30.487525Z","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-08-06T00:57:30.488429Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.128:2379"]} {"level":"info","ts":"2026-08-06T00:57:30.488534Z","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.128:2380"],"listen-peer-urls":["https://199.19.213.128:2380"],"advertise-client-urls":["https://199.19.213.128:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.128:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.128: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-08-06T00:57:30.494028Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.227689ms"} {"level":"info","ts":"2026-08-06T00:57:30.503544Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a463a6aa9b462de2","cluster-id":"cd3d14b8ed85e2f2"} {"level":"info","ts":"2026-08-06T00:57:30.50366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 switched to configuration voters=()"} {"level":"info","ts":"2026-08-06T00:57:30.503687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became follower at term 0"} {"level":"info","ts":"2026-08-06T00:57:30.503703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a463a6aa9b462de2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-06T00:57:30.50371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became follower at term 1"} {"level":"info","ts":"2026-08-06T00:57:30.503742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 switched to configuration voters=(11845494696594255330)"} {"level":"warn","ts":"2026-08-06T00:57:30.512695Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-06T00:57:30.5142Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-06T00:57:30.515883Z","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-08-06T00:57:30.520611Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a463a6aa9b462de2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-06T00:57:30.520947Z","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-08-06T00:57:30.521025Z","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-08-06T00:57:30.521036Z","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-08-06T00:57:30.521248Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a463a6aa9b462de2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-06T00:57:30.521752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 switched to configuration voters=(11845494696594255330)"} {"level":"info","ts":"2026-08-06T00:57:30.521856Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cd3d14b8ed85e2f2","local-member-id":"a463a6aa9b462de2","added-peer-id":"a463a6aa9b462de2","added-peer-peer-urls":["https://199.19.213.128:2380"]} {"level":"info","ts":"2026-08-06T00:57:30.533494Z","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-08-06T00:57:30.53378Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a463a6aa9b462de2","initial-advertise-peer-urls":["https://199.19.213.128:2380"],"listen-peer-urls":["https://199.19.213.128:2380"],"advertise-client-urls":["https://199.19.213.128:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.128:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-06T00:57:30.533828Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-06T00:57:30.533912Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.128:2380"} {"level":"info","ts":"2026-08-06T00:57:30.533933Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.128:2380"} {"level":"info","ts":"2026-08-06T00:57:31.504019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 is starting a new election at term 1"} {"level":"info","ts":"2026-08-06T00:57:31.504088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-06T00:57:31.504127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 received MsgPreVoteResp from a463a6aa9b462de2 at term 1"} {"level":"info","ts":"2026-08-06T00:57:31.504147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became candidate at term 2"} {"level":"info","ts":"2026-08-06T00:57:31.504163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 received MsgVoteResp from a463a6aa9b462de2 at term 2"} {"level":"info","ts":"2026-08-06T00:57:31.504178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became leader at term 2"} {"level":"info","ts":"2026-08-06T00:57:31.504197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a463a6aa9b462de2 elected leader a463a6aa9b462de2 at term 2"} {"level":"info","ts":"2026-08-06T00:57:31.505194Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T00:57:31.505997Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a463a6aa9b462de2","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.128:2379]}","request-path":"/0/members/a463a6aa9b462de2/attributes","cluster-id":"cd3d14b8ed85e2f2","publish-timeout":"7s"} {"level":"info","ts":"2026-08-06T00:57:31.506004Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-06T00:57:31.506209Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-06T00:57:31.506309Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-06T00:57:31.506362Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-06T00:57:31.506608Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cd3d14b8ed85e2f2","local-member-id":"a463a6aa9b462de2","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T00:57:31.506706Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T00:57:31.50673Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T00:57:31.508395Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-06T00:57:31.509181Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.128:2379"} {"level":"info","ts":"2026-08-06T00:57:44.466411Z","caller":"traceutil/trace.go:171","msg":"trace[833046344] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:367; }","duration":"122.382032ms","start":"2026-08-06T00:57:44.34401Z","end":"2026-08-06T00:57:44.466392Z","steps":["trace[833046344] 'read index received' (duration: 122.216368ms)","trace[833046344] 'applied index is now lower than readState.Index' (duration: 164.984µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T00:57:44.466565Z","caller":"traceutil/trace.go:171","msg":"trace[1034356079] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"145.264656ms","start":"2026-08-06T00:57:44.321291Z","end":"2026-08-06T00:57:44.466555Z","steps":["trace[1034356079] 'process raft request' (duration: 144.985369ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:57:44.466889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.839242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.128\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-06T00:57:44.466947Z","caller":"traceutil/trace.go:171","msg":"trace[1225094418] range","detail":"{range_begin:/registry/masterleases/199.19.213.128; range_end:; response_count:1; response_revision:357; }","duration":"122.959714ms","start":"2026-08-06T00:57:44.343977Z","end":"2026-08-06T00:57:44.466937Z","steps":["trace[1225094418] 'agreement among raft nodes before linearized reading' (duration: 122.525794ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:57:50.073855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.636071ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-06T00:57:50.777114Z","caller":"traceutil/trace.go:171","msg":"trace[271864545] linearizableReadLoop","detail":"{readStateIndex:390; appliedIndex:389; }","duration":"160.410151ms","start":"2026-08-06T00:57:50.616686Z","end":"2026-08-06T00:57:50.777096Z","steps":["trace[271864545] 'read index received' (duration: 160.260797ms)","trace[271864545] 'applied index is now lower than readState.Index' (duration: 148.774µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T00:57:50.777168Z","caller":"traceutil/trace.go:171","msg":"trace[1035345223] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"287.820853ms","start":"2026-08-06T00:57:50.489323Z","end":"2026-08-06T00:57:50.777144Z","steps":["trace[1035345223] 'process raft request' (duration: 287.664439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:57:50.777224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.515623ms","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-08-06T00:57:50.777251Z","caller":"traceutil/trace.go:171","msg":"trace[1344019741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:377; }","duration":"160.614345ms","start":"2026-08-06T00:57:50.616628Z","end":"2026-08-06T00:57:50.777242Z","steps":["trace[1344019741] 'agreement among raft nodes before linearized reading' (duration: 160.538363ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:57:51.098745Z","caller":"traceutil/trace.go:171","msg":"trace[1890105917] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:390; }","duration":"139.362236ms","start":"2026-08-06T00:57:50.959356Z","end":"2026-08-06T00:57:51.098718Z","steps":["trace[1890105917] 'read index received' (duration: 137.060064ms)","trace[1890105917] 'applied index is now lower than readState.Index' (duration: 2.301062ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T00:57:51.098826Z","caller":"traceutil/trace.go:171","msg":"trace[52307265] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"317.138762ms","start":"2026-08-06T00:57:50.781663Z","end":"2026-08-06T00:57:51.098802Z","steps":["trace[52307265] 'process raft request' (duration: 314.813659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:57:51.098976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.599852ms","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-08-06T00:57:51.099095Z","caller":"traceutil/trace.go:171","msg":"trace[368824415] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:378; }","duration":"139.726815ms","start":"2026-08-06T00:57:50.959344Z","end":"2026-08-06T00:57:51.099071Z","steps":["trace[368824415] 'agreement among raft nodes before linearized reading' (duration: 139.51548ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:57:51.099341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:57:50.781647Z","time spent":"317.240255ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T00:58:05.329433Z","caller":"traceutil/trace.go:171","msg":"trace[1050307926] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"106.595984ms","start":"2026-08-06T00:58:05.222811Z","end":"2026-08-06T00:58:05.329407Z","steps":["trace[1050307926] 'process raft request' (duration: 66.434465ms)","trace[1050307926] 'compare' (duration: 39.957024ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T00:58:05.329653Z","caller":"traceutil/trace.go:171","msg":"trace[1093165185] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"106.523141ms","start":"2026-08-06T00:58:05.223105Z","end":"2026-08-06T00:58:05.329628Z","steps":["trace[1093165185] 'process raft request' (duration: 106.228555ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:58:08.920606Z","caller":"traceutil/trace.go:171","msg":"trace[771193984] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"587.194935ms","start":"2026-08-06T00:58:08.33339Z","end":"2026-08-06T00:58:08.920585Z","steps":["trace[771193984] 'process raft request' (duration: 586.203726ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:58:08.920735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:58:08.33336Z","time spent":"587.310388ms","remote":"127.0.0.1:40186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7684,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T00:58:08.925664Z","caller":"traceutil/trace.go:171","msg":"trace[271226124] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:547; }","duration":"476.106096ms","start":"2026-08-06T00:58:08.449518Z","end":"2026-08-06T00:58:08.925625Z","steps":["trace[271226124] 'read index received' (duration: 470.145607ms)","trace[271226124] 'applied index is now lower than readState.Index' (duration: 5.959769ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T00:58:08.925688Z","caller":"traceutil/trace.go:171","msg":"trace[644146210] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"590.356359ms","start":"2026-08-06T00:58:08.335314Z","end":"2026-08-06T00:58:08.92567Z","steps":["trace[644146210] 'process raft request' (duration: 589.850409ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:58:08.925717Z","caller":"traceutil/trace.go:171","msg":"trace[352580294] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"553.059383ms","start":"2026-08-06T00:58:08.372626Z","end":"2026-08-06T00:58:08.925685Z","steps":["trace[352580294] 'process raft request' (duration: 552.948861ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:58:08.925777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.252379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T00:58:08.925782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:58:08.33529Z","time spent":"590.43321ms","remote":"127.0.0.1:40186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T00:58:08.9258Z","caller":"traceutil/trace.go:171","msg":"trace[1251950969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"476.305839ms","start":"2026-08-06T00:58:08.449487Z","end":"2026-08-06T00:58:08.925793Z","steps":["trace[1251950969] 'agreement among raft nodes before linearized reading' (duration: 476.246508ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:58:08.925817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:58:08.449472Z","time spent":"476.34084ms","remote":"127.0.0.1:40132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T00:58:08.925827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:58:08.3726Z","time spent":"553.174976ms","remote":"127.0.0.1:40186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T00:58:09.848957Z","caller":"traceutil/trace.go:171","msg":"trace[560978233] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"203.788117ms","start":"2026-08-06T00:58:09.645137Z","end":"2026-08-06T00:58:09.848925Z","steps":["trace[560978233] 'process raft request' (duration: 197.443291ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:58:09.849504Z","caller":"traceutil/trace.go:171","msg":"trace[847438355] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"156.929976ms","start":"2026-08-06T00:58:09.692557Z","end":"2026-08-06T00:58:09.849487Z","steps":["trace[847438355] 'process raft request' (duration: 156.573999ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:58:15.668397Z","caller":"traceutil/trace.go:171","msg":"trace[1942283079] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"284.113822ms","start":"2026-08-06T00:58:15.384256Z","end":"2026-08-06T00:58:15.66837Z","steps":["trace[1942283079] 'read index received' (duration: 283.246836ms)","trace[1942283079] 'applied index is now lower than readState.Index' (duration: 865.846µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T00:58:15.668525Z","caller":"traceutil/trace.go:171","msg":"trace[1444988901] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"309.808684ms","start":"2026-08-06T00:58:15.358707Z","end":"2026-08-06T00:58:15.668516Z","steps":["trace[1444988901] 'process raft request' (duration: 309.095191ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:58:15.668653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:58:15.358683Z","time spent":"309.873305ms","remote":"127.0.0.1:40234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T00:58:15.670168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.902698ms","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-08-06T00:58:15.670285Z","caller":"traceutil/trace.go:171","msg":"trace[1831462288] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:594; }","duration":"286.00027ms","start":"2026-08-06T00:58:15.384239Z","end":"2026-08-06T00:58:15.670239Z","steps":["trace[1831462288] 'agreement among raft nodes before linearized reading' (duration: 284.775935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:58:15.670567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.370443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T00:58:15.670641Z","caller":"traceutil/trace.go:171","msg":"trace[281554277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"222.031806ms","start":"2026-08-06T00:58:15.448583Z","end":"2026-08-06T00:58:15.670615Z","steps":["trace[281554277] 'agreement among raft nodes before linearized reading' (duration: 220.398124ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:58:15.670729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.749438ms","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-08-06T00:58:15.670831Z","caller":"traceutil/trace.go:171","msg":"trace[1400005645] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:594; }","duration":"270.873161ms","start":"2026-08-06T00:58:15.399906Z","end":"2026-08-06T00:58:15.670779Z","steps":["trace[1400005645] 'agreement among raft nodes before linearized reading' (duration: 270.631246ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:58:16.654511Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380811796384847,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T00:58:16.814548Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.072973465s","expected-duration":"1s"} {"level":"info","ts":"2026-08-06T00:58:16.850987Z","caller":"traceutil/trace.go:171","msg":"trace[1528578487] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"697.431916ms","start":"2026-08-06T00:58:16.153534Z","end":"2026-08-06T00:58:16.850966Z","steps":["trace[1528578487] 'read index received' (duration: 661.184257ms)","trace[1528578487] 'applied index is now lower than readState.Index' (duration: 36.246709ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T00:58:16.851168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.612069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T00:58:16.85126Z","caller":"traceutil/trace.go:171","msg":"trace[1179659887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"697.738342ms","start":"2026-08-06T00:58:16.1535Z","end":"2026-08-06T00:58:16.851238Z","steps":["trace[1179659887] 'agreement among raft nodes before linearized reading' (duration: 697.566599ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:58:16.851094Z","caller":"traceutil/trace.go:171","msg":"trace[489219343] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.114673568s","start":"2026-08-06T00:58:15.736384Z","end":"2026-08-06T00:58:16.851057Z","steps":["trace[489219343] 'process raft request' (duration: 1.078449309s)","trace[489219343] 'compare' (duration: 35.679348ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T00:58:16.85131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:58:16.153483Z","time spent":"697.817313ms","remote":"127.0.0.1:40132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T00:58:16.851198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.268196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T00:58:16.851378Z","caller":"traceutil/trace.go:171","msg":"trace[234156673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"402.49246ms","start":"2026-08-06T00:58:16.448867Z","end":"2026-08-06T00:58:16.851359Z","steps":["trace[234156673] 'agreement among raft nodes before linearized reading' (duration: 402.225215ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:58:16.851462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:58:16.448824Z","time spent":"402.603863ms","remote":"127.0.0.1:40146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T00:58:16.851522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:58:15.736363Z","time spent":"1.114961214s","remote":"127.0.0.1:40372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T00:58:17.470592Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380811796384856,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-06T00:58:17.650068Z","caller":"traceutil/trace.go:171","msg":"trace[1126578607] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"739.706626ms","start":"2026-08-06T00:58:16.910341Z","end":"2026-08-06T00:58:17.650048Z","steps":["trace[1126578607] 'process raft request' (duration: 739.581154ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:58:17.650189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:58:16.910319Z","time spent":"739.817018ms","remote":"127.0.0.1:40216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T00:58:17.668439Z","caller":"traceutil/trace.go:171","msg":"trace[989986783] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:619; }","duration":"698.411659ms","start":"2026-08-06T00:58:16.970004Z","end":"2026-08-06T00:58:17.668416Z","steps":["trace[989986783] 'read index received' (duration: 679.974301ms)","trace[989986783] 'applied index is now lower than readState.Index' (duration: 18.436328ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T00:58:17.668456Z","caller":"traceutil/trace.go:171","msg":"trace[188000696] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"758.044702ms","start":"2026-08-06T00:58:16.910342Z","end":"2026-08-06T00:58:17.668387Z","steps":["trace[188000696] 'process raft request' (duration: 757.824878ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:58:17.66851Z","caller":"traceutil/trace.go:171","msg":"trace[264544623] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"755.862682ms","start":"2026-08-06T00:58:16.912611Z","end":"2026-08-06T00:58:17.668473Z","steps":["trace[264544623] 'process raft request' (duration: 755.729929ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:58:17.668644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:58:16.912594Z","time spent":"755.975274ms","remote":"127.0.0.1:40372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T00:58:17.668652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:58:16.910319Z","time spent":"758.236445ms","remote":"127.0.0.1:40216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T00:58:17.668692Z","caller":"traceutil/trace.go:171","msg":"trace[326995021] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"295.34186ms","start":"2026-08-06T00:58:17.373337Z","end":"2026-08-06T00:58:17.668678Z","steps":["trace[326995021] 'process raft request' (duration: 295.043304ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:58:17.668449Z","caller":"traceutil/trace.go:171","msg":"trace[101185069] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"756.163457ms","start":"2026-08-06T00:58:16.912261Z","end":"2026-08-06T00:58:17.668424Z","steps":["trace[101185069] 'process raft request' (duration: 756.020514ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:58:17.668919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.575183ms","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-08-06T00:58:17.668978Z","caller":"traceutil/trace.go:171","msg":"trace[1949105168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:604; }","duration":"368.671035ms","start":"2026-08-06T00:58:17.30029Z","end":"2026-08-06T00:58:17.668961Z","steps":["trace[1949105168] 'agreement among raft nodes before linearized reading' (duration: 368.490792ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:58:17.668992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:58:16.912248Z","time spent":"756.706017ms","remote":"127.0.0.1:40330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T00:58:17.669021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.746721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T00:58:17.669029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.02396ms","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-08-06T00:58:17.669028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:58:17.300271Z","time spent":"368.743186ms","remote":"127.0.0.1:40386","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":"info","ts":"2026-08-06T00:58:17.669054Z","caller":"traceutil/trace.go:171","msg":"trace[7496097] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"219.800162ms","start":"2026-08-06T00:58:17.44924Z","end":"2026-08-06T00:58:17.66904Z","steps":["trace[7496097] 'agreement among raft nodes before linearized reading' (duration: 219.757612ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:58:17.669062Z","caller":"traceutil/trace.go:171","msg":"trace[1113696231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:604; }","duration":"699.160903ms","start":"2026-08-06T00:58:16.969891Z","end":"2026-08-06T00:58:17.669052Z","steps":["trace[1113696231] 'agreement among raft nodes before linearized reading' (duration: 699.058221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:58:17.669091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:58:16.969875Z","time spent":"699.208894ms","remote":"127.0.0.1:40386","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-08-06T00:58:34.313362Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380811796385165,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T00:58:34.729411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.281398017s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T00:58:34.729525Z","caller":"traceutil/trace.go:171","msg":"trace[160592442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:759; }","duration":"1.281577491s","start":"2026-08-06T00:58:33.44792Z","end":"2026-08-06T00:58:34.729497Z","steps":["trace[160592442] 'range keys from in-memory index tree' (duration: 1.281272575s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:58:34.729574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:58:33.447892Z","time spent":"1.281671052s","remote":"127.0.0.1:40132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T00:58:34.729541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170137512s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-06T00:58:34.72968Z","caller":"traceutil/trace.go:171","msg":"trace[1179443006] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:759; }","duration":"1.170356026s","start":"2026-08-06T00:58:33.559289Z","end":"2026-08-06T00:58:34.729645Z","steps":["trace[1179443006] 'range keys from in-memory index tree' (duration: 1.169893848s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:58:34.729754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:58:33.559266Z","time spent":"1.17047266s","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-06T00:58:34.730335Z","caller":"traceutil/trace.go:171","msg":"trace[1955063408] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:783; }","duration":"917.660907ms","start":"2026-08-06T00:58:33.812654Z","end":"2026-08-06T00:58:34.730315Z","steps":["trace[1955063408] 'read index received' (duration: 917.547675ms)","trace[1955063408] 'applied index is now lower than readState.Index' (duration: 111.852µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T00:58:34.730422Z","caller":"traceutil/trace.go:171","msg":"trace[186586007] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"951.274459ms","start":"2026-08-06T00:58:33.779137Z","end":"2026-08-06T00:58:34.730411Z","steps":["trace[186586007] 'process raft request' (duration: 951.027885ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:58:34.730584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:58:33.779112Z","time spent":"951.334599ms","remote":"127.0.0.1:40372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T00:58:34.730842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.152705ms","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-08-06T00:58:34.730951Z","caller":"traceutil/trace.go:171","msg":"trace[1724795078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:760; }","duration":"918.307779ms","start":"2026-08-06T00:58:33.812618Z","end":"2026-08-06T00:58:34.730926Z","steps":["trace[1724795078] 'agreement among raft nodes before linearized reading' (duration: 918.022034ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:58:34.731001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:58:33.8126Z","time spent":"918.38926ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-06T00:58:34.734858Z","caller":"traceutil/trace.go:171","msg":"trace[1207397162] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"334.950283ms","start":"2026-08-06T00:58:34.39989Z","end":"2026-08-06T00:58:34.73484Z","steps":["trace[1207397162] 'process raft request' (duration: 334.78174ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:58:34.735027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:58:34.399868Z","time spent":"335.051995ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T00:58:34.735425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.395861ms","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-08-06T00:58:34.735485Z","caller":"traceutil/trace.go:171","msg":"trace[1126852888] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:761; }","duration":"914.485492ms","start":"2026-08-06T00:58:33.820988Z","end":"2026-08-06T00:58:34.735453Z","steps":["trace[1126852888] 'agreement among raft nodes before linearized reading' (duration: 914.35341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:58:34.735513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:58:33.820966Z","time spent":"914.540333ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-06T00:58:34.735662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.512112ms","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-08-06T00:58:34.73568Z","caller":"traceutil/trace.go:171","msg":"trace[1876488346] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:761; }","duration":"922.584343ms","start":"2026-08-06T00:58:33.81309Z","end":"2026-08-06T00:58:34.735674Z","steps":["trace[1876488346] 'agreement among raft nodes before linearized reading' (duration: 922.493601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:58:34.735697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:58:33.813055Z","time spent":"922.637503ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-06T00:58:34.735854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.595338ms","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-08-06T00:58:34.735879Z","caller":"traceutil/trace.go:171","msg":"trace[232405781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:761; }","duration":"920.640858ms","start":"2026-08-06T00:58:33.815231Z","end":"2026-08-06T00:58:34.735872Z","steps":["trace[232405781] 'agreement among raft nodes before linearized reading' (duration: 919.749272ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:58:34.735897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:58:33.81522Z","time spent":"920.67272ms","remote":"127.0.0.1:40386","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-08-06T00:58:34.73597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.897708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.128\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-06T00:58:34.736041Z","caller":"traceutil/trace.go:171","msg":"trace[1049065255] range","detail":"{range_begin:/registry/masterleases/199.19.213.128; range_end:; response_count:1; response_revision:761; }","duration":"390.022251ms","start":"2026-08-06T00:58:34.346Z","end":"2026-08-06T00:58:34.736022Z","steps":["trace[1049065255] 'agreement among raft nodes before linearized reading' (duration: 389.129025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:58:34.736077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:58:34.345968Z","time spent":"390.102422ms","remote":"127.0.0.1:40176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.128\" "} {"level":"info","ts":"2026-08-06T00:59:09.077041Z","caller":"traceutil/trace.go:171","msg":"trace[211377441] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"136.313251ms","start":"2026-08-06T00:59:08.940701Z","end":"2026-08-06T00:59:09.077014Z","steps":["trace[211377441] 'process raft request' (duration: 136.152058ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:59:09.182963Z","caller":"traceutil/trace.go:171","msg":"trace[1913216703] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1038; }","duration":"135.454535ms","start":"2026-08-06T00:59:09.047487Z","end":"2026-08-06T00:59:09.182942Z","steps":["trace[1913216703] 'read index received' (duration: 29.332596ms)","trace[1913216703] 'applied index is now lower than readState.Index' (duration: 106.121279ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T00:59:09.183057Z","caller":"traceutil/trace.go:171","msg":"trace[26187106] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"215.541789ms","start":"2026-08-06T00:59:08.967507Z","end":"2026-08-06T00:59:09.183049Z","steps":["trace[26187106] 'process raft request' (duration: 191.466001ms)","trace[26187106] 'compare' (duration: 23.883935ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T00:59:09.183227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.103963ms","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-08-06T00:59:09.183275Z","caller":"traceutil/trace.go:171","msg":"trace[1128667995] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1007; }","duration":"111.203224ms","start":"2026-08-06T00:59:09.072061Z","end":"2026-08-06T00:59:09.183265Z","steps":["trace[1128667995] 'agreement among raft nodes before linearized reading' (duration: 111.073182ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:09.183375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.882811ms","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-08-06T00:59:09.183401Z","caller":"traceutil/trace.go:171","msg":"trace[282413485] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1007; }","duration":"135.954663ms","start":"2026-08-06T00:59:09.04744Z","end":"2026-08-06T00:59:09.183395Z","steps":["trace[282413485] 'agreement among raft nodes before linearized reading' (duration: 135.892762ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:11.661864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.820149ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T00:59:11.662145Z","caller":"traceutil/trace.go:171","msg":"trace[828096406] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"379.710682ms","start":"2026-08-06T00:59:11.282415Z","end":"2026-08-06T00:59:11.662126Z","steps":["trace[828096406] 'process raft request' (duration: 379.660451ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:11.662225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:59:11.282403Z","time spent":"379.777094ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T00:59:11.662267Z","caller":"traceutil/trace.go:171","msg":"trace[529526116] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"380.598387ms","start":"2026-08-06T00:59:11.281648Z","end":"2026-08-06T00:59:11.662246Z","steps":["trace[529526116] 'process raft request' (duration: 380.329383ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:59:11.662303Z","caller":"traceutil/trace.go:171","msg":"trace[1480833057] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"381.161494ms","start":"2026-08-06T00:59:11.281111Z","end":"2026-08-06T00:59:11.662273Z","steps":["trace[1480833057] 'process raft request' (duration: 275.807027ms)","trace[1480833057] 'compare' (duration: 104.666257ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T00:59:11.662351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:59:11.281629Z","time spent":"380.677048ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T00:59:11.662482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:59:11.28109Z","time spent":"381.276656ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T00:59:35.948617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.059318ms","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-08-06T00:59:35.948709Z","caller":"traceutil/trace.go:171","msg":"trace[779414930] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1095; }","duration":"125.19421ms","start":"2026-08-06T00:59:35.823497Z","end":"2026-08-06T00:59:35.948691Z","steps":["trace[779414930] 'range keys from in-memory index tree' (duration: 124.898996ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:59:36.088689Z","caller":"traceutil/trace.go:171","msg":"trace[2099967008] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"136.555204ms","start":"2026-08-06T00:59:35.95211Z","end":"2026-08-06T00:59:36.088665Z","steps":["trace[2099967008] 'process raft request' (duration: 130.871126ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:59:38.110651Z","caller":"traceutil/trace.go:171","msg":"trace[1278759716] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"113.062902ms","start":"2026-08-06T00:59:37.997554Z","end":"2026-08-06T00:59:38.110617Z","steps":["trace[1278759716] 'process raft request' (duration: 112.892489ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:59:38.342792Z","caller":"traceutil/trace.go:171","msg":"trace[1190213017] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"147.455502ms","start":"2026-08-06T00:59:38.19531Z","end":"2026-08-06T00:59:38.342766Z","steps":["trace[1190213017] 'process raft request' (duration: 51.247523ms)","trace[1190213017] 'compare' (duration: 96.103048ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:00:26.05879Z","caller":"traceutil/trace.go:171","msg":"trace[395436761] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"290.366942ms","start":"2026-08-06T01:00:25.7684Z","end":"2026-08-06T01:00:26.058766Z","steps":["trace[395436761] 'process raft request' (duration: 290.182876ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:00:32.346076Z","caller":"traceutil/trace.go:171","msg":"trace[102067300] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"257.635757ms","start":"2026-08-06T01:00:32.088414Z","end":"2026-08-06T01:00:32.34605Z","steps":["trace[102067300] 'process raft request' (duration: 257.483372ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:00:32.424531Z","caller":"traceutil/trace.go:171","msg":"trace[1954694441] linearizableReadLoop","detail":"{readStateIndex:1369; appliedIndex:1367; }","duration":"238.512307ms","start":"2026-08-06T01:00:32.185997Z","end":"2026-08-06T01:00:32.424509Z","steps":["trace[1954694441] 'read index received' (duration: 159.834744ms)","trace[1954694441] 'applied index is now lower than readState.Index' (duration: 78.676763ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:00:32.424961Z","caller":"traceutil/trace.go:171","msg":"trace[2047256586] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"335.605825ms","start":"2026-08-06T01:00:32.089347Z","end":"2026-08-06T01:00:32.424953Z","steps":["trace[2047256586] 'process raft request' (duration: 335.075926ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:00:32.425034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:00:32.089332Z","time spent":"335.647566ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:00:32.425339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.298944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:00:32.425488Z","caller":"traceutil/trace.go:171","msg":"trace[2027879140] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1318; }","duration":"239.480161ms","start":"2026-08-06T01:00:32.185985Z","end":"2026-08-06T01:00:32.425465Z","steps":["trace[2027879140] 'agreement among raft nodes before linearized reading' (duration: 239.230512ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:00:32.4256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.583933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T01:00:32.425813Z","caller":"traceutil/trace.go:171","msg":"trace[977803368] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1318; }","duration":"224.809191ms","start":"2026-08-06T01:00:32.200915Z","end":"2026-08-06T01:00:32.425724Z","steps":["trace[977803368] 'agreement among raft nodes before linearized reading' (duration: 224.435918ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:01:06.753995Z","caller":"traceutil/trace.go:171","msg":"trace[1301883239] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"134.429293ms","start":"2026-08-06T01:01:06.619539Z","end":"2026-08-06T01:01:06.753968Z","steps":["trace[1301883239] 'process raft request' (duration: 134.3233ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:01:06.754019Z","caller":"traceutil/trace.go:171","msg":"trace[969939179] linearizableReadLoop","detail":"{readStateIndex:1495; appliedIndex:1493; }","duration":"144.602154ms","start":"2026-08-06T01:01:06.609392Z","end":"2026-08-06T01:01:06.753994Z","steps":["trace[969939179] 'read index received' (duration: 4.487787ms)","trace[969939179] 'applied index is now lower than readState.Index' (duration: 140.113037ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:01:06.754216Z","caller":"traceutil/trace.go:171","msg":"trace[1036277365] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"165.280687ms","start":"2026-08-06T01:01:06.588898Z","end":"2026-08-06T01:01:06.754178Z","steps":["trace[1036277365] 'process raft request' (duration: 117.809466ms)","trace[1036277365] 'compare' (duration: 46.974506ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:01:06.754351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.966586ms","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-08-06T01:01:06.754393Z","caller":"traceutil/trace.go:171","msg":"trace[1563044982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1437; }","duration":"145.062258ms","start":"2026-08-06T01:01:06.609319Z","end":"2026-08-06T01:01:06.754382Z","steps":["trace[1563044982] 'agreement among raft nodes before linearized reading' (duration: 144.81767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:01:06.754636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.779745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6v8s7\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-08-06T01:01:06.754783Z","caller":"traceutil/trace.go:171","msg":"trace[1559055690] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6v8s7; range_end:; response_count:1; response_revision:1437; }","duration":"135.917329ms","start":"2026-08-06T01:01:06.618794Z","end":"2026-08-06T01:01:06.754712Z","steps":["trace[1559055690] 'agreement among raft nodes before linearized reading' (duration: 135.330851ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:01:06.925097Z","caller":"traceutil/trace.go:171","msg":"trace[1854354642] linearizableReadLoop","detail":"{readStateIndex:1497; appliedIndex:1496; }","duration":"161.613445ms","start":"2026-08-06T01:01:06.763465Z","end":"2026-08-06T01:01:06.925079Z","steps":["trace[1854354642] 'read index received' (duration: 161.007516ms)","trace[1854354642] 'applied index is now lower than readState.Index' (duration: 605.069µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:01:06.925199Z","caller":"traceutil/trace.go:171","msg":"trace[2024683387] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"166.850975ms","start":"2026-08-06T01:01:06.758332Z","end":"2026-08-06T01:01:06.925183Z","steps":["trace[2024683387] 'process raft request' (duration: 166.133873ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:01:06.925274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.79923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6v8s7\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-08-06T01:01:06.925297Z","caller":"traceutil/trace.go:171","msg":"trace[994980771] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6v8s7; range_end:; response_count:1; response_revision:1438; }","duration":"161.874363ms","start":"2026-08-06T01:01:06.763417Z","end":"2026-08-06T01:01:06.925292Z","steps":["trace[994980771] 'agreement among raft nodes before linearized reading' (duration: 161.740199ms)"],"step_count":1}