{"level":"warn","ts":"2026-07-09T23:46:28.25169Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T23:46:28.25177Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.65: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.65:2380","--initial-cluster=instance=https://199.19.213.65:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.65:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.65:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-09T23:46:28.251828Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T23:46:28.251835Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.65:2380"]} {"level":"info","ts":"2026-07-09T23:46:28.251849Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-09T23:46:28.252457Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.65:2379"]} {"level":"info","ts":"2026-07-09T23:46:28.252521Z","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.65:2380"],"listen-peer-urls":["https://199.19.213.65:2380"],"advertise-client-urls":["https://199.19.213.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.65:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-09T23:46:28.260252Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.545208ms"} {"level":"info","ts":"2026-07-09T23:46:28.27016Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a071a454a8148542","cluster-id":"aa233e43aeaf7fe5"} {"level":"info","ts":"2026-07-09T23:46:28.270285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 switched to configuration voters=()"} {"level":"info","ts":"2026-07-09T23:46:28.270345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became follower at term 0"} {"level":"info","ts":"2026-07-09T23:46:28.270367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a071a454a8148542 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-09T23:46:28.27038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became follower at term 1"} {"level":"info","ts":"2026-07-09T23:46:28.270548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 switched to configuration voters=(11561202401940899138)"} {"level":"warn","ts":"2026-07-09T23:46:28.27642Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-09T23:46:28.278024Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-09T23:46:28.279169Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-09T23:46:28.281905Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a071a454a8148542","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-09T23:46:28.28225Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a071a454a8148542","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-09T23:46:28.282503Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T23:46:28.282677Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T23:46:28.282688Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T23:46:28.284438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 switched to configuration voters=(11561202401940899138)"} {"level":"info","ts":"2026-07-09T23:46:28.28577Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa233e43aeaf7fe5","local-member-id":"a071a454a8148542","added-peer-id":"a071a454a8148542","added-peer-peer-urls":["https://199.19.213.65:2380"]} {"level":"info","ts":"2026-07-09T23:46:28.291162Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-09T23:46:28.291757Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a071a454a8148542","initial-advertise-peer-urls":["https://199.19.213.65:2380"],"listen-peer-urls":["https://199.19.213.65:2380"],"advertise-client-urls":["https://199.19.213.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-09T23:46:28.291401Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.65:2380"} {"level":"info","ts":"2026-07-09T23:46:28.292967Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.65:2380"} {"level":"info","ts":"2026-07-09T23:46:28.291957Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-09T23:46:29.070957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 is starting a new election at term 1"} {"level":"info","ts":"2026-07-09T23:46:29.071018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-09T23:46:29.071049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 received MsgPreVoteResp from a071a454a8148542 at term 1"} {"level":"info","ts":"2026-07-09T23:46:29.071069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became candidate at term 2"} {"level":"info","ts":"2026-07-09T23:46:29.07108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 received MsgVoteResp from a071a454a8148542 at term 2"} {"level":"info","ts":"2026-07-09T23:46:29.071092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became leader at term 2"} {"level":"info","ts":"2026-07-09T23:46:29.071105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a071a454a8148542 elected leader a071a454a8148542 at term 2"} {"level":"info","ts":"2026-07-09T23:46:29.072425Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a071a454a8148542","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.65:2379]}","request-path":"/0/members/a071a454a8148542/attributes","cluster-id":"aa233e43aeaf7fe5","publish-timeout":"7s"} {"level":"info","ts":"2026-07-09T23:46:29.072455Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T23:46:29.072759Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T23:46:29.073016Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T23:46:29.075535Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa233e43aeaf7fe5","local-member-id":"a071a454a8148542","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T23:46:29.075602Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T23:46:29.075627Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T23:46:29.075777Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.65:2379"} {"level":"info","ts":"2026-07-09T23:46:29.07625Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-09T23:46:29.076627Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-09T23:46:29.076656Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-07-09T23:46:33.137165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.734652ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"warn","ts":"2026-07-09T23:46:33.137331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:32.71016Z","time spent":"427.1651ms","remote":"127.0.0.1:51976","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-09T23:46:33.662028Z","caller":"traceutil/trace.go:171","msg":"trace[2098920154] linearizableReadLoop","detail":"{readStateIndex:211; appliedIndex:209; }","duration":"945.348876ms","start":"2026-07-09T23:46:32.716655Z","end":"2026-07-09T23:46:33.662004Z","steps":["trace[2098920154] 'read index received' (duration: 9.42197ms)","trace[2098920154] 'applied index is now lower than readState.Index' (duration: 935.925746ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:46:33.662208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.495119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:46:33.662171Z","caller":"traceutil/trace.go:171","msg":"trace[1168539087] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"950.212855ms","start":"2026-07-09T23:46:32.711917Z","end":"2026-07-09T23:46:33.66213Z","steps":["trace[1168539087] 'process raft request' (duration: 949.964394ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:46:33.662276Z","caller":"traceutil/trace.go:171","msg":"trace[253649585] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:0; response_revision:205; }","duration":"945.614819ms","start":"2026-07-09T23:46:32.716621Z","end":"2026-07-09T23:46:33.662236Z","steps":["trace[253649585] 'agreement among raft nodes before linearized reading' (duration: 945.501279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:33.662351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:32.716599Z","time spent":"945.716087ms","remote":"127.0.0.1:52104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-07-09T23:46:33.662838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:32.711904Z","time spent":"950.359338ms","remote":"127.0.0.1:52174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:46:33.699638Z","caller":"traceutil/trace.go:171","msg":"trace[1625726171] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"560.501893ms","start":"2026-07-09T23:46:33.139094Z","end":"2026-07-09T23:46:33.699596Z","steps":["trace[1625726171] 'process raft request' (duration: 560.322778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:33.69975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:33.139069Z","time spent":"560.629244ms","remote":"127.0.0.1:51976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T23:46:33.699882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T23:46:33.699871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.44353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:46:33.700006Z","caller":"traceutil/trace.go:171","msg":"trace[1747977795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:206; }","duration":"494.614835ms","start":"2026-07-09T23:46:33.205337Z","end":"2026-07-09T23:46:33.699951Z","steps":["trace[1747977795] 'agreement among raft nodes before linearized reading' (duration: 494.446661ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:46:33.700119Z","caller":"traceutil/trace.go:171","msg":"trace[1097448026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:206; }","duration":"759.770927ms","start":"2026-07-09T23:46:32.940263Z","end":"2026-07-09T23:46:33.700034Z","steps":["trace[1097448026] 'agreement among raft nodes before linearized reading' (duration: 759.358682ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:33.700238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:32.940231Z","time spent":"759.992696ms","remote":"127.0.0.1:52174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T23:46:33.700057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.206762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-07-09T23:46:33.700411Z","caller":"traceutil/trace.go:171","msg":"trace[173290053] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:206; }","duration":"524.674281ms","start":"2026-07-09T23:46:33.175717Z","end":"2026-07-09T23:46:33.700392Z","steps":["trace[173290053] 'agreement among raft nodes before linearized reading' (duration: 523.896426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:33.700118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:33.205312Z","time spent":"494.7892ms","remote":"127.0.0.1:52174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T23:46:33.70054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:33.175665Z","time spent":"524.858547ms","remote":"127.0.0.1:52078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3515,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-09T23:46:34.37861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.063509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:46:34.378718Z","caller":"traceutil/trace.go:171","msg":"trace[860040883] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"674.420635ms","start":"2026-07-09T23:46:33.704279Z","end":"2026-07-09T23:46:34.3787Z","steps":["trace[860040883] 'process raft request' (duration: 119.241686ms)","trace[860040883] 'compare' (duration: 554.83601ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:46:34.378802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:33.704257Z","time spent":"674.503381ms","remote":"127.0.0.1:52104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T23:46:34.909136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.928025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:46:34.90938Z","caller":"traceutil/trace.go:171","msg":"trace[908168023] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"1.204271565s","start":"2026-07-09T23:46:33.705084Z","end":"2026-07-09T23:46:34.909355Z","steps":["trace[908168023] 'process raft request' (duration: 933.063316ms)","trace[908168023] 'compare' (duration: 270.808695ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:46:34.909479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:33.705068Z","time spent":"1.204366393s","remote":"127.0.0.1:52174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:46:34.909807Z","caller":"traceutil/trace.go:171","msg":"trace[855375517] linearizableReadLoop","detail":"{readStateIndex:216; appliedIndex:213; }","duration":"524.536774ms","start":"2026-07-09T23:46:34.385251Z","end":"2026-07-09T23:46:34.909788Z","steps":["trace[855375517] 'read index received' (duration: 252.918149ms)","trace[855375517] 'applied index is now lower than readState.Index' (duration: 271.615074ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:46:34.909831Z","caller":"traceutil/trace.go:171","msg":"trace[281593236] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"486.018379ms","start":"2026-07-09T23:46:34.423804Z","end":"2026-07-09T23:46:34.909823Z","steps":["trace[281593236] 'process raft request' (duration: 485.828254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:34.909874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:34.423777Z","time spent":"486.076385ms","remote":"127.0.0.1:52084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:46:34.909909Z","caller":"traceutil/trace.go:171","msg":"trace[979513164] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"512.990751ms","start":"2026-07-09T23:46:34.396908Z","end":"2026-07-09T23:46:34.909899Z","steps":["trace[979513164] 'process raft request' (duration: 512.632791ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:34.90997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:34.396892Z","time spent":"513.044206ms","remote":"127.0.0.1:51976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:46:34.909674Z","caller":"traceutil/trace.go:171","msg":"trace[1491968] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"1.201683177s","start":"2026-07-09T23:46:33.707984Z","end":"2026-07-09T23:46:34.909668Z","steps":["trace[1491968] 'process raft request' (duration: 1.201251281s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:34.910084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:33.707966Z","time spent":"1.202101493s","remote":"127.0.0.1:52174","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-07-09T23:46:34.910126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.851109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replication-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:46:34.910276Z","caller":"traceutil/trace.go:171","msg":"trace[1619882017] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replication-controller; range_end:; response_count:0; response_revision:212; }","duration":"524.993231ms","start":"2026-07-09T23:46:34.385219Z","end":"2026-07-09T23:46:34.910212Z","steps":["trace[1619882017] 'agreement among raft nodes before linearized reading' (duration: 524.706957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:34.910374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:34.385008Z","time spent":"525.354803ms","remote":"127.0.0.1:52104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/replication-controller\" "} {"level":"info","ts":"2026-07-09T23:46:34.910931Z","caller":"traceutil/trace.go:171","msg":"trace[215350112] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"1.200669102s","start":"2026-07-09T23:46:33.710245Z","end":"2026-07-09T23:46:34.910914Z","steps":["trace[215350112] 'process raft request' (duration: 1.199032115s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:34.911135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:33.710234Z","time spent":"1.200728367s","remote":"127.0.0.1:52078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3694,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:46:35.415292Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602412492572405764,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T23:46:35.575412Z","caller":"traceutil/trace.go:171","msg":"trace[1851320000] linearizableReadLoop","detail":"{readStateIndex:222; appliedIndex:218; }","duration":"660.408744ms","start":"2026-07-09T23:46:34.914976Z","end":"2026-07-09T23:46:35.575385Z","steps":["trace[1851320000] 'read index received' (duration: 605.359321ms)","trace[1851320000] 'applied index is now lower than readState.Index' (duration: 55.047872ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:46:35.575836Z","caller":"traceutil/trace.go:171","msg":"trace[296063997] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"661.165368ms","start":"2026-07-09T23:46:34.914651Z","end":"2026-07-09T23:46:35.575816Z","steps":["trace[296063997] 'process raft request' (duration: 605.734053ms)","trace[296063997] 'compare' (duration: 54.255286ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:46:35.575962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:34.914636Z","time spent":"661.256547ms","remote":"127.0.0.1:52174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T23:46:35.576232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.916797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-gythk8\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T23:46:35.576256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.281779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T23:46:35.576291Z","caller":"traceutil/trace.go:171","msg":"trace[63414912] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-gythk8; range_end:; response_count:0; response_revision:216; }","duration":"660.999185ms","start":"2026-07-09T23:46:34.915278Z","end":"2026-07-09T23:46:35.576277Z","steps":["trace[63414912] 'agreement among raft nodes before linearized reading' (duration: 660.908717ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:46:35.576322Z","caller":"traceutil/trace.go:171","msg":"trace[1646793837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:216; }","duration":"661.383047ms","start":"2026-07-09T23:46:34.914921Z","end":"2026-07-09T23:46:35.576304Z","steps":["trace[1646793837] 'agreement among raft nodes before linearized reading' (duration: 661.273888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:35.57641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:34.914903Z","time spent":"661.493316ms","remote":"127.0.0.1:52174","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-07-09T23:46:35.576412Z","caller":"traceutil/trace.go:171","msg":"trace[1431773283] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"661.211052ms","start":"2026-07-09T23:46:34.915169Z","end":"2026-07-09T23:46:35.57638Z","steps":["trace[1431773283] 'process raft request' (duration: 660.057064ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:46:35.576433Z","caller":"traceutil/trace.go:171","msg":"trace[1633272119] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"659.566725ms","start":"2026-07-09T23:46:34.916834Z","end":"2026-07-09T23:46:35.5764Z","steps":["trace[1633272119] 'process raft request' (duration: 658.45043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:35.576355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:34.915271Z","time spent":"661.062569ms","remote":"127.0.0.1:51988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-gythk8\" "} {"level":"info","ts":"2026-07-09T23:46:35.576515Z","caller":"traceutil/trace.go:171","msg":"trace[1069071144] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"658.301807ms","start":"2026-07-09T23:46:34.918195Z","end":"2026-07-09T23:46:35.576497Z","steps":["trace[1069071144] 'process raft request' (duration: 657.134998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:35.576718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:34.918188Z","time spent":"658.470362ms","remote":"127.0.0.1:52174","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-07-09T23:46:35.576564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:34.91516Z","time spent":"661.335463ms","remote":"127.0.0.1:51976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T23:46:35.576575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:34.916816Z","time spent":"659.693085ms","remote":"127.0.0.1:52104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T23:46:40.567832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.783473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:46:40.568161Z","caller":"traceutil/trace.go:171","msg":"trace[686427292] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"118.173162ms","start":"2026-07-09T23:46:40.449961Z","end":"2026-07-09T23:46:40.568134Z","steps":["trace[686427292] 'process raft request' (duration: 118.049831ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:46:40.568136Z","caller":"traceutil/trace.go:171","msg":"trace[1497632690] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"153.93527ms","start":"2026-07-09T23:46:40.414066Z","end":"2026-07-09T23:46:40.568001Z","steps":["trace[1497632690] 'process raft request' (duration: 32.857672ms)","trace[1497632690] 'compare' (duration: 120.350187ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:46:40.568205Z","caller":"traceutil/trace.go:171","msg":"trace[1659029116] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:376; }","duration":"125.474412ms","start":"2026-07-09T23:46:40.442687Z","end":"2026-07-09T23:46:40.568161Z","steps":["trace[1659029116] 'read index received' (duration: 4.226839ms)","trace[1659029116] 'applied index is now lower than readState.Index' (duration: 121.243683ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:46:40.568553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.687696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-07-09T23:46:40.568707Z","caller":"traceutil/trace.go:171","msg":"trace[547850031] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:364; }","duration":"118.838409ms","start":"2026-07-09T23:46:40.449825Z","end":"2026-07-09T23:46:40.568663Z","steps":["trace[547850031] 'agreement among raft nodes before linearized reading' (duration: 118.61826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:40.568571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.693541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-09T23:46:40.568902Z","caller":"traceutil/trace.go:171","msg":"trace[1168209429] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:364; }","duration":"136.079474ms","start":"2026-07-09T23:46:40.4328Z","end":"2026-07-09T23:46:40.56888Z","steps":["trace[1168209429] 'agreement among raft nodes before linearized reading' (duration: 135.628416ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:40.569264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.992656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:46:40.569573Z","caller":"traceutil/trace.go:171","msg":"trace[1123816741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:364; }","duration":"110.329385ms","start":"2026-07-09T23:46:40.459224Z","end":"2026-07-09T23:46:40.569553Z","steps":["trace[1123816741] 'agreement among raft nodes before linearized reading' (duration: 109.990516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:41.467302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.525882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:46:41.467625Z","caller":"traceutil/trace.go:171","msg":"trace[682514682] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"892.461469ms","start":"2026-07-09T23:46:40.575127Z","end":"2026-07-09T23:46:41.467588Z","steps":["trace[682514682] 'process raft request' (duration: 892.295404ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:46:41.467715Z","caller":"traceutil/trace.go:171","msg":"trace[51801382] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"890.590439ms","start":"2026-07-09T23:46:40.577115Z","end":"2026-07-09T23:46:41.467706Z","steps":["trace[51801382] 'process raft request' (duration: 890.439547ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:46:41.467729Z","caller":"traceutil/trace.go:171","msg":"trace[1009628830] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"835.120426ms","start":"2026-07-09T23:46:40.632587Z","end":"2026-07-09T23:46:41.467707Z","steps":["trace[1009628830] 'process raft request' (duration: 835.084283ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:46:41.467735Z","caller":"traceutil/trace.go:171","msg":"trace[978834194] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"891.219714ms","start":"2026-07-09T23:46:40.576492Z","end":"2026-07-09T23:46:41.467712Z","steps":["trace[978834194] 'process raft request' (duration: 891.009125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:41.467829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:40.575112Z","time spent":"892.602391ms","remote":"127.0.0.1:51976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T23:46:41.467863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:40.576481Z","time spent":"891.305111ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2886,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:46:41.467888Z","caller":"traceutil/trace.go:171","msg":"trace[408606077] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"889.417001ms","start":"2026-07-09T23:46:40.578462Z","end":"2026-07-09T23:46:41.467879Z","steps":["trace[408606077] 'process raft request' (duration: 889.160679ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:46:41.467889Z","caller":"traceutil/trace.go:171","msg":"trace[1938687134] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"890.286434ms","start":"2026-07-09T23:46:40.577589Z","end":"2026-07-09T23:46:41.467876Z","steps":["trace[1938687134] 'process raft request' (duration: 889.998789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:41.467948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:40.577578Z","time spent":"890.346489ms","remote":"127.0.0.1:51976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T23:46:41.467955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:40.578452Z","time spent":"889.462275ms","remote":"127.0.0.1:40016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:46:41.467629Z","caller":"traceutil/trace.go:171","msg":"trace[726302233] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"893.443182ms","start":"2026-07-09T23:46:40.57415Z","end":"2026-07-09T23:46:41.467593Z","steps":["trace[726302233] 'process raft request' (duration: 312.534516ms)","trace[726302233] 'compare' (duration: 580.325325ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:46:41.468265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:40.574122Z","time spent":"894.108218ms","remote":"127.0.0.1:52000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:46:41.46817Z","caller":"traceutil/trace.go:171","msg":"trace[1844415146] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:378; }","duration":"886.737163ms","start":"2026-07-09T23:46:40.581416Z","end":"2026-07-09T23:46:41.468153Z","steps":["trace[1844415146] 'read index received' (duration: 45.865439ms)","trace[1844415146] 'applied index is now lower than readState.Index' (duration: 840.870004ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:46:41.467825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:40.632551Z","time spent":"835.238246ms","remote":"127.0.0.1:51976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T23:46:41.46783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:40.577082Z","time spent":"890.678307ms","remote":"127.0.0.1:52188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T23:46:41.468271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.847342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-09T23:46:41.46859Z","caller":"traceutil/trace.go:171","msg":"trace[127291120] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:372; }","duration":"887.221964ms","start":"2026-07-09T23:46:40.581356Z","end":"2026-07-09T23:46:41.468578Z","steps":["trace[127291120] 'agreement among raft nodes before linearized reading' (duration: 886.841542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:41.468654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:40.581347Z","time spent":"887.294009ms","remote":"127.0.0.1:52104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-07-09T23:46:41.615113Z","caller":"traceutil/trace.go:171","msg":"trace[141418737] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"531.541987ms","start":"2026-07-09T23:46:41.08353Z","end":"2026-07-09T23:46:41.615072Z","steps":["trace[141418737] 'process raft request' (duration: 496.949155ms)","trace[141418737] 'compare' (duration: 33.592128ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:46:41.615338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:41.083501Z","time spent":"531.719662ms","remote":"127.0.0.1:52174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:46:41.61532Z","caller":"traceutil/trace.go:171","msg":"trace[2012649350] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:386; }","duration":"146.897578ms","start":"2026-07-09T23:46:41.468385Z","end":"2026-07-09T23:46:41.615283Z","steps":["trace[2012649350] 'read index received' (duration: 112.113099ms)","trace[2012649350] 'applied index is now lower than readState.Index' (duration: 34.781119ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:46:41.615555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.832593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:46:41.615623Z","caller":"traceutil/trace.go:171","msg":"trace[486244822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:373; }","duration":"152.959934ms","start":"2026-07-09T23:46:41.462645Z","end":"2026-07-09T23:46:41.615605Z","steps":["trace[486244822] 'agreement among raft nodes before linearized reading' (duration: 152.828492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:41.615631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.316965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-hgmrv\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-07-09T23:46:41.61586Z","caller":"traceutil/trace.go:171","msg":"trace[1495695118] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-hgmrv; range_end:; response_count:1; response_revision:373; }","duration":"983.567486ms","start":"2026-07-09T23:46:40.632221Z","end":"2026-07-09T23:46:41.615788Z","steps":["trace[1495695118] 'agreement among raft nodes before linearized reading' (duration: 983.353408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:41.615864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.901979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-07-09T23:46:41.615925Z","caller":"traceutil/trace.go:171","msg":"trace[784345586] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:373; }","duration":"822.000477ms","start":"2026-07-09T23:46:40.793907Z","end":"2026-07-09T23:46:41.615907Z","steps":["trace[784345586] 'agreement among raft nodes before linearized reading' (duration: 821.876397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:41.615988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:40.793878Z","time spent":"822.098076ms","remote":"127.0.0.1:52104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":209,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-07-09T23:46:41.615937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:40.6322Z","time spent":"983.697907ms","remote":"127.0.0.1:52084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":3463,"request content":"key:\"/registry/pods/kube-system/kube-proxy-hgmrv\" "} {"level":"info","ts":"2026-07-09T23:46:41.616823Z","caller":"traceutil/trace.go:171","msg":"trace[2047209532] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"143.114897ms","start":"2026-07-09T23:46:41.473668Z","end":"2026-07-09T23:46:41.616782Z","steps":["trace[2047209532] 'process raft request' (duration: 141.925276ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:46:41.618758Z","caller":"traceutil/trace.go:171","msg":"trace[774898391] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"142.602463ms","start":"2026-07-09T23:46:41.476136Z","end":"2026-07-09T23:46:41.618738Z","steps":["trace[774898391] 'process raft request' (duration: 140.740815ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:46:45.796278Z","caller":"traceutil/trace.go:171","msg":"trace[711586742] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"131.207139ms","start":"2026-07-09T23:46:45.665039Z","end":"2026-07-09T23:46:45.796246Z","steps":["trace[711586742] 'process raft request' (duration: 95.258435ms)","trace[711586742] 'compare' (duration: 35.476563ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:46:45.796526Z","caller":"traceutil/trace.go:171","msg":"trace[211795198] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"131.301977ms","start":"2026-07-09T23:46:45.665202Z","end":"2026-07-09T23:46:45.796504Z","steps":["trace[211795198] 'process raft request' (duration: 130.854629ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:46:45.975815Z","caller":"traceutil/trace.go:171","msg":"trace[31245253] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"170.997175ms","start":"2026-07-09T23:46:45.804778Z","end":"2026-07-09T23:46:45.975775Z","steps":["trace[31245253] 'process raft request' (duration: 117.614958ms)","trace[31245253] 'compare' (duration: 53.200855ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:47:10.047291Z","caller":"traceutil/trace.go:171","msg":"trace[845974864] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"178.398788ms","start":"2026-07-09T23:47:09.868869Z","end":"2026-07-09T23:47:10.047268Z","steps":["trace[845974864] 'process raft request' (duration: 178.277441ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:47:10.047764Z","caller":"traceutil/trace.go:171","msg":"trace[1168987735] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:515; }","duration":"135.849579ms","start":"2026-07-09T23:47:09.911889Z","end":"2026-07-09T23:47:10.047739Z","steps":["trace[1168987735] 'read index received' (duration: 135.347299ms)","trace[1168987735] 'applied index is now lower than readState.Index' (duration: 500.51µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:47:10.047931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.842947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-09T23:47:10.047943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.037125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T23:47:10.048018Z","caller":"traceutil/trace.go:171","msg":"trace[1791442025] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:494; }","duration":"136.153282ms","start":"2026-07-09T23:47:09.911846Z","end":"2026-07-09T23:47:10.047999Z","steps":["trace[1791442025] 'agreement among raft nodes before linearized reading' (duration: 135.995165ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:47:10.047972Z","caller":"traceutil/trace.go:171","msg":"trace[1441443887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:494; }","duration":"131.902816ms","start":"2026-07-09T23:47:09.916056Z","end":"2026-07-09T23:47:10.047959Z","steps":["trace[1441443887] 'agreement among raft nodes before linearized reading' (duration: 131.819738ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:47:10.048302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.099312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-09T23:47:10.048343Z","caller":"traceutil/trace.go:171","msg":"trace[2065105888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:494; }","duration":"132.1752ms","start":"2026-07-09T23:47:09.916157Z","end":"2026-07-09T23:47:10.048332Z","steps":["trace[2065105888] 'agreement among raft nodes before linearized reading' (duration: 132.053873ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:47:12.886054Z","caller":"traceutil/trace.go:171","msg":"trace[2070099556] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"179.041879ms","start":"2026-07-09T23:47:12.706995Z","end":"2026-07-09T23:47:12.886036Z","steps":["trace[2070099556] 'process raft request' (duration: 178.919931ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:47:12.891015Z","caller":"traceutil/trace.go:171","msg":"trace[76295066] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"181.105011ms","start":"2026-07-09T23:47:12.709886Z","end":"2026-07-09T23:47:12.89099Z","steps":["trace[76295066] 'process raft request' (duration: 180.912014ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:47:12.891565Z","caller":"traceutil/trace.go:171","msg":"trace[700528978] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"169.695311ms","start":"2026-07-09T23:47:12.721819Z","end":"2026-07-09T23:47:12.891514Z","steps":["trace[700528978] 'process raft request' (duration: 169.086132ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:47:13.190795Z","caller":"traceutil/trace.go:171","msg":"trace[680206074] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"168.4283ms","start":"2026-07-09T23:47:13.022347Z","end":"2026-07-09T23:47:13.190775Z","steps":["trace[680206074] 'process raft request' (duration: 158.484321ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:47:13.190938Z","caller":"traceutil/trace.go:171","msg":"trace[659811703] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"142.427379ms","start":"2026-07-09T23:47:13.0485Z","end":"2026-07-09T23:47:13.190927Z","steps":["trace[659811703] 'process raft request' (duration: 142.37395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:47:19.963318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.978117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:47:19.963424Z","caller":"traceutil/trace.go:171","msg":"trace[75126925] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"273.290193ms","start":"2026-07-09T23:47:19.690118Z","end":"2026-07-09T23:47:19.963409Z","steps":["trace[75126925] 'read index received' (duration: 106.111648ms)","trace[75126925] 'applied index is now lower than readState.Index' (duration: 167.177505ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:47:19.963435Z","caller":"traceutil/trace.go:171","msg":"trace[620462251] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"487.457992ms","start":"2026-07-09T23:47:19.475962Z","end":"2026-07-09T23:47:19.96342Z","steps":["trace[620462251] 'process raft request' (duration: 320.314476ms)","trace[620462251] 'compare' (duration: 166.837429ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:47:19.963504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:47:19.475935Z","time spent":"487.54779ms","remote":"127.0.0.1:52138","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-07-09T23:47:19.963567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.450892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qb8z6\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-09T23:47:19.963598Z","caller":"traceutil/trace.go:171","msg":"trace[40764250] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qb8z6; range_end:; response_count:1; response_revision:647; }","duration":"273.517192ms","start":"2026-07-09T23:47:19.690072Z","end":"2026-07-09T23:47:19.96359Z","steps":["trace[40764250] 'agreement among raft nodes before linearized reading' (duration: 273.378143ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:47:20.621057Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602412492572406611,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T23:47:21.07054Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.043032187s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T23:47:21.091398Z","caller":"traceutil/trace.go:171","msg":"trace[569836778] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:675; }","duration":"970.949936ms","start":"2026-07-09T23:47:20.120424Z","end":"2026-07-09T23:47:21.091374Z","steps":["trace[569836778] 'read index received' (duration: 950.216104ms)","trace[569836778] 'applied index is now lower than readState.Index' (duration: 20.731952ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:47:21.091451Z","caller":"traceutil/trace.go:171","msg":"trace[1730273079] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"1.064060276s","start":"2026-07-09T23:47:20.027361Z","end":"2026-07-09T23:47:21.091422Z","steps":["trace[1730273079] 'process raft request' (duration: 1.043315215s)","trace[1730273079] 'compare' (duration: 20.405393ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:47:21.091559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:47:20.027343Z","time spent":"1.064164246s","remote":"127.0.0.1:52084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T23:47:21.091715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.275714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-09T23:47:21.091767Z","caller":"traceutil/trace.go:171","msg":"trace[2125542569] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"971.371963ms","start":"2026-07-09T23:47:20.120381Z","end":"2026-07-09T23:47:21.091753Z","steps":["trace[2125542569] 'agreement among raft nodes before linearized reading' (duration: 971.142725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:47:21.091807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:47:20.120365Z","time spent":"971.434923ms","remote":"127.0.0.1:52174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T23:47:21.09188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.53274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-09T23:47:21.091936Z","caller":"traceutil/trace.go:171","msg":"trace[1234410859] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"950.615768ms","start":"2026-07-09T23:47:20.141303Z","end":"2026-07-09T23:47:21.091919Z","steps":["trace[1234410859] 'agreement among raft nodes before linearized reading' (duration: 950.462359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:47:21.091959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.859005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-09T23:47:21.092001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:47:20.141288Z","time spent":"950.698048ms","remote":"127.0.0.1:52174","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":"info","ts":"2026-07-09T23:47:21.092046Z","caller":"traceutil/trace.go:171","msg":"trace[531858006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"923.993524ms","start":"2026-07-09T23:47:20.16803Z","end":"2026-07-09T23:47:21.092024Z","steps":["trace[531858006] 'agreement among raft nodes before linearized reading' (duration: 923.757765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:47:21.092123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:47:20.168012Z","time spent":"924.092623ms","remote":"127.0.0.1:52174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T23:47:21.092102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.171663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T23:47:21.092204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.229183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-09T23:47:21.092223Z","caller":"traceutil/trace.go:171","msg":"trace[1362509759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"630.349033ms","start":"2026-07-09T23:47:20.461839Z","end":"2026-07-09T23:47:21.092189Z","steps":["trace[1362509759] 'agreement among raft nodes before linearized reading' (duration: 630.112924ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:47:21.092262Z","caller":"traceutil/trace.go:171","msg":"trace[1051489704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"924.306752ms","start":"2026-07-09T23:47:20.167943Z","end":"2026-07-09T23:47:21.09225Z","steps":["trace[1051489704] 'agreement among raft nodes before linearized reading' (duration: 924.175873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:47:21.092283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:47:20.461759Z","time spent":"630.514931ms","remote":"127.0.0.1:51906","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-07-09T23:47:21.0923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:47:20.167933Z","time spent":"924.359942ms","remote":"127.0.0.1:52174","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-07-09T23:47:22.980766Z","caller":"traceutil/trace.go:171","msg":"trace[1195419180] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"139.299669ms","start":"2026-07-09T23:47:22.841437Z","end":"2026-07-09T23:47:22.980737Z","steps":["trace[1195419180] 'process raft request' (duration: 139.244019ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:47:22.980819Z","caller":"traceutil/trace.go:171","msg":"trace[1426617172] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"139.431298ms","start":"2026-07-09T23:47:22.841354Z","end":"2026-07-09T23:47:22.980786Z","steps":["trace[1426617172] 'process raft request' (duration: 136.536931ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:47:23.240751Z","caller":"traceutil/trace.go:171","msg":"trace[505984283] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"204.184158ms","start":"2026-07-09T23:47:23.036528Z","end":"2026-07-09T23:47:23.240712Z","steps":["trace[505984283] 'process raft request' (duration: 204.093189ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:47:23.241185Z","caller":"traceutil/trace.go:171","msg":"trace[217309273] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"252.683305ms","start":"2026-07-09T23:47:22.988476Z","end":"2026-07-09T23:47:23.241159Z","steps":["trace[217309273] 'process raft request' (duration: 235.629192ms)","trace[217309273] 'compare' (duration: 15.948577ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:47:23.241451Z","caller":"traceutil/trace.go:171","msg":"trace[1755084234] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:704; }","duration":"136.283295ms","start":"2026-07-09T23:47:23.105091Z","end":"2026-07-09T23:47:23.241375Z","steps":["trace[1755084234] 'read index received' (duration: 118.915223ms)","trace[1755084234] 'applied index is now lower than readState.Index' (duration: 17.317242ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:47:23.241484Z","caller":"traceutil/trace.go:171","msg":"trace[770394648] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"203.154232ms","start":"2026-07-09T23:47:23.038317Z","end":"2026-07-09T23:47:23.241472Z","steps":["trace[770394648] 'process raft request' (duration: 202.847273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:47:23.242398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.286371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-09T23:47:23.242497Z","caller":"traceutil/trace.go:171","msg":"trace[1922578026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:679; }","duration":"137.439451ms","start":"2026-07-09T23:47:23.10503Z","end":"2026-07-09T23:47:23.242469Z","steps":["trace[1922578026] 'agreement among raft nodes before linearized reading' (duration: 136.550714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:47:23.251254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.795144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-09T23:47:23.251305Z","caller":"traceutil/trace.go:171","msg":"trace[423152034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:680; }","duration":"145.928443ms","start":"2026-07-09T23:47:23.105362Z","end":"2026-07-09T23:47:23.251291Z","steps":["trace[423152034] 'agreement among raft nodes before linearized reading' (duration: 145.769874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:47:23.251472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.001123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-09T23:47:23.251419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.100436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-09T23:47:23.251601Z","caller":"traceutil/trace.go:171","msg":"trace[1175689072] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:680; }","duration":"146.103244ms","start":"2026-07-09T23:47:23.105411Z","end":"2026-07-09T23:47:23.251515Z","steps":["trace[1175689072] 'agreement among raft nodes before linearized reading' (duration: 145.992264ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:47:23.251624Z","caller":"traceutil/trace.go:171","msg":"trace[1448045459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:680; }","duration":"139.329595ms","start":"2026-07-09T23:47:23.11228Z","end":"2026-07-09T23:47:23.25161Z","steps":["trace[1448045459] 'agreement among raft nodes before linearized reading' (duration: 139.027046ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:47:24.788608Z","caller":"traceutil/trace.go:171","msg":"trace[747697982] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"106.381119ms","start":"2026-07-09T23:47:24.682203Z","end":"2026-07-09T23:47:24.788584Z","steps":["trace[747697982] 'process raft request' (duration: 104.435944ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:47:24.966714Z","caller":"traceutil/trace.go:171","msg":"trace[232908425] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"169.195483ms","start":"2026-07-09T23:47:24.797487Z","end":"2026-07-09T23:47:24.966683Z","steps":["trace[232908425] 'process raft request' (duration: 168.718234ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:47:25.065717Z","caller":"traceutil/trace.go:171","msg":"trace[726723717] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:739; }","duration":"230.34697ms","start":"2026-07-09T23:47:24.835338Z","end":"2026-07-09T23:47:25.065685Z","steps":["trace[726723717] 'read index received' (duration: 130.700058ms)","trace[726723717] 'applied index is now lower than readState.Index' (duration: 99.645542ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:47:25.065813Z","caller":"traceutil/trace.go:171","msg":"trace[1329536035] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"262.223981ms","start":"2026-07-09T23:47:24.803564Z","end":"2026-07-09T23:47:25.065788Z","steps":["trace[1329536035] 'process raft request' (duration: 261.944182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:47:25.066006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.643849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-07-09T23:47:25.066107Z","caller":"traceutil/trace.go:171","msg":"trace[252978258] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:713; }","duration":"230.767329ms","start":"2026-07-09T23:47:24.835301Z","end":"2026-07-09T23:47:25.066069Z","steps":["trace[252978258] 'agreement among raft nodes before linearized reading' (duration: 230.511609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:47:25.17204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.036322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-07-09T23:47:25.172282Z","caller":"traceutil/trace.go:171","msg":"trace[133985202] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:713; }","duration":"136.319352ms","start":"2026-07-09T23:47:25.035886Z","end":"2026-07-09T23:47:25.172205Z","steps":["trace[133985202] 'agreement among raft nodes before linearized reading' (duration: 30.204109ms)","trace[133985202] 'range keys from in-memory index tree' (duration: 105.566033ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:47:25.172043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.190558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T23:47:25.172941Z","caller":"traceutil/trace.go:171","msg":"trace[1912618779] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:713; }","duration":"283.973547ms","start":"2026-07-09T23:47:24.888724Z","end":"2026-07-09T23:47:25.172698Z","steps":["trace[1912618779] 'agreement among raft nodes before linearized reading' (duration: 177.281375ms)","trace[1912618779] 'range keys from in-memory index tree' (duration: 105.735033ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:47:25.975927Z","caller":"traceutil/trace.go:171","msg":"trace[2034137481] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"117.123672ms","start":"2026-07-09T23:47:25.85878Z","end":"2026-07-09T23:47:25.975904Z","steps":["trace[2034137481] 'process raft request' (duration: 116.922452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:47:33.596487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.210311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:47:33.596632Z","caller":"traceutil/trace.go:171","msg":"trace[859207861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"134.426592ms","start":"2026-07-09T23:47:33.462179Z","end":"2026-07-09T23:47:33.596606Z","steps":["trace[859207861] 'range keys from in-memory index tree' (duration: 134.129741ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:47:37.913589Z","caller":"traceutil/trace.go:171","msg":"trace[893137947] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"100.189035ms","start":"2026-07-09T23:47:37.813375Z","end":"2026-07-09T23:47:37.913564Z","steps":["trace[893137947] 'process raft request' (duration: 100.030823ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:47:41.124323Z","caller":"traceutil/trace.go:171","msg":"trace[2003515743] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"140.944592ms","start":"2026-07-09T23:47:40.983357Z","end":"2026-07-09T23:47:41.124302Z","steps":["trace[2003515743] 'process raft request' (duration: 126.842018ms)","trace[2003515743] 'compare' (duration: 13.928811ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:47:41.124568Z","caller":"traceutil/trace.go:171","msg":"trace[1620299660] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"140.932342ms","start":"2026-07-09T23:47:40.983624Z","end":"2026-07-09T23:47:41.124556Z","steps":["trace[1620299660] 'process raft request' (duration: 140.643578ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:47:41.124654Z","caller":"traceutil/trace.go:171","msg":"trace[1851275721] linearizableReadLoop","detail":"{readStateIndex:903; appliedIndex:900; }","duration":"135.027507ms","start":"2026-07-09T23:47:40.989566Z","end":"2026-07-09T23:47:41.124594Z","steps":["trace[1851275721] 'read index received' (duration: 120.648519ms)","trace[1851275721] 'applied index is now lower than readState.Index' (duration: 14.377558ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:47:41.124906Z","caller":"traceutil/trace.go:171","msg":"trace[1446882289] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"140.266562ms","start":"2026-07-09T23:47:40.984587Z","end":"2026-07-09T23:47:41.124853Z","steps":["trace[1446882289] 'process raft request' (duration: 139.880876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:47:41.125441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.525545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/\" range_end:\"/registry/serviceaccounts/envoy-gateway-system0\" limit:10000 ","response":"range_response_count:3 size:1862"} {"level":"info","ts":"2026-07-09T23:47:41.125492Z","caller":"traceutil/trace.go:171","msg":"trace[62147491] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/; range_end:/registry/serviceaccounts/envoy-gateway-system0; response_count:3; response_revision:870; }","duration":"135.94211ms","start":"2026-07-09T23:47:40.98954Z","end":"2026-07-09T23:47:41.125482Z","steps":["trace[62147491] 'agreement among raft nodes before linearized reading' (duration: 135.412673ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:48:11.727333Z","caller":"traceutil/trace.go:171","msg":"trace[793731552] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"169.091073ms","start":"2026-07-09T23:48:11.558214Z","end":"2026-07-09T23:48:11.727305Z","steps":["trace[793731552] 'process raft request' (duration: 168.966888ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:48:11.727448Z","caller":"traceutil/trace.go:171","msg":"trace[1155959523] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"169.135184ms","start":"2026-07-09T23:48:11.55826Z","end":"2026-07-09T23:48:11.727395Z","steps":["trace[1155959523] 'process raft request' (duration: 168.996869ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:48:11.727607Z","caller":"traceutil/trace.go:171","msg":"trace[580387730] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"169.403524ms","start":"2026-07-09T23:48:11.558169Z","end":"2026-07-09T23:48:11.727572Z","steps":["trace[580387730] 'process raft request' (duration: 168.815473ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:48:43.749281Z","caller":"traceutil/trace.go:171","msg":"trace[1044626613] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"130.96415ms","start":"2026-07-09T23:48:43.618296Z","end":"2026-07-09T23:48:43.749261Z","steps":["trace[1044626613] 'process raft request' (duration: 130.830213ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:48:44.149399Z","caller":"traceutil/trace.go:171","msg":"trace[1208134353] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"114.689497ms","start":"2026-07-09T23:48:44.034684Z","end":"2026-07-09T23:48:44.149373Z","steps":["trace[1208134353] 'process raft request' (duration: 84.998379ms)","trace[1208134353] 'compare' (duration: 29.593983ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:49:08.267539Z","caller":"traceutil/trace.go:171","msg":"trace[1548054601] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"116.616775ms","start":"2026-07-09T23:49:08.150884Z","end":"2026-07-09T23:49:08.267501Z","steps":["trace[1548054601] 'process raft request' (duration: 116.37433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:49:20.360877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.280025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:49:20.360946Z","caller":"traceutil/trace.go:171","msg":"trace[1950386310] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1318; }","duration":"177.422593ms","start":"2026-07-09T23:49:20.183508Z","end":"2026-07-09T23:49:20.360931Z","steps":["trace[1950386310] 'count revisions from in-memory index tree' (duration: 177.162829ms)"],"step_count":1}