{"level":"warn","ts":"2026-08-20T01:13:49.138172Z","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-20T01:13:49.138263Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.130: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.130:2380","--initial-cluster=instance=https://199.19.213.130:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.130:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.130: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-20T01:13:49.139277Z","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-20T01:13:49.139304Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.130:2380"]} {"level":"info","ts":"2026-08-20T01:13:49.139346Z","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-20T01:13:49.14012Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.130:2379"]} {"level":"info","ts":"2026-08-20T01:13:49.140457Z","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.130:2380"],"listen-peer-urls":["https://199.19.213.130:2380"],"advertise-client-urls":["https://199.19.213.130:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.130:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.130: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-20T01:13:49.145033Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.326102ms"} {"level":"info","ts":"2026-08-20T01:13:49.150529Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a727d7e7ce5fe36a","cluster-id":"db8a5e7d54164af4"} {"level":"info","ts":"2026-08-20T01:13:49.150608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a switched to configuration voters=()"} {"level":"info","ts":"2026-08-20T01:13:49.15063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a became follower at term 0"} {"level":"info","ts":"2026-08-20T01:13:49.150645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a727d7e7ce5fe36a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-20T01:13:49.150659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a became follower at term 1"} {"level":"info","ts":"2026-08-20T01:13:49.150708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a switched to configuration voters=(12044833119025488746)"} {"level":"warn","ts":"2026-08-20T01:13:49.158629Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-20T01:13:49.160657Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-20T01:13:49.162Z","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-20T01:13:49.164369Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a727d7e7ce5fe36a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-20T01:13:49.165775Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a727d7e7ce5fe36a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-20T01:13:49.166065Z","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-20T01:13:49.166133Z","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-20T01:13:49.166147Z","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-20T01:13:49.167244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a switched to configuration voters=(12044833119025488746)"} {"level":"info","ts":"2026-08-20T01:13:49.167372Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"db8a5e7d54164af4","local-member-id":"a727d7e7ce5fe36a","added-peer-id":"a727d7e7ce5fe36a","added-peer-peer-urls":["https://199.19.213.130:2380"]} {"level":"info","ts":"2026-08-20T01:13:49.16845Z","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-20T01:13:49.1686Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.130:2380"} {"level":"info","ts":"2026-08-20T01:13:49.168634Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.130:2380"} {"level":"info","ts":"2026-08-20T01:13:49.168661Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a727d7e7ce5fe36a","initial-advertise-peer-urls":["https://199.19.213.130:2380"],"listen-peer-urls":["https://199.19.213.130:2380"],"advertise-client-urls":["https://199.19.213.130:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.130:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-20T01:13:49.168703Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-20T01:13:49.651222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a is starting a new election at term 1"} {"level":"info","ts":"2026-08-20T01:13:49.651308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a became pre-candidate at term 1"} {"level":"info","ts":"2026-08-20T01:13:49.651373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a received MsgPreVoteResp from a727d7e7ce5fe36a at term 1"} {"level":"info","ts":"2026-08-20T01:13:49.651405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a became candidate at term 2"} {"level":"info","ts":"2026-08-20T01:13:49.651454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a received MsgVoteResp from a727d7e7ce5fe36a at term 2"} {"level":"info","ts":"2026-08-20T01:13:49.651489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a became leader at term 2"} {"level":"info","ts":"2026-08-20T01:13:49.651512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a727d7e7ce5fe36a elected leader a727d7e7ce5fe36a at term 2"} {"level":"info","ts":"2026-08-20T01:13:49.655774Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a727d7e7ce5fe36a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.130:2379]}","request-path":"/0/members/a727d7e7ce5fe36a/attributes","cluster-id":"db8a5e7d54164af4","publish-timeout":"7s"} {"level":"info","ts":"2026-08-20T01:13:49.656173Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:13:49.658303Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T01:13:49.658773Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T01:13:49.659013Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-20T01:13:49.659037Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-20T01:13:49.659084Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"db8a5e7d54164af4","local-member-id":"a727d7e7ce5fe36a","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:13:49.659216Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:13:49.659243Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:13:49.661964Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-20T01:13:49.665628Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.130:2379"} {"level":"info","ts":"2026-08-20T01:13:51.354781Z","caller":"traceutil/trace.go:171","msg":"trace[595942990] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"293.487299ms","start":"2026-08-20T01:13:51.061258Z","end":"2026-08-20T01:13:51.354745Z","steps":["trace[595942990] 'process raft request' (duration: 292.126408ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:13:51.359282Z","caller":"traceutil/trace.go:171","msg":"trace[1842953408] linearizableReadLoop","detail":"{readStateIndex:39; appliedIndex:37; }","duration":"290.432538ms","start":"2026-08-20T01:13:51.068829Z","end":"2026-08-20T01:13:51.359262Z","steps":["trace[1842953408] 'read index received' (duration: 284.537352ms)","trace[1842953408] 'applied index is now lower than readState.Index' (duration: 5.893646ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:13:51.359385Z","caller":"traceutil/trace.go:171","msg":"trace[1090182353] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"286.255233ms","start":"2026-08-20T01:13:51.073116Z","end":"2026-08-20T01:13:51.359371Z","steps":["trace[1090182353] 'process raft request' (duration: 286.041307ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:13:51.359649Z","caller":"traceutil/trace.go:171","msg":"trace[480859233] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"291.983494ms","start":"2026-08-20T01:13:51.067637Z","end":"2026-08-20T01:13:51.359621Z","steps":["trace[480859233] 'process raft request' (duration: 291.417498ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:51.359712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.919446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-20T01:13:51.359794Z","caller":"traceutil/trace.go:171","msg":"trace[441288527] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:37; }","duration":"137.092681ms","start":"2026-08-20T01:13:51.222685Z","end":"2026-08-20T01:13:51.359778Z","steps":["trace[441288527] 'agreement among raft nodes before linearized reading' (duration: 136.921836ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:13:51.35979Z","caller":"traceutil/trace.go:171","msg":"trace[1529233141] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"276.300666ms","start":"2026-08-20T01:13:51.083458Z","end":"2026-08-20T01:13:51.359759Z","steps":["trace[1529233141] 'process raft request' (duration: 275.761941ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:13:51.360058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.1688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-20T01:13:51.360169Z","caller":"traceutil/trace.go:171","msg":"trace[387251143] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:37; }","duration":"291.355076ms","start":"2026-08-20T01:13:51.06879Z","end":"2026-08-20T01:13:51.360145Z","steps":["trace[387251143] 'agreement among raft nodes before linearized reading' (duration: 291.15604ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:13:52.705926Z","caller":"traceutil/trace.go:171","msg":"trace[1841881702] transaction","detail":"{read_only:false; response_revision:183; number_of_response:1; }","duration":"111.823292ms","start":"2026-08-20T01:13:52.594072Z","end":"2026-08-20T01:13:52.705895Z","steps":["trace[1841881702] 'process raft request' (duration: 103.194508ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:09.913321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.802327ms","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-08-20T01:14:09.913411Z","caller":"traceutil/trace.go:171","msg":"trace[667943521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:379; }","duration":"134.886059ms","start":"2026-08-20T01:14:09.778504Z","end":"2026-08-20T01:14:09.91339Z","steps":["trace[667943521] 'range keys from in-memory index tree' (duration: 134.724534ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:09.913775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.067218ms","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-20T01:14:09.913814Z","caller":"traceutil/trace.go:171","msg":"trace[1061371582] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:379; }","duration":"560.717244ms","start":"2026-08-20T01:14:09.353087Z","end":"2026-08-20T01:14:09.913804Z","steps":["trace[1061371582] 'range keys from in-memory index tree' (duration: 559.933643ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:09.913845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:09.353073Z","time spent":"560.761355ms","remote":"127.0.0.1:50792","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":"info","ts":"2026-08-20T01:14:11.824809Z","caller":"traceutil/trace.go:171","msg":"trace[1327765142] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"237.197682ms","start":"2026-08-20T01:14:11.587589Z","end":"2026-08-20T01:14:11.824786Z","steps":["trace[1327765142] 'process raft request' (duration: 237.065929ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:25.420585Z","caller":"traceutil/trace.go:171","msg":"trace[1631567293] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"141.333215ms","start":"2026-08-20T01:14:25.279224Z","end":"2026-08-20T01:14:25.420557Z","steps":["trace[1631567293] 'process raft request' (duration: 141.156232ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:25.422466Z","caller":"traceutil/trace.go:171","msg":"trace[1875216815] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"116.945417ms","start":"2026-08-20T01:14:25.3055Z","end":"2026-08-20T01:14:25.422446Z","steps":["trace[1875216815] 'process raft request' (duration: 116.835105ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:28.42788Z","caller":"traceutil/trace.go:171","msg":"trace[601129942] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"639.327444ms","start":"2026-08-20T01:14:27.788509Z","end":"2026-08-20T01:14:28.427837Z","steps":["trace[601129942] 'process raft request' (duration: 563.500067ms)","trace[601129942] 'compare' (duration: 75.098632ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:28.428132Z","caller":"traceutil/trace.go:171","msg":"trace[1723330875] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"525.304653ms","start":"2026-08-20T01:14:27.902813Z","end":"2026-08-20T01:14:28.428117Z","steps":["trace[1723330875] 'process raft request' (duration: 525.131359ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:28.428083Z","caller":"traceutil/trace.go:171","msg":"trace[747948034] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"635.432794ms","start":"2026-08-20T01:14:27.792622Z","end":"2026-08-20T01:14:28.428054Z","steps":["trace[747948034] 'process raft request' (duration: 634.898762ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:28.428127Z","caller":"traceutil/trace.go:171","msg":"trace[627244071] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"635.440514ms","start":"2026-08-20T01:14:27.792665Z","end":"2026-08-20T01:14:28.428105Z","steps":["trace[627244071] 'process raft request' (duration: 635.022084ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:28.429585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:27.902777Z","time spent":"525.793432ms","remote":"127.0.0.1:50560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-20T01:14:28.429759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:27.792597Z","time spent":"636.076487ms","remote":"127.0.0.1:50560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:14:28.42989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:27.792642Z","time spent":"636.068837ms","remote":"127.0.0.1:50560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:14:28.430002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:27.788494Z","time spent":"640.040558ms","remote":"127.0.0.1:50560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:14:28.437871Z","caller":"traceutil/trace.go:171","msg":"trace[238138617] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:532; }","duration":"381.691492ms","start":"2026-08-20T01:14:28.056163Z","end":"2026-08-20T01:14:28.437855Z","steps":["trace[238138617] 'read index received' (duration: 295.947806ms)","trace[238138617] 'applied index is now lower than readState.Index' (duration: 85.742606ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:14:28.438106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.921928ms","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-20T01:14:28.438132Z","caller":"traceutil/trace.go:171","msg":"trace[438690918] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:517; }","duration":"382.02532ms","start":"2026-08-20T01:14:28.0561Z","end":"2026-08-20T01:14:28.438125Z","steps":["trace[438690918] 'agreement among raft nodes before linearized reading' (duration: 381.858216ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:28.438168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:28.056074Z","time spent":"382.08768ms","remote":"127.0.0.1:50792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-20T01:14:28.438488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.573307ms","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-20T01:14:28.438549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.569989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:28.438565Z","caller":"traceutil/trace.go:171","msg":"trace[1789480072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"262.607699ms","start":"2026-08-20T01:14:28.175952Z","end":"2026-08-20T01:14:28.43856Z","steps":["trace[1789480072] 'agreement among raft nodes before linearized reading' (duration: 262.582889ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:28.438749Z","caller":"traceutil/trace.go:171","msg":"trace[1949899477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"370.651849ms","start":"2026-08-20T01:14:28.067858Z","end":"2026-08-20T01:14:28.43851Z","steps":["trace[1949899477] 'agreement among raft nodes before linearized reading' (duration: 370.555907ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:28.438774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:28.067847Z","time spent":"370.920454ms","remote":"127.0.0.1:50792","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":"info","ts":"2026-08-20T01:14:33.36464Z","caller":"traceutil/trace.go:171","msg":"trace[874858316] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"188.094369ms","start":"2026-08-20T01:14:33.176518Z","end":"2026-08-20T01:14:33.364613Z","steps":["trace[874858316] 'read index received' (duration: 187.919616ms)","trace[874858316] 'applied index is now lower than readState.Index' (duration: 172.933µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:14:33.364666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:33.038139Z","time spent":"326.518278ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-20T01:14:33.36481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.252352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:33.364847Z","caller":"traceutil/trace.go:171","msg":"trace[1301093087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"188.373064ms","start":"2026-08-20T01:14:33.176463Z","end":"2026-08-20T01:14:33.364836Z","steps":["trace[1301093087] 'agreement among raft nodes before linearized reading' (duration: 188.270422ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:35.222391Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16387086239402124096,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-20T01:14:35.331949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.710543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:14:35.332254Z","caller":"traceutil/trace.go:171","msg":"trace[2035778821] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"829.197043ms","start":"2026-08-20T01:14:34.502986Z","end":"2026-08-20T01:14:35.332183Z","steps":["trace[2035778821] 'process raft request' (duration: 70.958531ms)","trace[2035778821] 'compare' (duration: 757.323635ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:35.332331Z","caller":"traceutil/trace.go:171","msg":"trace[1187583777] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:605; }","duration":"610.275828ms","start":"2026-08-20T01:14:34.722033Z","end":"2026-08-20T01:14:35.332309Z","steps":["trace[1187583777] 'read index received' (duration: 587.449394ms)","trace[1187583777] 'applied index is now lower than readState.Index' (duration: 22.824614ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:14:35.332453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:34.502977Z","time spent":"829.384208ms","remote":"127.0.0.1:50792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:14:35.332638Z","caller":"traceutil/trace.go:171","msg":"trace[1028548008] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"816.761535ms","start":"2026-08-20T01:14:34.515864Z","end":"2026-08-20T01:14:35.332626Z","steps":["trace[1028548008] 'process raft request' (duration: 816.224725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:35.332703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:34.515818Z","time spent":"816.855266ms","remote":"127.0.0.1:50740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-20T01:14:35.332767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.752719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:35.332824Z","caller":"traceutil/trace.go:171","msg":"trace[1465153123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"155.856651ms","start":"2026-08-20T01:14:35.176948Z","end":"2026-08-20T01:14:35.332804Z","steps":["trace[1465153123] 'agreement among raft nodes before linearized reading' (duration: 155.774709ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:35.332858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.252031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fq8fk\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-20T01:14:35.33293Z","caller":"traceutil/trace.go:171","msg":"trace[1403493825] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fq8fk; range_end:; response_count:1; response_revision:586; }","duration":"469.425865ms","start":"2026-08-20T01:14:34.863478Z","end":"2026-08-20T01:14:35.332904Z","steps":["trace[1403493825] 'agreement among raft nodes before linearized reading' (duration: 469.17292ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:35.33291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.839278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-20T01:14:35.33298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:34.863444Z","time spent":"469.526866ms","remote":"127.0.0.1:50710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-fq8fk\" "} {"level":"info","ts":"2026-08-20T01:14:35.333007Z","caller":"traceutil/trace.go:171","msg":"trace[1599137366] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"610.987981ms","start":"2026-08-20T01:14:34.721996Z","end":"2026-08-20T01:14:35.332984Z","steps":["trace[1599137366] 'agreement among raft nodes before linearized reading' (duration: 610.678955ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:35.333072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:34.721977Z","time spent":"611.084833ms","remote":"127.0.0.1:50792","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-20T01:14:35.978988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.958113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:14:35.979448Z","caller":"traceutil/trace.go:171","msg":"trace[2044733950] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"627.7902ms","start":"2026-08-20T01:14:35.351626Z","end":"2026-08-20T01:14:35.979416Z","steps":["trace[2044733950] 'process raft request' (duration: 159.184325ms)","trace[2044733950] 'compare' (duration: 467.738059ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:14:35.979589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:35.351594Z","time spent":"627.942863ms","remote":"127.0.0.1:50710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:14:36.030182Z","caller":"traceutil/trace.go:171","msg":"trace[1953442611] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:608; }","duration":"521.569677ms","start":"2026-08-20T01:14:35.508508Z","end":"2026-08-20T01:14:36.030077Z","steps":["trace[1953442611] 'read index received' (duration: 2.282704ms)","trace[1953442611] 'applied index is now lower than readState.Index' (duration: 519.285373ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:36.030432Z","caller":"traceutil/trace.go:171","msg":"trace[1672954111] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"558.775615ms","start":"2026-08-20T01:14:35.471583Z","end":"2026-08-20T01:14:36.030359Z","steps":["trace[1672954111] 'process raft request' (duration: 558.248455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:36.030794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:35.471548Z","time spent":"559.092101ms","remote":"127.0.0.1:50792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:14:36.032369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.80474ms","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-20T01:14:36.032526Z","caller":"traceutil/trace.go:171","msg":"trace[432189937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:589; }","duration":"524.033154ms","start":"2026-08-20T01:14:35.508473Z","end":"2026-08-20T01:14:36.032506Z","steps":["trace[432189937] 'agreement among raft nodes before linearized reading' (duration: 521.804812ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:36.033457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:35.508453Z","time spent":"524.146906ms","remote":"127.0.0.1:50792","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-20T01:14:36.129817Z","caller":"traceutil/trace.go:171","msg":"trace[1126751135] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"353.607689ms","start":"2026-08-20T01:14:35.776182Z","end":"2026-08-20T01:14:36.12979Z","steps":["trace[1126751135] 'process raft request' (duration: 352.984357ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:36.130068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:35.776133Z","time spent":"353.831463ms","remote":"127.0.0.1:50610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:14:36.140401Z","caller":"traceutil/trace.go:171","msg":"trace[1204622722] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"102.46748ms","start":"2026-08-20T01:14:36.037898Z","end":"2026-08-20T01:14:36.140366Z","steps":["trace[1204622722] 'process raft request' (duration: 102.279057ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:37.008056Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16387086239402124108,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-20T01:14:37.508642Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16387086239402124108,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-20T01:14:37.946959Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.756030099s","expected-duration":"1s"} {"level":"info","ts":"2026-08-20T01:14:37.947451Z","caller":"traceutil/trace.go:171","msg":"trace[866081840] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"1.439825733s","start":"2026-08-20T01:14:36.5076Z","end":"2026-08-20T01:14:37.947426Z","steps":["trace[866081840] 'read index received' (duration: 1.439564198s)","trace[866081840] 'applied index is now lower than readState.Index' (duration: 260.055µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:37.947583Z","caller":"traceutil/trace.go:171","msg":"trace[445277388] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"1.75667978s","start":"2026-08-20T01:14:36.190835Z","end":"2026-08-20T01:14:37.947515Z","steps":["trace[445277388] 'process raft request' (duration: 1.756434346s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:37.947691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.440081377s","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-20T01:14:37.947732Z","caller":"traceutil/trace.go:171","msg":"trace[611619977] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:592; }","duration":"1.440199509s","start":"2026-08-20T01:14:36.507521Z","end":"2026-08-20T01:14:37.94772Z","steps":["trace[611619977] 'agreement among raft nodes before linearized reading' (duration: 1.440027986s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:37.947777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:36.507494Z","time spent":"1.440273191s","remote":"127.0.0.1:50792","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-20T01:14:37.947773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.253009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:37.947895Z","caller":"traceutil/trace.go:171","msg":"trace[959376877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"764.374561ms","start":"2026-08-20T01:14:37.183505Z","end":"2026-08-20T01:14:37.94788Z","steps":["trace[959376877] 'agreement among raft nodes before linearized reading' (duration: 764.220468ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:37.947934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:37.183455Z","time spent":"764.468423ms","remote":"127.0.0.1:50502","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-20T01:14:37.947823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:36.190811Z","time spent":"1.756899775s","remote":"127.0.0.1:50884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-20T01:14:37.948081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.704992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-20T01:14:37.948083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.974966ms","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-20T01:14:37.948107Z","caller":"traceutil/trace.go:171","msg":"trace[636191299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:592; }","duration":"610.786943ms","start":"2026-08-20T01:14:37.337314Z","end":"2026-08-20T01:14:37.948101Z","steps":["trace[636191299] 'agreement among raft nodes before linearized reading' (duration: 610.713072ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:37.948128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:37.337291Z","time spent":"610.832904ms","remote":"127.0.0.1:50792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-20T01:14:37.948127Z","caller":"traceutil/trace.go:171","msg":"trace[1727156324] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"574.076518ms","start":"2026-08-20T01:14:37.374038Z","end":"2026-08-20T01:14:37.948115Z","steps":["trace[1727156324] 'agreement among raft nodes before linearized reading' (duration: 573.837494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:37.948159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:37.374016Z","time spent":"574.137419ms","remote":"127.0.0.1:50792","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-20T01:14:39.356631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.010923ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:14:39.356853Z","caller":"traceutil/trace.go:171","msg":"trace[1137993888] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:624; }","duration":"241.372408ms","start":"2026-08-20T01:14:39.115461Z","end":"2026-08-20T01:14:39.356834Z","steps":["trace[1137993888] 'read index received' (duration: 76.322µs)","trace[1137993888] 'applied index is now lower than readState.Index' (duration: 241.294546ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:14:39.356968Z","caller":"traceutil/trace.go:171","msg":"trace[1533011176] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"359.718601ms","start":"2026-08-20T01:14:38.99724Z","end":"2026-08-20T01:14:39.356959Z","steps":["trace[1533011176] 'compare' (duration: 354.805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:39.357035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:38.997213Z","time spent":"359.795362ms","remote":"127.0.0.1:50592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-20T01:14:39.357288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.789356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:39.357418Z","caller":"traceutil/trace.go:171","msg":"trace[1031112530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"241.931378ms","start":"2026-08-20T01:14:39.115453Z","end":"2026-08-20T01:14:39.357384Z","steps":["trace[1031112530] 'agreement among raft nodes before linearized reading' (duration: 241.669163ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:39.357515Z","caller":"traceutil/trace.go:171","msg":"trace[638560142] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"352.400315ms","start":"2026-08-20T01:14:39.005104Z","end":"2026-08-20T01:14:39.357504Z","steps":["trace[638560142] 'process raft request' (duration: 351.664002ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:39.357288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.768787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-20T01:14:39.357586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:39.005086Z","time spent":"352.471116ms","remote":"127.0.0.1:50900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T01:14:39.357639Z","caller":"traceutil/trace.go:171","msg":"trace[347809580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"181.177294ms","start":"2026-08-20T01:14:39.176439Z","end":"2026-08-20T01:14:39.357616Z","steps":["trace[347809580] 'agreement among raft nodes before linearized reading' (duration: 180.765187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:54.474065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.276406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-20T01:14:54.474207Z","caller":"traceutil/trace.go:171","msg":"trace[174726213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:764; }","duration":"211.50513ms","start":"2026-08-20T01:14:54.262669Z","end":"2026-08-20T01:14:54.474174Z","steps":["trace[174726213] 'range keys from in-memory index tree' (duration: 211.047312ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:54.474617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.814501ms","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-20T01:14:54.474718Z","caller":"traceutil/trace.go:171","msg":"trace[810668809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:764; }","duration":"411.002194ms","start":"2026-08-20T01:14:54.063692Z","end":"2026-08-20T01:14:54.474694Z","steps":["trace[810668809] 'range keys from in-memory index tree' (duration: 410.586128ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:54.474772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:54.063682Z","time spent":"411.079496ms","remote":"127.0.0.1:50792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-20T01:14:54.474768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.951148ms","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-20T01:14:54.474829Z","caller":"traceutil/trace.go:171","msg":"trace[1149896809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:764; }","duration":"352.05135ms","start":"2026-08-20T01:14:54.122761Z","end":"2026-08-20T01:14:54.474813Z","steps":["trace[1149896809] 'range keys from in-memory index tree' (duration: 351.814925ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:54.474872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:54.12274Z","time spent":"352.11906ms","remote":"127.0.0.1:50792","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-20T01:14:54.475202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.780072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-p9hxx\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-08-20T01:14:54.47519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.253323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-20T01:14:54.475247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.120385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:54.475256Z","caller":"traceutil/trace.go:171","msg":"trace[655093505] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-p9hxx; range_end:; response_count:1; response_revision:764; }","duration":"493.838823ms","start":"2026-08-20T01:14:53.9814Z","end":"2026-08-20T01:14:54.475239Z","steps":["trace[655093505] 'range keys from in-memory index tree' (duration: 493.629259ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:54.475303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:53.981372Z","time spent":"493.922473ms","remote":"127.0.0.1:50710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3989,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-p9hxx\" "} {"level":"info","ts":"2026-08-20T01:14:54.475305Z","caller":"traceutil/trace.go:171","msg":"trace[1529417915] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:764; }","duration":"298.200056ms","start":"2026-08-20T01:14:54.177087Z","end":"2026-08-20T01:14:54.475287Z","steps":["trace[1529417915] 'range keys from in-memory index tree' (duration: 298.057204ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:54.475307Z","caller":"traceutil/trace.go:171","msg":"trace[1052504822] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:764; }","duration":"423.421486ms","start":"2026-08-20T01:14:54.051848Z","end":"2026-08-20T01:14:54.47527Z","steps":["trace[1052504822] 'range keys from in-memory index tree' (duration: 423.063341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:54.475357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:54.051813Z","time spent":"423.533818ms","remote":"127.0.0.1:50792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-20T01:14:54.475482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.383563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:14:54.47553Z","caller":"traceutil/trace.go:171","msg":"trace[1396150318] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:764; }","duration":"289.459955ms","start":"2026-08-20T01:14:54.186056Z","end":"2026-08-20T01:14:54.475516Z","steps":["trace[1396150318] 'count revisions from in-memory index tree' (duration: 289.303772ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:54.667884Z","caller":"traceutil/trace.go:171","msg":"trace[122351481] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"188.074989ms","start":"2026-08-20T01:14:54.479784Z","end":"2026-08-20T01:14:54.667859Z","steps":["trace[122351481] 'process raft request' (duration: 187.925687ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:54.668099Z","caller":"traceutil/trace.go:171","msg":"trace[616936118] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"186.634087ms","start":"2026-08-20T01:14:54.481453Z","end":"2026-08-20T01:14:54.668087Z","steps":["trace[616936118] 'process raft request' (duration: 186.365862ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:54.668363Z","caller":"traceutil/trace.go:171","msg":"trace[951156777] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"185.679662ms","start":"2026-08-20T01:14:54.482656Z","end":"2026-08-20T01:14:54.668336Z","steps":["trace[951156777] 'process raft request' (duration: 185.390987ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:14:54.668578Z","caller":"traceutil/trace.go:171","msg":"trace[86850895] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"179.101574ms","start":"2026-08-20T01:14:54.489445Z","end":"2026-08-20T01:14:54.668546Z","steps":["trace[86850895] 'process raft request' (duration: 178.81557ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:23.986111Z","caller":"traceutil/trace.go:171","msg":"trace[1861871509] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"194.581935ms","start":"2026-08-20T01:15:23.791488Z","end":"2026-08-20T01:15:23.98607Z","steps":["trace[1861871509] 'process raft request' (duration: 194.396372ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:28.155857Z","caller":"traceutil/trace.go:171","msg":"trace[713369461] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"102.677456ms","start":"2026-08-20T01:15:28.053153Z","end":"2026-08-20T01:15:28.15583Z","steps":["trace[713369461] 'read index received' (duration: 102.049107ms)","trace[713369461] 'applied index is now lower than readState.Index' (duration: 626.849µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:15:28.156108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.940409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-20T01:15:28.156153Z","caller":"traceutil/trace.go:171","msg":"trace[823172992] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1004; }","duration":"103.03111ms","start":"2026-08-20T01:15:28.053109Z","end":"2026-08-20T01:15:28.15614Z","steps":["trace[823172992] 'agreement among raft nodes before linearized reading' (duration: 102.825707ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:28.156473Z","caller":"traceutil/trace.go:171","msg":"trace[22429537] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"111.750682ms","start":"2026-08-20T01:15:28.044683Z","end":"2026-08-20T01:15:28.156433Z","steps":["trace[22429537] 'process raft request' (duration: 110.566357ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:29.168903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.134171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:15:29.169025Z","caller":"traceutil/trace.go:171","msg":"trace[1118931097] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1044; }","duration":"309.722724ms","start":"2026-08-20T01:15:28.859284Z","end":"2026-08-20T01:15:29.169006Z","steps":["trace[1118931097] 'read index received' (duration: 19.35986ms)","trace[1118931097] 'applied index is now lower than readState.Index' (duration: 290.361604ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:15:29.16912Z","caller":"traceutil/trace.go:171","msg":"trace[1885587170] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"310.520534ms","start":"2026-08-20T01:15:28.858567Z","end":"2026-08-20T01:15:29.169087Z","steps":["trace[1885587170] 'process raft request' (duration: 20.11097ms)","trace[1885587170] 'compare' (duration: 289.969799ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:15:29.169273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:15:28.858559Z","time spent":"310.636646ms","remote":"127.0.0.1:50792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:15:29.169279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.972647ms","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-20T01:15:29.16935Z","caller":"traceutil/trace.go:171","msg":"trace[386651234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1010; }","duration":"310.050598ms","start":"2026-08-20T01:15:28.859278Z","end":"2026-08-20T01:15:29.169328Z","steps":["trace[386651234] 'agreement among raft nodes before linearized reading' (duration: 309.805445ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:29.169391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:15:28.85926Z","time spent":"310.123209ms","remote":"127.0.0.1:50792","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-20T01:15:45.48734Z","caller":"traceutil/trace.go:171","msg":"trace[730907505] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"239.312966ms","start":"2026-08-20T01:15:45.247996Z","end":"2026-08-20T01:15:45.487309Z","steps":["trace[730907505] 'process raft request' (duration: 239.121162ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:45.536412Z","caller":"traceutil/trace.go:171","msg":"trace[1284290094] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"287.398724ms","start":"2026-08-20T01:15:45.248988Z","end":"2026-08-20T01:15:45.536386Z","steps":["trace[1284290094] 'process raft request' (duration: 287.221082ms)"],"step_count":1}