{"level":"warn","ts":"2026-08-24T15:22:22.05083Z","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-24T15:22:22.050957Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.184: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.184:2380","--initial-cluster=instance=https://199.19.213.184:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.184:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.184: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-24T15:22:22.051058Z","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-24T15:22:22.051075Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.184:2380"]} {"level":"info","ts":"2026-08-24T15:22:22.051103Z","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-24T15:22:22.051993Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.184:2379"]} {"level":"info","ts":"2026-08-24T15:22:22.052093Z","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.184:2380"],"listen-peer-urls":["https://199.19.213.184:2380"],"advertise-client-urls":["https://199.19.213.184:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.184:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.184: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-24T15:22:22.062856Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.470837ms"} {"level":"info","ts":"2026-08-24T15:22:22.093207Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a98e0cfc7e94da4e","cluster-id":"6159be5634512a47"} {"level":"info","ts":"2026-08-24T15:22:22.093332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a98e0cfc7e94da4e switched to configuration voters=()"} {"level":"info","ts":"2026-08-24T15:22:22.093361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a98e0cfc7e94da4e became follower at term 0"} {"level":"info","ts":"2026-08-24T15:22:22.093372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a98e0cfc7e94da4e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-24T15:22:22.093385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a98e0cfc7e94da4e became follower at term 1"} {"level":"info","ts":"2026-08-24T15:22:22.093433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a98e0cfc7e94da4e switched to configuration voters=(12217717117697710670)"} {"level":"warn","ts":"2026-08-24T15:22:22.112988Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-24T15:22:22.120456Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-24T15:22:22.122496Z","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-24T15:22:22.125262Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a98e0cfc7e94da4e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-24T15:22:22.125607Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a98e0cfc7e94da4e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-24T15:22:22.126312Z","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-24T15:22:22.12643Z","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-24T15:22:22.126449Z","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-24T15:22:22.132194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a98e0cfc7e94da4e switched to configuration voters=(12217717117697710670)"} {"level":"info","ts":"2026-08-24T15:22:22.132362Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6159be5634512a47","local-member-id":"a98e0cfc7e94da4e","added-peer-id":"a98e0cfc7e94da4e","added-peer-peer-urls":["https://199.19.213.184:2380"]} {"level":"info","ts":"2026-08-24T15:22:22.132876Z","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-24T15:22:22.133202Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.184:2380"} {"level":"info","ts":"2026-08-24T15:22:22.133427Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.184:2380"} {"level":"info","ts":"2026-08-24T15:22:22.134484Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a98e0cfc7e94da4e","initial-advertise-peer-urls":["https://199.19.213.184:2380"],"listen-peer-urls":["https://199.19.213.184:2380"],"advertise-client-urls":["https://199.19.213.184:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.184:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-24T15:22:22.134602Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-24T15:22:22.194433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a98e0cfc7e94da4e is starting a new election at term 1"} {"level":"info","ts":"2026-08-24T15:22:22.194482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a98e0cfc7e94da4e became pre-candidate at term 1"} {"level":"info","ts":"2026-08-24T15:22:22.194512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a98e0cfc7e94da4e received MsgPreVoteResp from a98e0cfc7e94da4e at term 1"} {"level":"info","ts":"2026-08-24T15:22:22.194528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a98e0cfc7e94da4e became candidate at term 2"} {"level":"info","ts":"2026-08-24T15:22:22.194536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a98e0cfc7e94da4e received MsgVoteResp from a98e0cfc7e94da4e at term 2"} {"level":"info","ts":"2026-08-24T15:22:22.19455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a98e0cfc7e94da4e became leader at term 2"} {"level":"info","ts":"2026-08-24T15:22:22.194559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a98e0cfc7e94da4e elected leader a98e0cfc7e94da4e at term 2"} {"level":"info","ts":"2026-08-24T15:22:22.197468Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:22:22.1992Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a98e0cfc7e94da4e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.184:2379]}","request-path":"/0/members/a98e0cfc7e94da4e/attributes","cluster-id":"6159be5634512a47","publish-timeout":"7s"} {"level":"info","ts":"2026-08-24T15:22:22.199286Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:22:22.199737Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:22:22.202414Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.184:2379"} {"level":"info","ts":"2026-08-24T15:22:22.202558Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-24T15:22:22.202584Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-24T15:22:22.204959Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6159be5634512a47","local-member-id":"a98e0cfc7e94da4e","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:22:22.20508Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:22:22.205122Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:22:22.205516Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-24T15:22:24.769219Z","caller":"traceutil/trace.go:171","msg":"trace[1401704887] transaction","detail":"{read_only:false; response_revision:54; number_of_response:1; }","duration":"108.069917ms","start":"2026-08-24T15:22:24.66112Z","end":"2026-08-24T15:22:24.76919Z","steps":["trace[1401704887] 'process raft request' (duration: 108.010817ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:22:24.769344Z","caller":"traceutil/trace.go:171","msg":"trace[583216028] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"110.141471ms","start":"2026-08-24T15:22:24.659179Z","end":"2026-08-24T15:22:24.76932Z","steps":["trace[583216028] 'process raft request' (duration: 97.956693ms)","trace[583216028] 'compare' (duration: 11.819394ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:22:24.947743Z","caller":"traceutil/trace.go:171","msg":"trace[1925472520] transaction","detail":"{read_only:false; response_revision:57; number_of_response:1; }","duration":"155.306317ms","start":"2026-08-24T15:22:24.792399Z","end":"2026-08-24T15:22:24.947705Z","steps":["trace[1925472520] 'process raft request' (duration: 97.422788ms)","trace[1925472520] 'compare' (duration: 57.399452ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:22:24.94785Z","caller":"traceutil/trace.go:171","msg":"trace[1390964920] transaction","detail":"{read_only:false; response_revision:58; number_of_response:1; }","duration":"111.951242ms","start":"2026-08-24T15:22:24.835864Z","end":"2026-08-24T15:22:24.947816Z","steps":["trace[1390964920] 'process raft request' (duration: 111.538467ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:22:25.064135Z","caller":"traceutil/trace.go:171","msg":"trace[1103015855] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"111.128193ms","start":"2026-08-24T15:22:24.952967Z","end":"2026-08-24T15:22:25.064095Z","steps":["trace[1103015855] 'process raft request' (duration: 105.160231ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:22:42.177776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.229504ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-24T15:22:42.177894Z","caller":"traceutil/trace.go:171","msg":"trace[1971051322] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"927.439382ms","start":"2026-08-24T15:22:41.25044Z","end":"2026-08-24T15:22:42.177879Z","steps":["trace[1971051322] 'read index received' (duration: 423.089584ms)","trace[1971051322] 'applied index is now lower than readState.Index' (duration: 504.348858ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:22:42.177951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.511033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:22:42.177964Z","caller":"traceutil/trace.go:171","msg":"trace[2019805482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:414; }","duration":"927.576505ms","start":"2026-08-24T15:22:41.250383Z","end":"2026-08-24T15:22:42.177959Z","steps":["trace[2019805482] 'agreement among raft nodes before linearized reading' (duration: 927.528594ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:22:42.177986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:22:41.250357Z","time spent":"927.624457ms","remote":"127.0.0.1:41236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-24T15:22:42.442214Z","caller":"traceutil/trace.go:171","msg":"trace[1815529025] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"132.360217ms","start":"2026-08-24T15:22:42.309833Z","end":"2026-08-24T15:22:42.442194Z","steps":["trace[1815529025] 'process raft request' (duration: 132.095259ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:00.719927Z","caller":"traceutil/trace.go:171","msg":"trace[1141832303] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"768.740437ms","start":"2026-08-24T15:22:59.951157Z","end":"2026-08-24T15:23:00.719898Z","steps":["trace[1141832303] 'process raft request' (duration: 768.431608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:00.721215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:22:59.951135Z","time spent":"768.960422ms","remote":"127.0.0.1:41286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:23:01.20611Z","caller":"traceutil/trace.go:171","msg":"trace[1130458733] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"1.25339717s","start":"2026-08-24T15:22:59.952669Z","end":"2026-08-24T15:23:01.206067Z","steps":["trace[1130458733] 'process raft request' (duration: 1.253254436s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:01.206153Z","caller":"traceutil/trace.go:171","msg":"trace[1089852614] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"1.243173405s","start":"2026-08-24T15:22:59.962935Z","end":"2026-08-24T15:23:01.206109Z","steps":["trace[1089852614] 'process raft request' (duration: 1.243073862s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:01.206214Z","caller":"traceutil/trace.go:171","msg":"trace[2028412545] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"1.243237047s","start":"2026-08-24T15:22:59.962963Z","end":"2026-08-24T15:23:01.2062Z","steps":["trace[2028412545] 'process raft request' (duration: 1.243085872s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:01.206291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:22:59.962905Z","time spent":"1.243316079s","remote":"127.0.0.1:41286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:23:01.206141Z","caller":"traceutil/trace.go:171","msg":"trace[519223079] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"1.243248797s","start":"2026-08-24T15:22:59.962856Z","end":"2026-08-24T15:23:01.206105Z","steps":["trace[519223079] 'process raft request' (duration: 1.243110852s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:01.206321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:22:59.962957Z","time spent":"1.243297198s","remote":"127.0.0.1:41286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:23:01.206318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:22:59.952651Z","time spent":"1.253562115s","remote":"127.0.0.1:41286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:23:01.206304Z","caller":"traceutil/trace.go:171","msg":"trace[1521664392] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"1.242868976s","start":"2026-08-24T15:22:59.963387Z","end":"2026-08-24T15:23:01.206256Z","steps":["trace[1521664392] 'process raft request' (duration: 1.242696282s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:01.20645Z","caller":"traceutil/trace.go:171","msg":"trace[383773351] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.242589339s","start":"2026-08-24T15:22:59.963849Z","end":"2026-08-24T15:23:01.206438Z","steps":["trace[383773351] 'process raft request' (duration: 1.242291721s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:01.20646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:22:59.96338Z","time spent":"1.243009391s","remote":"127.0.0.1:41286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:23:01.206499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:22:59.963842Z","time spent":"1.24263053s","remote":"127.0.0.1:41286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:23:01.206508Z","caller":"traceutil/trace.go:171","msg":"trace[1667973429] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.233372891s","start":"2026-08-24T15:22:59.973105Z","end":"2026-08-24T15:23:01.206478Z","steps":["trace[1667973429] 'process raft request' (duration: 1.233110094s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:01.206519Z","caller":"traceutil/trace.go:171","msg":"trace[1985255742] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.227160858s","start":"2026-08-24T15:22:59.979332Z","end":"2026-08-24T15:23:01.206493Z","steps":["trace[1985255742] 'process raft request' (duration: 1.227027614s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:01.20659Z","caller":"traceutil/trace.go:171","msg":"trace[1041894596] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:540; }","duration":"955.507385ms","start":"2026-08-24T15:23:00.251068Z","end":"2026-08-24T15:23:01.206576Z","steps":["trace[1041894596] 'read index received' (duration: 468.554847ms)","trace[1041894596] 'applied index is now lower than readState.Index' (duration: 486.951758ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:23:01.206604Z","caller":"traceutil/trace.go:171","msg":"trace[1939818473] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.201259865s","start":"2026-08-24T15:23:00.005335Z","end":"2026-08-24T15:23:01.206595Z","steps":["trace[1939818473] 'process raft request' (duration: 1.20108194s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:01.206594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:22:59.979318Z","time spent":"1.22724051s","remote":"127.0.0.1:41286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:23:01.206591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:22:59.973077Z","time spent":"1.233473784s","remote":"127.0.0.1:41286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:23:01.206642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:00.005304Z","time spent":"1.201321446s","remote":"127.0.0.1:41286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:23:01.20635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:22:59.962838Z","time spent":"1.243498385s","remote":"127.0.0.1:41286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:23:01.206665Z","caller":"traceutil/trace.go:171","msg":"trace[2003448787] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.080502604s","start":"2026-08-24T15:23:00.126142Z","end":"2026-08-24T15:23:01.206644Z","steps":["trace[2003448787] 'process raft request' (duration: 1.080385101s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:01.206697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.630369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:23:01.206719Z","caller":"traceutil/trace.go:171","msg":"trace[2023701643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"955.704921ms","start":"2026-08-24T15:23:00.251007Z","end":"2026-08-24T15:23:01.206712Z","steps":["trace[2023701643] 'agreement among raft nodes before linearized reading' (duration: 955.635599ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:01.206737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:00.250985Z","time spent":"955.748302ms","remote":"127.0.0.1:41236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:23:01.206733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:00.126114Z","time spent":"1.080583526s","remote":"127.0.0.1:41286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:23:01.207135Z","caller":"traceutil/trace.go:171","msg":"trace[1657120897] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"1.255093868s","start":"2026-08-24T15:22:59.951649Z","end":"2026-08-24T15:23:01.206765Z","steps":["trace[1657120897] 'process raft request' (duration: 1.254121811s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:01.207251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:22:59.951635Z","time spent":"1.255564771s","remote":"127.0.0.1:41286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:23:01.216829Z","caller":"traceutil/trace.go:171","msg":"trace[1840625076] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"489.462106ms","start":"2026-08-24T15:23:00.727345Z","end":"2026-08-24T15:23:01.216807Z","steps":["trace[1840625076] 'process raft request' (duration: 488.462879ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:01.216993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:00.727328Z","time spent":"489.61063ms","remote":"127.0.0.1:41756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:23:01.218511Z","caller":"traceutil/trace.go:171","msg":"trace[912074201] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"488.130409ms","start":"2026-08-24T15:23:00.730345Z","end":"2026-08-24T15:23:01.218475Z","steps":["trace[912074201] 'process raft request' (duration: 485.566177ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:01.218817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:00.730337Z","time spent":"488.240732ms","remote":"127.0.0.1:41286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:23:01.226529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.670203ms","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-24T15:23:01.226593Z","caller":"traceutil/trace.go:171","msg":"trace[1912462172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:536; }","duration":"226.789157ms","start":"2026-08-24T15:23:00.99979Z","end":"2026-08-24T15:23:01.226579Z","steps":["trace[1912462172] 'agreement among raft nodes before linearized reading' (duration: 226.615592ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:01.226807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.096517ms","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-24T15:23:01.226807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.337282ms","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-24T15:23:01.226845Z","caller":"traceutil/trace.go:171","msg":"trace[1454559051] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:537; }","duration":"585.182189ms","start":"2026-08-24T15:23:00.641655Z","end":"2026-08-24T15:23:01.226837Z","steps":["trace[1454559051] 'agreement among raft nodes before linearized reading' (duration: 585.102106ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:01.226871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:00.641635Z","time spent":"585.2292ms","remote":"127.0.0.1:41484","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-24T15:23:01.226911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.422419ms","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-24T15:23:01.226965Z","caller":"traceutil/trace.go:171","msg":"trace[1329808264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:537; }","duration":"542.512472ms","start":"2026-08-24T15:23:00.684433Z","end":"2026-08-24T15:23:01.226945Z","steps":["trace[1329808264] 'agreement among raft nodes before linearized reading' (duration: 542.45463ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:01.22699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:00.684402Z","time spent":"542.580784ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-24T15:23:01.226873Z","caller":"traceutil/trace.go:171","msg":"trace[990772236] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:537; }","duration":"581.450165ms","start":"2026-08-24T15:23:00.645412Z","end":"2026-08-24T15:23:01.226862Z","steps":["trace[990772236] 'agreement among raft nodes before linearized reading' (duration: 581.322922ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:01.227093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:00.64536Z","time spent":"581.720693ms","remote":"127.0.0.1:41484","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":"info","ts":"2026-08-24T15:23:03.425467Z","caller":"traceutil/trace.go:171","msg":"trace[299488834] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"150.074778ms","start":"2026-08-24T15:23:03.275366Z","end":"2026-08-24T15:23:03.425441Z","steps":["trace[299488834] 'process raft request' (duration: 148.441442ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:03.425845Z","caller":"traceutil/trace.go:171","msg":"trace[806629851] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"150.376406ms","start":"2026-08-24T15:23:03.275437Z","end":"2026-08-24T15:23:03.425814Z","steps":["trace[806629851] 'process raft request' (duration: 149.957274ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:03.426156Z","caller":"traceutil/trace.go:171","msg":"trace[972973995] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"150.604371ms","start":"2026-08-24T15:23:03.275526Z","end":"2026-08-24T15:23:03.426131Z","steps":["trace[972973995] 'process raft request' (duration: 150.093547ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:03.743283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.324271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:23:03.743372Z","caller":"traceutil/trace.go:171","msg":"trace[402948305] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:605; }","duration":"131.460945ms","start":"2026-08-24T15:23:03.611893Z","end":"2026-08-24T15:23:03.743354Z","steps":["trace[402948305] 'range keys from in-memory index tree' (duration: 131.226789ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:03.743377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.083755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:23:03.743669Z","caller":"traceutil/trace.go:171","msg":"trace[909972680] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:605; }","duration":"131.380193ms","start":"2026-08-24T15:23:03.612257Z","end":"2026-08-24T15:23:03.743637Z","steps":["trace[909972680] 'range keys from in-memory index tree' (duration: 131.059405ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:03.743425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.112036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:23:03.743789Z","caller":"traceutil/trace.go:171","msg":"trace[79666059] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:605; }","duration":"131.473645ms","start":"2026-08-24T15:23:03.6123Z","end":"2026-08-24T15:23:03.743774Z","steps":["trace[79666059] 'count revisions from in-memory index tree' (duration: 131.093736ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:03.955887Z","caller":"traceutil/trace.go:171","msg":"trace[247059325] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"106.5462ms","start":"2026-08-24T15:23:03.849317Z","end":"2026-08-24T15:23:03.955863Z","steps":["trace[247059325] 'read index received' (duration: 106.272332ms)","trace[247059325] 'applied index is now lower than readState.Index' (duration: 272.658µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:23:03.956037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.682994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:23:03.95605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.709405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:23:03.956093Z","caller":"traceutil/trace.go:171","msg":"trace[2105810755] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:606; }","duration":"106.797047ms","start":"2026-08-24T15:23:03.849287Z","end":"2026-08-24T15:23:03.956085Z","steps":["trace[2105810755] 'agreement among raft nodes before linearized reading' (duration: 106.669273ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:03.956092Z","caller":"traceutil/trace.go:171","msg":"trace[1919176775] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:606; }","duration":"106.788696ms","start":"2026-08-24T15:23:03.849287Z","end":"2026-08-24T15:23:03.956076Z","steps":["trace[1919176775] 'agreement among raft nodes before linearized reading' (duration: 106.647863ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:03.955908Z","caller":"traceutil/trace.go:171","msg":"trace[1556756122] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"126.843888ms","start":"2026-08-24T15:23:03.82904Z","end":"2026-08-24T15:23:03.955884Z","steps":["trace[1556756122] 'process raft request' (duration: 126.592101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:03.9563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.833691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:23:03.95684Z","caller":"traceutil/trace.go:171","msg":"trace[2048674983] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:606; }","duration":"106.389706ms","start":"2026-08-24T15:23:03.850433Z","end":"2026-08-24T15:23:03.956822Z","steps":["trace[2048674983] 'agreement among raft nodes before linearized reading' (duration: 105.81736ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:04.122196Z","caller":"traceutil/trace.go:171","msg":"trace[1241501675] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"154.141326ms","start":"2026-08-24T15:23:03.968012Z","end":"2026-08-24T15:23:04.122153Z","steps":["trace[1241501675] 'process raft request' (duration: 151.509145ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:04.122275Z","caller":"traceutil/trace.go:171","msg":"trace[460226742] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"154.216817ms","start":"2026-08-24T15:23:03.968036Z","end":"2026-08-24T15:23:04.122253Z","steps":["trace[460226742] 'process raft request' (duration: 154.047423ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:04.122513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.757026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:23:04.122575Z","caller":"traceutil/trace.go:171","msg":"trace[1290953725] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:608; }","duration":"137.867659ms","start":"2026-08-24T15:23:03.984693Z","end":"2026-08-24T15:23:04.122561Z","steps":["trace[1290953725] 'agreement among raft nodes before linearized reading' (duration: 137.726325ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:04.122162Z","caller":"traceutil/trace.go:171","msg":"trace[871107919] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:629; }","duration":"137.393455ms","start":"2026-08-24T15:23:03.984734Z","end":"2026-08-24T15:23:04.122127Z","steps":["trace[871107919] 'read index received' (duration: 134.803975ms)","trace[871107919] 'applied index is now lower than readState.Index' (duration: 2.58849ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:23:04.123177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.186908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:23:04.123227Z","caller":"traceutil/trace.go:171","msg":"trace[2067695395] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:608; }","duration":"138.31024ms","start":"2026-08-24T15:23:03.984903Z","end":"2026-08-24T15:23:04.123213Z","steps":["trace[2067695395] 'agreement among raft nodes before linearized reading' (duration: 138.120225ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:04.123449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.825706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:23:04.123492Z","caller":"traceutil/trace.go:171","msg":"trace[98696709] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:608; }","duration":"106.882178ms","start":"2026-08-24T15:23:04.016601Z","end":"2026-08-24T15:23:04.123483Z","steps":["trace[98696709] 'agreement among raft nodes before linearized reading' (duration: 106.817186ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:04.12374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.947767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:23:04.124004Z","caller":"traceutil/trace.go:171","msg":"trace[57737091] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:608; }","duration":"108.207004ms","start":"2026-08-24T15:23:04.015766Z","end":"2026-08-24T15:23:04.123973Z","steps":["trace[57737091] 'agreement among raft nodes before linearized reading' (duration: 107.926816ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:04.124208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.732446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:23:04.124247Z","caller":"traceutil/trace.go:171","msg":"trace[1673299601] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:608; }","duration":"124.783608ms","start":"2026-08-24T15:23:03.999454Z","end":"2026-08-24T15:23:04.124237Z","steps":["trace[1673299601] 'agreement among raft nodes before linearized reading' (duration: 124.719026ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:04.124264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.701226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:23:04.124814Z","caller":"traceutil/trace.go:171","msg":"trace[225671645] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:608; }","duration":"125.247731ms","start":"2026-08-24T15:23:03.999482Z","end":"2026-08-24T15:23:04.12473Z","steps":["trace[225671645] 'agreement among raft nodes before linearized reading' (duration: 124.509671ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:04.124853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.335729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:23:04.124878Z","caller":"traceutil/trace.go:171","msg":"trace[1977700414] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:608; }","duration":"123.39722ms","start":"2026-08-24T15:23:04.001474Z","end":"2026-08-24T15:23:04.124871Z","steps":["trace[1977700414] 'agreement among raft nodes before linearized reading' (duration: 123.355799ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:04.12488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.112349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:23:04.124932Z","caller":"traceutil/trace.go:171","msg":"trace[158631517] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:608; }","duration":"109.185511ms","start":"2026-08-24T15:23:04.01573Z","end":"2026-08-24T15:23:04.124915Z","steps":["trace[158631517] 'agreement among raft nodes before linearized reading' (duration: 109.104468ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:07.902406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.239204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cd5cn\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-24T15:23:07.902524Z","caller":"traceutil/trace.go:171","msg":"trace[1519230114] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cd5cn; range_end:; response_count:1; response_revision:634; }","duration":"158.370727ms","start":"2026-08-24T15:23:07.744127Z","end":"2026-08-24T15:23:07.902497Z","steps":["trace[1519230114] 'range keys from in-memory index tree' (duration: 158.015949ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:08.599118Z","caller":"traceutil/trace.go:171","msg":"trace[1263804277] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"743.751612ms","start":"2026-08-24T15:23:07.855345Z","end":"2026-08-24T15:23:08.599096Z","steps":["trace[1263804277] 'process raft request' (duration: 743.290949ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:08.599275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:07.85531Z","time spent":"743.878516ms","remote":"127.0.0.1:41322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:23:09.774721Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.176227659s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T15:23:09.775127Z","caller":"traceutil/trace.go:171","msg":"trace[522531461] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:658; }","duration":"1.523499904s","start":"2026-08-24T15:23:08.251607Z","end":"2026-08-24T15:23:09.775107Z","steps":["trace[522531461] 'read index received' (duration: 346.962836ms)","trace[522531461] 'applied index is now lower than readState.Index' (duration: 1.176536038s)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:23:09.775243Z","caller":"traceutil/trace.go:171","msg":"trace[1325366792] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.755948811s","start":"2026-08-24T15:23:08.019252Z","end":"2026-08-24T15:23:09.775201Z","steps":["trace[1325366792] 'process raft request' (duration: 1.755806837s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:09.775701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:08.019225Z","time spent":"1.756356463s","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:23:09.775261Z","caller":"traceutil/trace.go:171","msg":"trace[2122772064] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.855842195s","start":"2026-08-24T15:23:07.919374Z","end":"2026-08-24T15:23:09.775216Z","steps":["trace[2122772064] 'process raft request' (duration: 1.855544317s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:09.775939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:07.919332Z","time spent":"1.856571695s","remote":"127.0.0.1:41420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:23:09.775424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.523794781s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:23:09.776115Z","caller":"traceutil/trace.go:171","msg":"trace[1837386403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"1.524515232s","start":"2026-08-24T15:23:08.251552Z","end":"2026-08-24T15:23:09.776067Z","steps":["trace[1837386403] 'agreement among raft nodes before linearized reading' (duration: 1.523773772s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:09.776244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:08.25153Z","time spent":"1.524697657s","remote":"127.0.0.1:41236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-24T15:23:09.912643Z","caller":"traceutil/trace.go:171","msg":"trace[1726497631] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"137.067901ms","start":"2026-08-24T15:23:09.775546Z","end":"2026-08-24T15:23:09.912614Z","steps":["trace[1726497631] 'read index received' (duration: 136.916777ms)","trace[1726497631] 'applied index is now lower than readState.Index' (duration: 149.984µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:23:09.912848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.241195ms","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-24T15:23:09.912901Z","caller":"traceutil/trace.go:171","msg":"trace[1415623982] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"434.348639ms","start":"2026-08-24T15:23:09.478543Z","end":"2026-08-24T15:23:09.912891Z","steps":["trace[1415623982] 'agreement among raft nodes before linearized reading' (duration: 434.166713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:09.912937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:09.478521Z","time spent":"434.407391ms","remote":"127.0.0.1:41484","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-24T15:23:09.912952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.970935ms","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-24T15:23:09.912945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.500403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-24T15:23:09.912994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.556354ms","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-24T15:23:09.913005Z","caller":"traceutil/trace.go:171","msg":"trace[153748893] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.259310676s","start":"2026-08-24T15:23:08.653653Z","end":"2026-08-24T15:23:09.912963Z","steps":["trace[153748893] 'process raft request' (duration: 1.258801631s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:09.91305Z","caller":"traceutil/trace.go:171","msg":"trace[432218685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"553.054737ms","start":"2026-08-24T15:23:09.35993Z","end":"2026-08-24T15:23:09.912985Z","steps":["trace[432218685] 'agreement among raft nodes before linearized reading' (duration: 552.81701ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:09.913061Z","caller":"traceutil/trace.go:171","msg":"trace[1593734573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"434.641047ms","start":"2026-08-24T15:23:09.4784Z","end":"2026-08-24T15:23:09.913041Z","steps":["trace[1593734573] 'agreement among raft nodes before linearized reading' (duration: 434.472832ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:09.91306Z","caller":"traceutil/trace.go:171","msg":"trace[1309766546] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:638; }","duration":"434.609906ms","start":"2026-08-24T15:23:09.478399Z","end":"2026-08-24T15:23:09.913009Z","steps":["trace[1309766546] 'agreement among raft nodes before linearized reading' (duration: 434.342448ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:09.913153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:09.478385Z","time spent":"434.756579ms","remote":"127.0.0.1:41484","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-08-24T15:23:09.91319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:09.478384Z","time spent":"434.78872ms","remote":"127.0.0.1:41484","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-24T15:23:09.913165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:09.359912Z","time spent":"553.174381ms","remote":"127.0.0.1:41484","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-24T15:23:09.913184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:08.653634Z","time spent":"1.259437039s","remote":"127.0.0.1:41468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:23:09.913206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.15724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:23:09.913543Z","caller":"traceutil/trace.go:171","msg":"trace[1059592035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"129.760066ms","start":"2026-08-24T15:23:09.783719Z","end":"2026-08-24T15:23:09.913479Z","steps":["trace[1059592035] 'agreement among raft nodes before linearized reading' (duration: 129.115919ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:10.443537Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15730686695220187801,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:23:10.943998Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15730686695220187801,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:23:11.294821Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.351883093s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-24T15:23:11.920966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000459876s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-24T15:23:11.921103Z","caller":"traceutil/trace.go:171","msg":"trace[985689718] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000629161s","start":"2026-08-24T15:23:09.920455Z","end":"2026-08-24T15:23:11.921084Z","steps":["trace[985689718] 'agreement among raft nodes before linearized reading' (duration: 2.000474487s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:11.921152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:09.920446Z","time spent":"2.000695583s","remote":"127.0.0.1:41236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/24 15:23:11 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T15:23:12.009054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.963022ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:23:12.009861Z","caller":"traceutil/trace.go:171","msg":"trace[1143463167] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"2.090843276s","start":"2026-08-24T15:23:09.918996Z","end":"2026-08-24T15:23:12.00984Z","steps":["trace[1143463167] 'process raft request' (duration: 2.090158118s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:12.009955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:09.918975Z","time spent":"2.090938798s","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:23:12.010043Z","caller":"traceutil/trace.go:171","msg":"trace[676803391] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"2.090936668s","start":"2026-08-24T15:23:09.919085Z","end":"2026-08-24T15:23:12.010022Z","steps":["trace[676803391] 'process raft request' (duration: 2.090140997s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:12.01014Z","caller":"traceutil/trace.go:171","msg":"trace[632340807] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"2.092240304s","start":"2026-08-24T15:23:09.917888Z","end":"2026-08-24T15:23:12.010128Z","steps":["trace[632340807] 'process raft request' (duration: 1.377123971s)","trace[632340807] 'compare' (duration: 713.553072ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:23:12.010163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:09.919081Z","time spent":"2.09102477s","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:23:12.010148Z","caller":"traceutil/trace.go:171","msg":"trace[868965289] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:663; }","duration":"2.067068817s","start":"2026-08-24T15:23:09.943041Z","end":"2026-08-24T15:23:12.01011Z","steps":["trace[868965289] 'read index received' (duration: 1.351981366s)","trace[868965289] 'applied index is now lower than readState.Index' (duration: 715.083971ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:23:12.0102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:09.917871Z","time spent":"2.092301135s","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:23:12.218836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.550815ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-24T15:23:12.218954Z","caller":"traceutil/trace.go:171","msg":"trace[799215944] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"208.676612ms","start":"2026-08-24T15:23:12.010254Z","end":"2026-08-24T15:23:12.21893Z","steps":["trace[799215944] 'read index received' (duration: 197.375µs)","trace[799215944] 'applied index is now lower than readState.Index' (duration: 208.477357ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:23:12.219062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.272768439s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-08-24T15:23:12.219103Z","caller":"traceutil/trace.go:171","msg":"trace[813462153] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:642; }","duration":"2.272841631s","start":"2026-08-24T15:23:09.946251Z","end":"2026-08-24T15:23:12.219093Z","steps":["trace[813462153] 'agreement among raft nodes before linearized reading' (duration: 2.272738309s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:12.219143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:09.94624Z","time spent":"2.272892782s","remote":"127.0.0.1:41440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-08-24T15:23:12.219162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.917391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:23:12.219195Z","caller":"traceutil/trace.go:171","msg":"trace[1051363543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"297.988623ms","start":"2026-08-24T15:23:11.921197Z","end":"2026-08-24T15:23:12.219186Z","steps":["trace[1051363543] 'agreement among raft nodes before linearized reading' (duration: 297.931371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:12.219223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.05182ms","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-24T15:23:12.219296Z","caller":"traceutil/trace.go:171","msg":"trace[152698218] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:642; }","duration":"272.164023ms","start":"2026-08-24T15:23:11.947116Z","end":"2026-08-24T15:23:12.21928Z","steps":["trace[152698218] 'agreement among raft nodes before linearized reading' (duration: 271.839995ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:15.902489Z","caller":"traceutil/trace.go:171","msg":"trace[1277740670] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"109.29908ms","start":"2026-08-24T15:23:15.793144Z","end":"2026-08-24T15:23:15.902443Z","steps":["trace[1277740670] 'process raft request' (duration: 63.918477ms)","trace[1277740670] 'compare' (duration: 45.097605ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:23:16.519306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.30688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:23:16.519483Z","caller":"traceutil/trace.go:171","msg":"trace[1682898552] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"218.464685ms","start":"2026-08-24T15:23:16.300996Z","end":"2026-08-24T15:23:16.519461Z","steps":["trace[1682898552] 'process raft request' (duration: 77.945739ms)","trace[1682898552] 'compare' (duration: 140.188747ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:23:17.749627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.246783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:23:17.749735Z","caller":"traceutil/trace.go:171","msg":"trace[1280756276] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:700; }","duration":"951.98369ms","start":"2026-08-24T15:23:16.797734Z","end":"2026-08-24T15:23:17.749718Z","steps":["trace[1280756276] 'read index received' (duration: 193.586584ms)","trace[1280756276] 'applied index is now lower than readState.Index' (duration: 758.395966ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:23:17.749939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.207297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cd5cn\" ","response":"range_response_count:1 size:18067"} {"level":"info","ts":"2026-08-24T15:23:17.749981Z","caller":"traceutil/trace.go:171","msg":"trace[345176570] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cd5cn; range_end:; response_count:1; response_revision:675; }","duration":"952.285938ms","start":"2026-08-24T15:23:16.797686Z","end":"2026-08-24T15:23:17.749972Z","steps":["trace[345176570] 'agreement among raft nodes before linearized reading' (duration: 952.079172ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:17.750014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:16.797668Z","time spent":"952.33858ms","remote":"127.0.0.1:41420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18090,"request content":"key:\"/registry/pods/kube-system/cilium-cd5cn\" "} {"level":"info","ts":"2026-08-24T15:23:17.750213Z","caller":"traceutil/trace.go:171","msg":"trace[761913462] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"1.218168634s","start":"2026-08-24T15:23:16.532032Z","end":"2026-08-24T15:23:17.7502Z","steps":["trace[761913462] 'process raft request' (duration: 459.278914ms)","trace[761913462] 'compare' (duration: 758.12066ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:23:17.750273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:16.532011Z","time spent":"1.218237465s","remote":"127.0.0.1:41306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:23:19.145754Z","caller":"traceutil/trace.go:171","msg":"trace[195412478] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"116.75217ms","start":"2026-08-24T15:23:19.028984Z","end":"2026-08-24T15:23:19.145736Z","steps":["trace[195412478] 'process raft request' (duration: 116.629437ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:24.368798Z","caller":"traceutil/trace.go:171","msg":"trace[1916146297] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"124.751189ms","start":"2026-08-24T15:23:24.244016Z","end":"2026-08-24T15:23:24.368767Z","steps":["trace[1916146297] 'process raft request' (duration: 76.226226ms)","trace[1916146297] 'compare' (duration: 48.366949ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:23:24.401736Z","caller":"traceutil/trace.go:171","msg":"trace[539348085] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:784; }","duration":"151.628482ms","start":"2026-08-24T15:23:24.250085Z","end":"2026-08-24T15:23:24.401713Z","steps":["trace[539348085] 'read index received' (duration: 70.082ms)","trace[539348085] 'applied index is now lower than readState.Index' (duration: 81.545392ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:23:24.401907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.800467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:23:24.401875Z","caller":"traceutil/trace.go:171","msg":"trace[830563631] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"157.661985ms","start":"2026-08-24T15:23:24.244179Z","end":"2026-08-24T15:23:24.401841Z","steps":["trace[830563631] 'process raft request' (duration: 157.429139ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:24.402046Z","caller":"traceutil/trace.go:171","msg":"trace[1253711261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:758; }","duration":"151.983541ms","start":"2026-08-24T15:23:24.250047Z","end":"2026-08-24T15:23:24.40203Z","steps":["trace[1253711261] 'agreement among raft nodes before linearized reading' (duration: 151.789606ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:25.137618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.633646ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-08-24T15:23:25.137982Z","caller":"traceutil/trace.go:171","msg":"trace[209804677] transaction","detail":"{read_only:false; number_of_response:0; response_revision:761; }","duration":"250.067059ms","start":"2026-08-24T15:23:24.887896Z","end":"2026-08-24T15:23:25.137963Z","steps":["trace[209804677] 'process raft request' (duration: 80.023913ms)","trace[209804677] 'compare' (duration: 169.577114ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:23:25.137847Z","caller":"traceutil/trace.go:171","msg":"trace[507475037] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"240.104236ms","start":"2026-08-24T15:23:24.897726Z","end":"2026-08-24T15:23:25.13783Z","steps":["trace[507475037] 'process raft request' (duration: 240.006503ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:25.324162Z","caller":"traceutil/trace.go:171","msg":"trace[6827873] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"179.426411ms","start":"2026-08-24T15:23:25.144705Z","end":"2026-08-24T15:23:25.324132Z","steps":["trace[6827873] 'process raft request' (duration: 177.474882ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:25.520699Z","caller":"traceutil/trace.go:171","msg":"trace[110365905] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"103.223463ms","start":"2026-08-24T15:23:25.417454Z","end":"2026-08-24T15:23:25.520678Z","steps":["trace[110365905] 'process raft request' (duration: 61.005854ms)","trace[110365905] 'compare' (duration: 42.114316ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:23:26.660444Z","caller":"traceutil/trace.go:171","msg":"trace[1339444044] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"177.773463ms","start":"2026-08-24T15:23:26.482652Z","end":"2026-08-24T15:23:26.660425Z","steps":["trace[1339444044] 'process raft request' (duration: 144.232258ms)","trace[1339444044] 'compare' (duration: 33.406052ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:23:27.300557Z","caller":"traceutil/trace.go:171","msg":"trace[1453956721] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"100.972467ms","start":"2026-08-24T15:23:27.199534Z","end":"2026-08-24T15:23:27.300506Z","steps":["trace[1453956721] 'process raft request' (duration: 99.983742ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:28.734386Z","caller":"traceutil/trace.go:171","msg":"trace[76223981] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"242.907263ms","start":"2026-08-24T15:23:28.491455Z","end":"2026-08-24T15:23:28.734363Z","steps":["trace[76223981] 'process raft request' (duration: 242.754909ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:28.877739Z","caller":"traceutil/trace.go:171","msg":"trace[1239456451] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:809; }","duration":"292.015512ms","start":"2026-08-24T15:23:28.585693Z","end":"2026-08-24T15:23:28.877709Z","steps":["trace[1239456451] 'read index received' (duration: 148.768065ms)","trace[1239456451] 'applied index is now lower than readState.Index' (duration: 143.246177ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:23:28.877881Z","caller":"traceutil/trace.go:171","msg":"trace[1928121311] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"386.26588ms","start":"2026-08-24T15:23:28.491592Z","end":"2026-08-24T15:23:28.877858Z","steps":["trace[1928121311] 'process raft request' (duration: 331.452428ms)","trace[1928121311] 'compare' (duration: 54.541676ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:23:28.877948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.243357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cd5cn\" ","response":"range_response_count:1 size:18713"} {"level":"info","ts":"2026-08-24T15:23:28.877993Z","caller":"traceutil/trace.go:171","msg":"trace[756072592] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cd5cn; range_end:; response_count:1; response_revision:780; }","duration":"292.36245ms","start":"2026-08-24T15:23:28.585618Z","end":"2026-08-24T15:23:28.877981Z","steps":["trace[756072592] 'agreement among raft nodes before linearized reading' (duration: 292.242698ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:28.878008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.172832ms","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-24T15:23:28.878022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:28.49157Z","time spent":"386.359263ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:23:28.878068Z","caller":"traceutil/trace.go:171","msg":"trace[1504424663] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:780; }","duration":"214.264684ms","start":"2026-08-24T15:23:28.663789Z","end":"2026-08-24T15:23:28.878054Z","steps":["trace[1504424663] 'agreement among raft nodes before linearized reading' (duration: 214.07683ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:29.130216Z","caller":"traceutil/trace.go:171","msg":"trace[1404222908] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"239.104775ms","start":"2026-08-24T15:23:28.891057Z","end":"2026-08-24T15:23:29.130162Z","steps":["trace[1404222908] 'process raft request' (duration: 180.098833ms)","trace[1404222908] 'compare' (duration: 58.848319ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:23:29.472853Z","caller":"traceutil/trace.go:171","msg":"trace[73279616] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"325.85717ms","start":"2026-08-24T15:23:29.146952Z","end":"2026-08-24T15:23:29.472809Z","steps":["trace[73279616] 'process raft request' (duration: 325.733587ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:29.47299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:29.146935Z","time spent":"325.988824ms","remote":"127.0.0.1:41420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:23:29.716774Z","caller":"traceutil/trace.go:171","msg":"trace[1585157566] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:812; }","duration":"466.884ms","start":"2026-08-24T15:23:29.24986Z","end":"2026-08-24T15:23:29.716744Z","steps":["trace[1585157566] 'read index received' (duration: 222.869061ms)","trace[1585157566] 'applied index is now lower than readState.Index' (duration: 244.014209ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:23:29.716873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.001262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:23:29.716895Z","caller":"traceutil/trace.go:171","msg":"trace[1670748390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:784; }","duration":"467.040473ms","start":"2026-08-24T15:23:29.249848Z","end":"2026-08-24T15:23:29.716888Z","steps":["trace[1670748390] 'agreement among raft nodes before linearized reading' (duration: 466.982322ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:29.716919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:29.2498Z","time spent":"467.113445ms","remote":"127.0.0.1:41238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-24T15:23:29.717145Z","caller":"traceutil/trace.go:171","msg":"trace[1149641520] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"551.748217ms","start":"2026-08-24T15:23:29.165367Z","end":"2026-08-24T15:23:29.717115Z","steps":["trace[1149641520] 'process raft request' (duration: 551.195723ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:29.717242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:29.165347Z","time spent":"551.837249ms","remote":"127.0.0.1:41404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4693,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:23:29.94661Z","caller":"traceutil/trace.go:171","msg":"trace[1695634977] transaction","detail":"{read_only:false; number_of_response:1; response_revision:784; }","duration":"464.221563ms","start":"2026-08-24T15:23:29.482355Z","end":"2026-08-24T15:23:29.946577Z","steps":["trace[1695634977] 'process raft request' (duration: 409.328313ms)","trace[1695634977] 'compare' (duration: 54.648044ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:23:29.947424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:29.482334Z","time spent":"464.381757ms","remote":"127.0.0.1:41404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30,"response count":0,"response size":4742,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:23:29.947431Z","caller":"traceutil/trace.go:171","msg":"trace[1010241787] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"461.542756ms","start":"2026-08-24T15:23:29.485861Z","end":"2026-08-24T15:23:29.947403Z","steps":["trace[1010241787] 'process raft request' (duration: 460.613004ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:29.947582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:29.485851Z","time spent":"461.640599ms","remote":"127.0.0.1:41702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14465,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:23:30.159989Z","caller":"traceutil/trace.go:171","msg":"trace[1439538885] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"108.436546ms","start":"2026-08-24T15:23:30.051531Z","end":"2026-08-24T15:23:30.159968Z","steps":["trace[1439538885] 'process raft request' (duration: 108.261762ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:30.244867Z","caller":"traceutil/trace.go:171","msg":"trace[522674145] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"166.483909ms","start":"2026-08-24T15:23:30.078353Z","end":"2026-08-24T15:23:30.244837Z","steps":["trace[522674145] 'process raft request' (duration: 166.332585ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:30.609645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.746708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:23:30.610142Z","caller":"traceutil/trace.go:171","msg":"trace[1154507406] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"265.394858ms","start":"2026-08-24T15:23:30.344682Z","end":"2026-08-24T15:23:30.610077Z","steps":["trace[1154507406] 'process raft request' (duration: 102.135198ms)","trace[1154507406] 'compare' (duration: 162.610054ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:23:30.610295Z","caller":"traceutil/trace.go:171","msg":"trace[1966811447] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"260.807664ms","start":"2026-08-24T15:23:30.349462Z","end":"2026-08-24T15:23:30.61027Z","steps":["trace[1966811447] 'process raft request' (duration: 260.336902ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:30.822516Z","caller":"traceutil/trace.go:171","msg":"trace[781669775] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"208.193217ms","start":"2026-08-24T15:23:30.614296Z","end":"2026-08-24T15:23:30.822489Z","steps":["trace[781669775] 'process raft request' (duration: 130.625808ms)","trace[781669775] 'compare' (duration: 77.232619ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:23:31.055772Z","caller":"traceutil/trace.go:171","msg":"trace[525419111] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:826; }","duration":"105.684323ms","start":"2026-08-24T15:23:30.950069Z","end":"2026-08-24T15:23:31.055753Z","steps":["trace[525419111] 'read index received' (duration: 100.611707ms)","trace[525419111] 'applied index is now lower than readState.Index' (duration: 5.071286ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:23:31.055875Z","caller":"traceutil/trace.go:171","msg":"trace[1550372189] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"161.965932ms","start":"2026-08-24T15:23:30.893875Z","end":"2026-08-24T15:23:31.055841Z","steps":["trace[1550372189] 'process raft request' (duration: 156.849675ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:31.056855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.749659ms","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-24T15:23:31.056902Z","caller":"traceutil/trace.go:171","msg":"trace[599850213] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:795; }","duration":"106.867612ms","start":"2026-08-24T15:23:30.950023Z","end":"2026-08-24T15:23:31.056891Z","steps":["trace[599850213] 'agreement among raft nodes before linearized reading' (duration: 105.803186ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:31.167392Z","caller":"traceutil/trace.go:171","msg":"trace[2130055174] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"106.830997ms","start":"2026-08-24T15:23:31.060535Z","end":"2026-08-24T15:23:31.167366Z","steps":["trace[2130055174] 'process raft request' (duration: 100.519229ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:31.528013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.238201ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-24T15:23:33.091465Z","caller":"traceutil/trace.go:171","msg":"trace[1329682108] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"149.997506ms","start":"2026-08-24T15:23:32.941447Z","end":"2026-08-24T15:23:33.091445Z","steps":["trace[1329682108] 'process raft request' (duration: 60.162445ms)","trace[1329682108] 'compare' (duration: 89.740368ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:23:33.102277Z","caller":"traceutil/trace.go:171","msg":"trace[931732214] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"159.361186ms","start":"2026-08-24T15:23:32.942839Z","end":"2026-08-24T15:23:33.1022Z","steps":["trace[931732214] 'process raft request' (duration: 159.213293ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:33.102404Z","caller":"traceutil/trace.go:171","msg":"trace[1819544511] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"159.363945ms","start":"2026-08-24T15:23:32.94303Z","end":"2026-08-24T15:23:33.102394Z","steps":["trace[1819544511] 'process raft request' (duration: 159.084538ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:33.102484Z","caller":"traceutil/trace.go:171","msg":"trace[1815605606] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"154.456755ms","start":"2026-08-24T15:23:32.948009Z","end":"2026-08-24T15:23:33.102466Z","steps":["trace[1815605606] 'process raft request' (duration: 154.154077ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:34.902118Z","caller":"traceutil/trace.go:171","msg":"trace[630113322] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"267.56084ms","start":"2026-08-24T15:23:34.63454Z","end":"2026-08-24T15:23:34.902101Z","steps":["trace[630113322] 'process raft request' (duration: 267.411037ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:38.897699Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15730686695220188217,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:23:39.00675Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.08601881s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T15:23:39.007055Z","caller":"traceutil/trace.go:171","msg":"trace[2027249326] linearizableReadLoop","detail":"{readStateIndex:867; appliedIndex:866; }","duration":"610.053253ms","start":"2026-08-24T15:23:38.396984Z","end":"2026-08-24T15:23:39.007037Z","steps":["trace[2027249326] 'read index received' (duration: 609.884729ms)","trace[2027249326] 'applied index is now lower than readState.Index' (duration: 167.524µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:23:39.007101Z","caller":"traceutil/trace.go:171","msg":"trace[2026956598] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"1.08643439s","start":"2026-08-24T15:23:37.920643Z","end":"2026-08-24T15:23:39.007077Z","steps":["trace[2026956598] 'process raft request' (duration: 1.086289557s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:39.007221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.231947ms","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":"warn","ts":"2026-08-24T15:23:39.007226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:37.920625Z","time spent":"1.086523342s","remote":"127.0.0.1:41468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:23:39.00725Z","caller":"traceutil/trace.go:171","msg":"trace[1575248714] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:833; }","duration":"610.317029ms","start":"2026-08-24T15:23:38.396926Z","end":"2026-08-24T15:23:39.007243Z","steps":["trace[1575248714] 'agreement among raft nodes before linearized reading' (duration: 610.205667ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:39.007275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:23:38.396906Z","time spent":"610.36338ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-24T15:23:39.121516Z","caller":"traceutil/trace.go:171","msg":"trace[1542331449] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"110.708718ms","start":"2026-08-24T15:23:39.010778Z","end":"2026-08-24T15:23:39.121487Z","steps":["trace[1542331449] 'process raft request' (duration: 106.649501ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:39.125087Z","caller":"traceutil/trace.go:171","msg":"trace[929852798] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"107.424859ms","start":"2026-08-24T15:23:39.017653Z","end":"2026-08-24T15:23:39.125078Z","steps":["trace[929852798] 'process raft request' (duration: 107.372988ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:53.323304Z","caller":"traceutil/trace.go:171","msg":"trace[925595005] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1029; }","duration":"138.699013ms","start":"2026-08-24T15:23:53.184583Z","end":"2026-08-24T15:23:53.323282Z","steps":["trace[925595005] 'read index received' (duration: 138.488608ms)","trace[925595005] 'applied index is now lower than readState.Index' (duration: 209.575µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:23:53.323341Z","caller":"traceutil/trace.go:171","msg":"trace[800461599] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"141.533808ms","start":"2026-08-24T15:23:53.181784Z","end":"2026-08-24T15:23:53.323318Z","steps":["trace[800461599] 'process raft request' (duration: 141.369274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:53.323569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.908551ms","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-24T15:23:53.32357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.976879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-24T15:23:53.323603Z","caller":"traceutil/trace.go:171","msg":"trace[1344938376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:993; }","duration":"129.966952ms","start":"2026-08-24T15:23:53.193627Z","end":"2026-08-24T15:23:53.323594Z","steps":["trace[1344938376] 'agreement among raft nodes before linearized reading' (duration: 129.850739ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:23:53.323649Z","caller":"traceutil/trace.go:171","msg":"trace[769351609] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:993; }","duration":"139.082502ms","start":"2026-08-24T15:23:53.184545Z","end":"2026-08-24T15:23:53.323627Z","steps":["trace[769351609] 'agreement among raft nodes before linearized reading' (duration: 138.874957ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:23:53.32372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.467829ms","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-24T15:23:53.323781Z","caller":"traceutil/trace.go:171","msg":"trace[1827707926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:993; }","duration":"102.612542ms","start":"2026-08-24T15:23:53.22115Z","end":"2026-08-24T15:23:53.323763Z","steps":["trace[1827707926] 'agreement among raft nodes before linearized reading' (duration: 102.384367ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:01.738471Z","caller":"traceutil/trace.go:171","msg":"trace[277763799] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"167.814927ms","start":"2026-08-24T15:24:01.570633Z","end":"2026-08-24T15:24:01.738448Z","steps":["trace[277763799] 'process raft request' (duration: 167.711775ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:01.738487Z","caller":"traceutil/trace.go:171","msg":"trace[85366931] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"169.084516ms","start":"2026-08-24T15:24:01.569374Z","end":"2026-08-24T15:24:01.738458Z","steps":["trace[85366931] 'process raft request' (duration: 166.526709ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:01.738643Z","caller":"traceutil/trace.go:171","msg":"trace[2146376492] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"167.93043ms","start":"2026-08-24T15:24:01.570706Z","end":"2026-08-24T15:24:01.738637Z","steps":["trace[2146376492] 'process raft request' (duration: 167.708745ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:05.951065Z","caller":"traceutil/trace.go:171","msg":"trace[746265746] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"114.712344ms","start":"2026-08-24T15:24:05.836329Z","end":"2026-08-24T15:24:05.951042Z","steps":["trace[746265746] 'process raft request' (duration: 101.148073ms)","trace[746265746] 'compare' (duration: 13.347877ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:12.266464Z","caller":"traceutil/trace.go:171","msg":"trace[2121189926] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1074; }","duration":"134.161575ms","start":"2026-08-24T15:24:12.132268Z","end":"2026-08-24T15:24:12.26643Z","steps":["trace[2121189926] 'process raft request' (duration: 92.182093ms)","trace[2121189926] 'compare' (duration: 41.690296ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:18.284291Z","caller":"traceutil/trace.go:171","msg":"trace[1774912877] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"124.298764ms","start":"2026-08-24T15:24:18.159947Z","end":"2026-08-24T15:24:18.284246Z","steps":["trace[1774912877] 'process raft request' (duration: 122.472385ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:20.464008Z","caller":"traceutil/trace.go:171","msg":"trace[1693291348] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"170.539812ms","start":"2026-08-24T15:24:20.293437Z","end":"2026-08-24T15:24:20.463977Z","steps":["trace[1693291348] 'process raft request' (duration: 114.767457ms)","trace[1693291348] 'compare' (duration: 55.53057ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:20.464179Z","caller":"traceutil/trace.go:171","msg":"trace[396142644] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"116.019004ms","start":"2026-08-24T15:24:20.348132Z","end":"2026-08-24T15:24:20.464151Z","steps":["trace[396142644] 'process raft request' (duration: 115.785259ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:24.035702Z","caller":"traceutil/trace.go:171","msg":"trace[2081577919] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"129.190989ms","start":"2026-08-24T15:24:23.906441Z","end":"2026-08-24T15:24:24.035632Z","steps":["trace[2081577919] 'process raft request' (duration: 32.425162ms)","trace[2081577919] 'compare' (duration: 96.647815ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:24.729258Z","caller":"traceutil/trace.go:171","msg":"trace[1265893662] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"136.119239ms","start":"2026-08-24T15:24:24.593111Z","end":"2026-08-24T15:24:24.729231Z","steps":["trace[1265893662] 'process raft request' (duration: 135.085017ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:26.334341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.440282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:24:26.334524Z","caller":"traceutil/trace.go:171","msg":"trace[1037589065] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"127.004224ms","start":"2026-08-24T15:24:26.207487Z","end":"2026-08-24T15:24:26.334492Z","steps":["trace[1037589065] 'process raft request' (duration: 126.950743ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:26.334628Z","caller":"traceutil/trace.go:171","msg":"trace[1441644405] linearizableReadLoop","detail":"{readStateIndex:1162; appliedIndex:1161; }","duration":"225.923919ms","start":"2026-08-24T15:24:26.108675Z","end":"2026-08-24T15:24:26.334598Z","steps":["trace[1441644405] 'read index received' (duration: 87.067978ms)","trace[1441644405] 'applied index is now lower than readState.Index' (duration: 138.853701ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:26.334818Z","caller":"traceutil/trace.go:171","msg":"trace[188789718] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"238.345889ms","start":"2026-08-24T15:24:26.096439Z","end":"2026-08-24T15:24:26.334784Z","steps":["trace[188789718] 'process raft request' (duration: 99.366525ms)","trace[188789718] 'compare' (duration: 138.3241ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:26.334999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.308358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-08-24T15:24:26.335267Z","caller":"traceutil/trace.go:171","msg":"trace[1043144477] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1119; }","duration":"226.625534ms","start":"2026-08-24T15:24:26.108631Z","end":"2026-08-24T15:24:26.335257Z","steps":["trace[1043144477] 'agreement among raft nodes before linearized reading' (duration: 226.257456ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:26.335127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.01644ms","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-24T15:24:26.335414Z","caller":"traceutil/trace.go:171","msg":"trace[1249298225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1119; }","duration":"156.339036ms","start":"2026-08-24T15:24:26.179059Z","end":"2026-08-24T15:24:26.335398Z","steps":["trace[1249298225] 'agreement among raft nodes before linearized reading' (duration: 155.962068ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:26.503406Z","caller":"traceutil/trace.go:171","msg":"trace[2063817675] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"162.28395ms","start":"2026-08-24T15:24:26.341094Z","end":"2026-08-24T15:24:26.503378Z","steps":["trace[2063817675] 'process raft request' (duration: 160.563884ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:28.505752Z","caller":"traceutil/trace.go:171","msg":"trace[834818639] linearizableReadLoop","detail":"{readStateIndex:1169; appliedIndex:1168; }","duration":"146.802161ms","start":"2026-08-24T15:24:28.358924Z","end":"2026-08-24T15:24:28.505726Z","steps":["trace[834818639] 'read index received' (duration: 146.639578ms)","trace[834818639] 'applied index is now lower than readState.Index' (duration: 161.603µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:28.505808Z","caller":"traceutil/trace.go:171","msg":"trace[191443249] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"161.084148ms","start":"2026-08-24T15:24:28.344686Z","end":"2026-08-24T15:24:28.50577Z","steps":["trace[191443249] 'process raft request' (duration: 160.863553ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:28.505952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.001725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-24T15:24:28.505993Z","caller":"traceutil/trace.go:171","msg":"trace[125464807] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1125; }","duration":"147.088047ms","start":"2026-08-24T15:24:28.358894Z","end":"2026-08-24T15:24:28.505982Z","steps":["trace[125464807] 'agreement among raft nodes before linearized reading' (duration: 146.928764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:28.786521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.90964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:24:28.786718Z","caller":"traceutil/trace.go:171","msg":"trace[748943596] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"267.15116ms","start":"2026-08-24T15:24:28.519549Z","end":"2026-08-24T15:24:28.786701Z","steps":["trace[748943596] 'process raft request' (duration: 267.082439ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:28.786938Z","caller":"traceutil/trace.go:171","msg":"trace[517341974] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"276.995985ms","start":"2026-08-24T15:24:28.509912Z","end":"2026-08-24T15:24:28.786908Z","steps":["trace[517341974] 'process raft request' (duration: 107.613666ms)","trace[517341974] 'compare' (duration: 168.395269ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:28.787869Z","caller":"traceutil/trace.go:171","msg":"trace[846342952] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"102.673153ms","start":"2026-08-24T15:24:28.685178Z","end":"2026-08-24T15:24:28.787851Z","steps":["trace[846342952] 'process raft request' (duration: 102.269424ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:30.139615Z","caller":"traceutil/trace.go:171","msg":"trace[2034326641] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"111.902053ms","start":"2026-08-24T15:24:30.027691Z","end":"2026-08-24T15:24:30.139593Z","steps":["trace[2034326641] 'process raft request' (duration: 111.657918ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:31.060173Z","caller":"traceutil/trace.go:171","msg":"trace[2013594747] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"244.604832ms","start":"2026-08-24T15:24:30.815545Z","end":"2026-08-24T15:24:31.06015Z","steps":["trace[2013594747] 'process raft request' (duration: 244.47134ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:31.099444Z","caller":"traceutil/trace.go:171","msg":"trace[653124267] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"283.544636ms","start":"2026-08-24T15:24:30.815882Z","end":"2026-08-24T15:24:31.099427Z","steps":["trace[653124267] 'process raft request' (duration: 283.399552ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:31.099777Z","caller":"traceutil/trace.go:171","msg":"trace[57793002] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"283.341221ms","start":"2026-08-24T15:24:30.816414Z","end":"2026-08-24T15:24:31.099756Z","steps":["trace[57793002] 'process raft request' (duration: 282.956633ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:31.099858Z","caller":"traceutil/trace.go:171","msg":"trace[1668915048] linearizableReadLoop","detail":"{readStateIndex:1178; appliedIndex:1175; }","duration":"276.00739ms","start":"2026-08-24T15:24:30.823826Z","end":"2026-08-24T15:24:31.099833Z","steps":["trace[1668915048] 'read index received' (duration: 236.25216ms)","trace[1668915048] 'applied index is now lower than readState.Index' (duration: 39.75385ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:31.099977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.156451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:24:31.100011Z","caller":"traceutil/trace.go:171","msg":"trace[1392036779] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1134; }","duration":"110.238633ms","start":"2026-08-24T15:24:30.989765Z","end":"2026-08-24T15:24:31.100003Z","steps":["trace[1392036779] 'agreement among raft nodes before linearized reading' (duration: 110.165272ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:31.100059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.221094ms","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-24T15:24:31.100127Z","caller":"traceutil/trace.go:171","msg":"trace[2121080944] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1134; }","duration":"276.352737ms","start":"2026-08-24T15:24:30.823761Z","end":"2026-08-24T15:24:31.100114Z","steps":["trace[2121080944] 'agreement among raft nodes before linearized reading' (duration: 276.153863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:31.331312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.507006ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:24:31.331445Z","caller":"traceutil/trace.go:171","msg":"trace[1895111924] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"227.105131ms","start":"2026-08-24T15:24:31.104324Z","end":"2026-08-24T15:24:31.331429Z","steps":["trace[1895111924] 'process raft request' (duration: 68.409051ms)","trace[1895111924] 'compare' (duration: 158.400624ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:31.331603Z","caller":"traceutil/trace.go:171","msg":"trace[656556083] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"184.830689ms","start":"2026-08-24T15:24:31.146742Z","end":"2026-08-24T15:24:31.331573Z","steps":["trace[656556083] 'process raft request' (duration: 184.650146ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:35.51938Z","caller":"traceutil/trace.go:171","msg":"trace[1436288862] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"122.492038ms","start":"2026-08-24T15:24:35.39687Z","end":"2026-08-24T15:24:35.519362Z","steps":["trace[1436288862] 'process raft request' (duration: 122.381616ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:37.408909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.281134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:24:37.409014Z","caller":"traceutil/trace.go:171","msg":"trace[622391322] linearizableReadLoop","detail":"{readStateIndex:1200; appliedIndex:1199; }","duration":"126.604439ms","start":"2026-08-24T15:24:37.282394Z","end":"2026-08-24T15:24:37.408999Z","steps":["trace[622391322] 'read index received' (duration: 30.761µs)","trace[622391322] 'applied index is now lower than readState.Index' (duration: 126.572448ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:37.409075Z","caller":"traceutil/trace.go:171","msg":"trace[177408331] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"181.364531ms","start":"2026-08-24T15:24:37.227675Z","end":"2026-08-24T15:24:37.409039Z","steps":["trace[177408331] 'process raft request' (duration: 53.872623ms)","trace[177408331] 'compare' (duration: 127.138451ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:37.409149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.751582ms","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-24T15:24:37.409183Z","caller":"traceutil/trace.go:171","msg":"trace[235575967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1154; }","duration":"126.814233ms","start":"2026-08-24T15:24:37.28236Z","end":"2026-08-24T15:24:37.409174Z","steps":["trace[235575967] 'agreement among raft nodes before linearized reading' (duration: 126.694321ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:37.735476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.240214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:24:37.735585Z","caller":"traceutil/trace.go:171","msg":"trace[851583118] linearizableReadLoop","detail":"{readStateIndex:1201; appliedIndex:1200; }","duration":"211.85743ms","start":"2026-08-24T15:24:37.523711Z","end":"2026-08-24T15:24:37.735568Z","steps":["trace[851583118] 'read index received' (duration: 111.463673ms)","trace[851583118] 'applied index is now lower than readState.Index' (duration: 100.392427ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:37.736087Z","caller":"traceutil/trace.go:171","msg":"trace[28406797] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"322.511856ms","start":"2026-08-24T15:24:37.413538Z","end":"2026-08-24T15:24:37.73605Z","steps":["trace[28406797] 'process raft request' (duration: 221.593168ms)","trace[28406797] 'compare' (duration: 99.869456ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:37.736302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:37.413504Z","time spent":"322.66811ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:24:37.736672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.958222ms","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-24T15:24:37.736717Z","caller":"traceutil/trace.go:171","msg":"trace[749764865] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1155; }","duration":"213.082724ms","start":"2026-08-24T15:24:37.523624Z","end":"2026-08-24T15:24:37.736707Z","steps":["trace[749764865] 'agreement among raft nodes before linearized reading' (duration: 211.985602ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:40.022851Z","caller":"traceutil/trace.go:171","msg":"trace[1736486116] linearizableReadLoop","detail":"{readStateIndex:1207; appliedIndex:1206; }","duration":"213.378298ms","start":"2026-08-24T15:24:39.809455Z","end":"2026-08-24T15:24:40.022834Z","steps":["trace[1736486116] 'read index received' (duration: 213.180914ms)","trace[1736486116] 'applied index is now lower than readState.Index' (duration: 196.594µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:40.022907Z","caller":"traceutil/trace.go:171","msg":"trace[1266552127] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"278.980053ms","start":"2026-08-24T15:24:39.743897Z","end":"2026-08-24T15:24:40.022877Z","steps":["trace[1266552127] 'process raft request' (duration: 278.825ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:40.023077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.596912ms","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-24T15:24:40.023154Z","caller":"traceutil/trace.go:171","msg":"trace[392111675] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1161; }","duration":"213.716144ms","start":"2026-08-24T15:24:39.809418Z","end":"2026-08-24T15:24:40.023134Z","steps":["trace[392111675] 'agreement among raft nodes before linearized reading' (duration: 213.478319ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:41.592013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.762045ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-24T15:24:41.839794Z","caller":"traceutil/trace.go:171","msg":"trace[1127607603] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"221.744805ms","start":"2026-08-24T15:24:41.618011Z","end":"2026-08-24T15:24:41.839756Z","steps":["trace[1127607603] 'process raft request' (duration: 209.600161ms)","trace[1127607603] 'compare' (duration: 11.863548ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:42.228891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.642546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:24:42.22902Z","caller":"traceutil/trace.go:171","msg":"trace[210933945] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"172.014827ms","start":"2026-08-24T15:24:42.056985Z","end":"2026-08-24T15:24:42.229Z","steps":["trace[210933945] 'process raft request' (duration: 69.168847ms)","trace[210933945] 'compare' (duration: 102.479203ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:45.388656Z","caller":"traceutil/trace.go:171","msg":"trace[1785038554] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"119.835574ms","start":"2026-08-24T15:24:45.268796Z","end":"2026-08-24T15:24:45.388631Z","steps":["trace[1785038554] 'process raft request' (duration: 119.689021ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:47.199339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.49952ms","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-24T15:24:47.199468Z","caller":"traceutil/trace.go:171","msg":"trace[1548160004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1184; }","duration":"203.677153ms","start":"2026-08-24T15:24:46.995771Z","end":"2026-08-24T15:24:47.199448Z","steps":["trace[1548160004] 'range keys from in-memory index tree' (duration: 203.272916ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:48.747413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.580506ms","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-24T15:24:48.747502Z","caller":"traceutil/trace.go:171","msg":"trace[1456281757] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1189; }","duration":"223.726179ms","start":"2026-08-24T15:24:48.523756Z","end":"2026-08-24T15:24:48.747482Z","steps":["trace[1456281757] 'range keys from in-memory index tree' (duration: 223.404423ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:49.094669Z","caller":"traceutil/trace.go:171","msg":"trace[683152487] linearizableReadLoop","detail":"{readStateIndex:1238; appliedIndex:1237; }","duration":"280.569787ms","start":"2026-08-24T15:24:48.814067Z","end":"2026-08-24T15:24:49.094637Z","steps":["trace[683152487] 'read index received' (duration: 280.21567ms)","trace[683152487] 'applied index is now lower than readState.Index' (duration: 352.707µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:49.094702Z","caller":"traceutil/trace.go:171","msg":"trace[1204051943] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"342.852316ms","start":"2026-08-24T15:24:48.751818Z","end":"2026-08-24T15:24:49.09467Z","steps":["trace[1204051943] 'process raft request' (duration: 342.596851ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:49.094842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.76146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:24:49.094877Z","caller":"traceutil/trace.go:171","msg":"trace[1542608437] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1190; }","duration":"280.871492ms","start":"2026-08-24T15:24:48.813998Z","end":"2026-08-24T15:24:49.09487Z","steps":["trace[1542608437] 'agreement among raft nodes before linearized reading' (duration: 280.76075ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:49.095387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:48.751787Z","time spent":"343.00108ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:24:49.955295Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15730686695220189012,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:24:50.456099Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15730686695220189012,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:24:50.959772Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15730686695220189012,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:24:51.460592Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15730686695220189012,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:24:51.961261Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15730686695220189012,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:24:52.46248Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15730686695220189012,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:24:52.796698Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.363229931s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T15:24:52.883395Z","caller":"traceutil/trace.go:171","msg":"trace[468555599] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"3.449991258s","start":"2026-08-24T15:24:49.43338Z","end":"2026-08-24T15:24:52.883372Z","steps":["trace[468555599] 'process raft request' (duration: 3.36372814s)","trace[468555599] 'compare' (duration: 85.916881ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:52.88351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:49.433361Z","time spent":"3.45009211s","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:24:53.004598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.382734ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-24T15:24:53.004793Z","caller":"traceutil/trace.go:171","msg":"trace[767034386] linearizableReadLoop","detail":"{readStateIndex:1242; appliedIndex:1239; }","duration":"3.549823007s","start":"2026-08-24T15:24:49.454958Z","end":"2026-08-24T15:24:53.004781Z","steps":["trace[767034386] 'read index received' (duration: 3.342147855s)","trace[767034386] 'applied index is now lower than readState.Index' (duration: 207.674382ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:53.004874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.549918369s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:24:53.004849Z","caller":"traceutil/trace.go:171","msg":"trace[389063711] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"1.030707674s","start":"2026-08-24T15:24:51.974099Z","end":"2026-08-24T15:24:53.004807Z","steps":["trace[389063711] 'process raft request' (duration: 1.030583261s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:53.004895Z","caller":"traceutil/trace.go:171","msg":"trace[1469377990] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1193; }","duration":"3.549947839s","start":"2026-08-24T15:24:49.454941Z","end":"2026-08-24T15:24:53.004889Z","steps":["trace[1469377990] 'agreement among raft nodes before linearized reading' (duration: 3.549903648s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:53.004919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:49.454879Z","time spent":"3.550034311s","remote":"127.0.0.1:33386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:24:53.004936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.485366078s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-24T15:24:53.004991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:51.974076Z","time spent":"1.030837547s","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:24:53.00508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.770741675s","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-24T15:24:53.0051Z","caller":"traceutil/trace.go:171","msg":"trace[859033058] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1193; }","duration":"1.770768225s","start":"2026-08-24T15:24:51.234327Z","end":"2026-08-24T15:24:53.005095Z","steps":["trace[859033058] 'agreement among raft nodes before linearized reading' (duration: 1.770684194s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:53.005122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:51.234324Z","time spent":"1.770792546s","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-24T15:24:53.005132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.634748358s","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-24T15:24:53.004994Z","caller":"traceutil/trace.go:171","msg":"trace[1625880164] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1193; }","duration":"3.485471799s","start":"2026-08-24T15:24:49.519508Z","end":"2026-08-24T15:24:53.00498Z","steps":["trace[1625880164] 'agreement among raft nodes before linearized reading' (duration: 3.485364937s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:53.005194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:49.519491Z","time spent":"3.485694134s","remote":"127.0.0.1:41570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-08-24T15:24:53.005197Z","caller":"traceutil/trace.go:171","msg":"trace[720490330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1193; }","duration":"1.634840779s","start":"2026-08-24T15:24:51.37034Z","end":"2026-08-24T15:24:53.00518Z","steps":["trace[720490330] 'agreement among raft nodes before linearized reading' (duration: 1.634664715s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:53.005225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.770908948s","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-24T15:24:53.005244Z","caller":"traceutil/trace.go:171","msg":"trace[1179828370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1193; }","duration":"1.770945559s","start":"2026-08-24T15:24:51.234293Z","end":"2026-08-24T15:24:53.005238Z","steps":["trace[1179828370] 'agreement among raft nodes before linearized reading' (duration: 1.770895218s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:53.005237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:51.370324Z","time spent":"1.634902581s","remote":"127.0.0.1:41484","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-24T15:24:53.005258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:51.234278Z","time spent":"1.770976159s","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T15:24:53.005352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.203465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:24:53.00537Z","caller":"traceutil/trace.go:171","msg":"trace[1815859831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1193; }","duration":"202.247826ms","start":"2026-08-24T15:24:52.803117Z","end":"2026-08-24T15:24:53.005365Z","steps":["trace[1815859831] 'agreement among raft nodes before linearized reading' (duration: 202.216226ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:53.005418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.771402057s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:24:53.00544Z","caller":"traceutil/trace.go:171","msg":"trace[2015042224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1193; }","duration":"1.771455569s","start":"2026-08-24T15:24:51.233979Z","end":"2026-08-24T15:24:53.005434Z","steps":["trace[2015042224] 'agreement among raft nodes before linearized reading' (duration: 1.771419978s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:53.005453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.292270833s","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-24T15:24:53.00546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:51.233963Z","time spent":"1.771492989s","remote":"127.0.0.1:41236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-24T15:24:53.005484Z","caller":"traceutil/trace.go:171","msg":"trace[1864092072] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1193; }","duration":"3.292339345s","start":"2026-08-24T15:24:49.713136Z","end":"2026-08-24T15:24:53.005475Z","steps":["trace[1864092072] 'agreement among raft nodes before linearized reading' (duration: 3.292203572s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:53.005506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:49.713115Z","time spent":"3.292385725s","remote":"127.0.0.1:41484","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-24T15:24:53.005515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.761508324s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:24:53.005533Z","caller":"traceutil/trace.go:171","msg":"trace[887455874] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1193; }","duration":"1.761555465s","start":"2026-08-24T15:24:51.243972Z","end":"2026-08-24T15:24:53.005528Z","steps":["trace[887455874] 'agreement among raft nodes before linearized reading' (duration: 1.761527864s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:53.005546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:51.243959Z","time spent":"1.761584636s","remote":"127.0.0.1:41238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:24:53.005989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.771628402s","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-24T15:24:53.006039Z","caller":"traceutil/trace.go:171","msg":"trace[545306953] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1193; }","duration":"1.771684883s","start":"2026-08-24T15:24:51.234338Z","end":"2026-08-24T15:24:53.006023Z","steps":["trace[545306953] 'agreement among raft nodes before linearized reading' (duration: 1.770591872s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:53.006082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:51.234336Z","time spent":"1.771734984s","remote":"127.0.0.1:41484","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":"info","ts":"2026-08-24T15:24:53.645119Z","caller":"traceutil/trace.go:171","msg":"trace[1269155307] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"634.748468ms","start":"2026-08-24T15:24:53.010345Z","end":"2026-08-24T15:24:53.645094Z","steps":["trace[1269155307] 'process raft request' (duration: 567.757731ms)","trace[1269155307] 'compare' (duration: 66.754922ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:53.645232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:53.010325Z","time spent":"634.84884ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:24:53.645294Z","caller":"traceutil/trace.go:171","msg":"trace[2016476246] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1242; }","duration":"633.77861ms","start":"2026-08-24T15:24:53.011487Z","end":"2026-08-24T15:24:53.645266Z","steps":["trace[2016476246] 'read index received' (duration: 4.864516ms)","trace[2016476246] 'applied index is now lower than readState.Index' (duration: 628.912904ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:53.645364Z","caller":"traceutil/trace.go:171","msg":"trace[476350660] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"634.756118ms","start":"2026-08-24T15:24:53.010601Z","end":"2026-08-24T15:24:53.645358Z","steps":["trace[476350660] 'process raft request' (duration: 634.554174ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:53.645398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:53.010592Z","time spent":"634.786929ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:24:53.645455Z","caller":"traceutil/trace.go:171","msg":"trace[948942382] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"634.731468ms","start":"2026-08-24T15:24:53.010657Z","end":"2026-08-24T15:24:53.645388Z","steps":["trace[948942382] 'process raft request' (duration: 634.551344ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:53.645558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.86927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:24:53.645561Z","caller":"traceutil/trace.go:171","msg":"trace[1460752361] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"635.128875ms","start":"2026-08-24T15:24:53.010412Z","end":"2026-08-24T15:24:53.645541Z","steps":["trace[1460752361] 'process raft request' (duration: 634.630206ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:53.645594Z","caller":"traceutil/trace.go:171","msg":"trace[1643550200] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1198; }","duration":"450.93346ms","start":"2026-08-24T15:24:53.194649Z","end":"2026-08-24T15:24:53.645582Z","steps":["trace[1643550200] 'agreement among raft nodes before linearized reading' (duration: 450.881379ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:53.645602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:53.010638Z","time spent":"634.889401ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:24:53.645635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:53.194634Z","time spent":"450.991542ms","remote":"127.0.0.1:41430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:24:53.645641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:53.010407Z","time spent":"635.192267ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:24:53.645487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.978124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:24:53.646359Z","caller":"traceutil/trace.go:171","msg":"trace[1452068374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1198; }","duration":"634.883951ms","start":"2026-08-24T15:24:53.011458Z","end":"2026-08-24T15:24:53.646342Z","steps":["trace[1452068374] 'agreement among raft nodes before linearized reading' (duration: 633.956483ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:53.646418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:53.011447Z","time spent":"634.956092ms","remote":"127.0.0.1:41238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:24:56.55973Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15730686695220189041,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:24:57.143922Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.133687036s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T15:24:57.14414Z","caller":"traceutil/trace.go:171","msg":"trace[101899395] linearizableReadLoop","detail":"{readStateIndex:1254; appliedIndex:1253; }","duration":"1.084688107s","start":"2026-08-24T15:24:56.059435Z","end":"2026-08-24T15:24:57.144123Z","steps":["trace[101899395] 'read index received' (duration: 1.084580445s)","trace[101899395] 'applied index is now lower than readState.Index' (duration: 107.002µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:57.144192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:56.010132Z","time spent":"1.134057643s","remote":"127.0.0.1:41264","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-24T15:24:57.14441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084956172s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:24:57.144437Z","caller":"traceutil/trace.go:171","msg":"trace[412150794] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1204; }","duration":"1.085021783s","start":"2026-08-24T15:24:56.059408Z","end":"2026-08-24T15:24:57.14443Z","steps":["trace[412150794] 'agreement among raft nodes before linearized reading' (duration: 1.084961411s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:57.144453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:56.059397Z","time spent":"1.085052144s","remote":"127.0.0.1:41446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:24:57.144553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024574011s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:24:57.144573Z","caller":"traceutil/trace.go:171","msg":"trace[586230844] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1204; }","duration":"1.024608861s","start":"2026-08-24T15:24:56.11996Z","end":"2026-08-24T15:24:57.144569Z","steps":["trace[586230844] 'agreement among raft nodes before linearized reading' (duration: 1.024577541s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:57.144594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:56.119951Z","time spent":"1.024639773s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:24:57.146182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.768539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:24:57.146868Z","caller":"traceutil/trace.go:171","msg":"trace[1171716106] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1204; }","duration":"403.502883ms","start":"2026-08-24T15:24:56.743346Z","end":"2026-08-24T15:24:57.146849Z","steps":["trace[1171716106] 'agreement among raft nodes before linearized reading' (duration: 402.745418ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:57.146973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:56.743333Z","time spent":"403.626477ms","remote":"127.0.0.1:33220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:24:57.387298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.857124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:24:57.387403Z","caller":"traceutil/trace.go:171","msg":"trace[48716968] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1204; }","duration":"773.009056ms","start":"2026-08-24T15:24:56.614364Z","end":"2026-08-24T15:24:57.387373Z","steps":["trace[48716968] 'agreement among raft nodes before linearized reading' (duration: 532.30011ms)","trace[48716968] 'count revisions from in-memory index tree' (duration: 240.565763ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:57.387469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:56.614351Z","time spent":"773.105398ms","remote":"127.0.0.1:33218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:24:57.387731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.091834ms","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-24T15:24:57.388272Z","caller":"traceutil/trace.go:171","msg":"trace[1561048848] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"241.618005ms","start":"2026-08-24T15:24:57.146628Z","end":"2026-08-24T15:24:57.388246Z","steps":["trace[1561048848] 'process raft request' (duration: 216.717943ms)","trace[1561048848] 'compare' (duration: 24.169008ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:57.388299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.185458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5549"} {"level":"info","ts":"2026-08-24T15:24:57.38832Z","caller":"traceutil/trace.go:171","msg":"trace[1375551263] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"240.457662ms","start":"2026-08-24T15:24:57.147819Z","end":"2026-08-24T15:24:57.388276Z","steps":["trace[1375551263] 'process raft request' (duration: 240.054744ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:57.388362Z","caller":"traceutil/trace.go:171","msg":"trace[1281247501] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1207; }","duration":"238.27574ms","start":"2026-08-24T15:24:57.150067Z","end":"2026-08-24T15:24:57.388343Z","steps":["trace[1281247501] 'agreement among raft nodes before linearized reading' (duration: 238.072897ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:57.388274Z","caller":"traceutil/trace.go:171","msg":"trace[990431053] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1204; }","duration":"241.647635ms","start":"2026-08-24T15:24:57.146578Z","end":"2026-08-24T15:24:57.388225Z","steps":["trace[990431053] 'range keys from in-memory index tree' (duration: 240.828089ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:57.388673Z","caller":"traceutil/trace.go:171","msg":"trace[1745297545] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"240.825839ms","start":"2026-08-24T15:24:57.147835Z","end":"2026-08-24T15:24:57.388661Z","steps":["trace[1745297545] 'process raft request' (duration: 240.166807ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:57.388075Z","caller":"traceutil/trace.go:171","msg":"trace[455277694] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1254; }","duration":"237.950934ms","start":"2026-08-24T15:24:57.150098Z","end":"2026-08-24T15:24:57.388048Z","steps":["trace[455277694] 'read index received' (duration: 213.387998ms)","trace[455277694] 'applied index is now lower than readState.Index' (duration: 24.561736ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:24:57.38888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.267953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:24:57.388941Z","caller":"traceutil/trace.go:171","msg":"trace[568774152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1207; }","duration":"141.358514ms","start":"2026-08-24T15:24:57.247566Z","end":"2026-08-24T15:24:57.388925Z","steps":["trace[568774152] 'agreement among raft nodes before linearized reading' (duration: 141.265863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:57.895559Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15730686695220189054,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:24:58.419178Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15730686695220189054,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:24:58.520655Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.125959007s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-24T15:24:58.520809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:58.053886Z","time spent":"466.919887ms","remote":"127.0.0.1:39206","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-24T15:24:58.521561Z","caller":"traceutil/trace.go:171","msg":"trace[1077526956] linearizableReadLoop","detail":"{readStateIndex:1258; appliedIndex:1257; }","duration":"1.126678791s","start":"2026-08-24T15:24:57.39484Z","end":"2026-08-24T15:24:58.521518Z","steps":["trace[1077526956] 'read index received' (duration: 1.125918946s)","trace[1077526956] 'applied index is now lower than readState.Index' (duration: 758.405µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:58.521619Z","caller":"traceutil/trace.go:171","msg":"trace[700059274] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"1.126985706s","start":"2026-08-24T15:24:57.394615Z","end":"2026-08-24T15:24:58.521601Z","steps":["trace[700059274] 'process raft request' (duration: 1.126201031s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:58.521719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:57.394601Z","time spent":"1.127053248s","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:24:58.521925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.73012ms","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-24T15:24:58.521973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.903711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:24:58.522008Z","caller":"traceutil/trace.go:171","msg":"trace[909626178] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1208; }","duration":"650.963464ms","start":"2026-08-24T15:24:57.871033Z","end":"2026-08-24T15:24:58.521997Z","steps":["trace[909626178] 'agreement among raft nodes before linearized reading' (duration: 650.909432ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:58.522Z","caller":"traceutil/trace.go:171","msg":"trace[846407942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1208; }","duration":"857.842573ms","start":"2026-08-24T15:24:57.664145Z","end":"2026-08-24T15:24:58.521987Z","steps":["trace[846407942] 'agreement among raft nodes before linearized reading' (duration: 857.577728ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:58.522133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:57.664126Z","time spent":"857.957155ms","remote":"127.0.0.1:41484","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-24T15:24:58.522305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.269588ms","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-24T15:24:58.522796Z","caller":"traceutil/trace.go:171","msg":"trace[698579698] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1208; }","duration":"818.728566ms","start":"2026-08-24T15:24:57.703997Z","end":"2026-08-24T15:24:58.522725Z","steps":["trace[698579698] 'agreement among raft nodes before linearized reading' (duration: 818.065454ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:58.522054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.755818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:24:58.522852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.539117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:24:58.522939Z","caller":"traceutil/trace.go:171","msg":"trace[1919289643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1208; }","duration":"273.67489ms","start":"2026-08-24T15:24:58.249246Z","end":"2026-08-24T15:24:58.522921Z","steps":["trace[1919289643] 'agreement among raft nodes before linearized reading' (duration: 273.477176ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:24:58.523019Z","caller":"traceutil/trace.go:171","msg":"trace[1313633412] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1208; }","duration":"193.724767ms","start":"2026-08-24T15:24:58.329219Z","end":"2026-08-24T15:24:58.522944Z","steps":["trace[1313633412] 'agreement among raft nodes before linearized reading' (duration: 192.738577ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:58.522964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:57.703986Z","time spent":"818.958571ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-24T15:24:58.52252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.217136ms","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-24T15:24:58.523202Z","caller":"traceutil/trace.go:171","msg":"trace[1912112430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1208; }","duration":"820.946609ms","start":"2026-08-24T15:24:57.702238Z","end":"2026-08-24T15:24:58.523184Z","steps":["trace[1912112430] 'agreement among raft nodes before linearized reading' (duration: 820.121874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:58.523252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:57.70223Z","time spent":"821.008ms","remote":"127.0.0.1:41484","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-08-24T15:24:58.522636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.595743ms","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-24T15:24:58.52332Z","caller":"traceutil/trace.go:171","msg":"trace[1489666452] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1208; }","duration":"821.315106ms","start":"2026-08-24T15:24:57.701993Z","end":"2026-08-24T15:24:58.523308Z","steps":["trace[1489666452] 'agreement among raft nodes before linearized reading' (duration: 820.555782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:58.523367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:57.701973Z","time spent":"821.381757ms","remote":"127.0.0.1:41484","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-24T15:24:58.522638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.412377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5549"} {"level":"info","ts":"2026-08-24T15:24:58.523484Z","caller":"traceutil/trace.go:171","msg":"trace[1120494936] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1208; }","duration":"186.274622ms","start":"2026-08-24T15:24:58.337191Z","end":"2026-08-24T15:24:58.523466Z","steps":["trace[1120494936] 'agreement among raft nodes before linearized reading' (duration: 185.352655ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:58.52204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:57.871022Z","time spent":"651.009664ms","remote":"127.0.0.1:33294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:24:58.522652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.267673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:24:58.523634Z","caller":"traceutil/trace.go:171","msg":"trace[1278718421] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1208; }","duration":"705.302823ms","start":"2026-08-24T15:24:57.818314Z","end":"2026-08-24T15:24:58.523617Z","steps":["trace[1278718421] 'agreement among raft nodes before linearized reading' (duration: 704.232213ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:58.523772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:57.818295Z","time spent":"705.454886ms","remote":"127.0.0.1:33242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:24:58.523959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.12871588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-24T15:24:58.524471Z","caller":"traceutil/trace.go:171","msg":"trace[1386595583] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1208; }","duration":"1.129435993s","start":"2026-08-24T15:24:57.394826Z","end":"2026-08-24T15:24:58.524262Z","steps":["trace[1386595583] 'agreement among raft nodes before linearized reading' (duration: 1.126838973s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:58.524709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:57.394821Z","time spent":"1.129871642s","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-08-24T15:24:59.772019Z","caller":"traceutil/trace.go:171","msg":"trace[288391561] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1262; }","duration":"373.160084ms","start":"2026-08-24T15:24:59.398824Z","end":"2026-08-24T15:24:59.771985Z","steps":["trace[288391561] 'read index received' (duration: 372.822488ms)","trace[288391561] 'applied index is now lower than readState.Index' (duration: 336.386µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:24:59.772223Z","caller":"traceutil/trace.go:171","msg":"trace[500358930] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"379.110059ms","start":"2026-08-24T15:24:59.393064Z","end":"2026-08-24T15:24:59.772174Z","steps":["trace[500358930] 'process raft request' (duration: 378.6764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:59.772426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.583642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4219"} {"level":"info","ts":"2026-08-24T15:24:59.772517Z","caller":"traceutil/trace.go:171","msg":"trace[1324073168] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:1213; }","duration":"373.733346ms","start":"2026-08-24T15:24:59.398768Z","end":"2026-08-24T15:24:59.772501Z","steps":["trace[1324073168] 'agreement among raft nodes before linearized reading' (duration: 373.350479ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:24:59.772564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:59.39873Z","time spent":"373.822897ms","remote":"127.0.0.1:41692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4242,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-08-24T15:24:59.772624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:24:59.393042Z","time spent":"379.377374ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:25:02.057926Z","caller":"traceutil/trace.go:171","msg":"trace[2069583431] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"279.906442ms","start":"2026-08-24T15:25:01.777971Z","end":"2026-08-24T15:25:02.057877Z","steps":["trace[2069583431] 'process raft request' (duration: 279.770069ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:25:02.698944Z","caller":"traceutil/trace.go:171","msg":"trace[373204995] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"105.15757ms","start":"2026-08-24T15:25:02.593755Z","end":"2026-08-24T15:25:02.698913Z","steps":["trace[373204995] 'process raft request' (duration: 104.523297ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:25:02.819241Z","caller":"traceutil/trace.go:171","msg":"trace[1498238067] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"114.900916ms","start":"2026-08-24T15:25:02.704317Z","end":"2026-08-24T15:25:02.819218Z","steps":["trace[1498238067] 'process raft request' (duration: 114.827215ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:25:02.819412Z","caller":"traceutil/trace.go:171","msg":"trace[550514098] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"116.264423ms","start":"2026-08-24T15:25:02.703137Z","end":"2026-08-24T15:25:02.819402Z","steps":["trace[550514098] 'process raft request' (duration: 69.502519ms)","trace[550514098] 'compare' (duration: 46.381096ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:25:02.819533Z","caller":"traceutil/trace.go:171","msg":"trace[783105595] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"114.424638ms","start":"2026-08-24T15:25:02.705101Z","end":"2026-08-24T15:25:02.819526Z","steps":["trace[783105595] 'process raft request' (duration: 114.080451ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:25:02.819704Z","caller":"traceutil/trace.go:171","msg":"trace[2145004263] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"113.876127ms","start":"2026-08-24T15:25:02.705819Z","end":"2026-08-24T15:25:02.819696Z","steps":["trace[2145004263] 'process raft request' (duration: 113.670553ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:25:03.447343Z","caller":"traceutil/trace.go:171","msg":"trace[1054828310] linearizableReadLoop","detail":"{readStateIndex:1276; appliedIndex:1275; }","duration":"195.123223ms","start":"2026-08-24T15:25:03.252199Z","end":"2026-08-24T15:25:03.447323Z","steps":["trace[1054828310] 'read index received' (duration: 194.94034ms)","trace[1054828310] 'applied index is now lower than readState.Index' (duration: 182.093µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:25:03.447454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.235475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:25:03.447476Z","caller":"traceutil/trace.go:171","msg":"trace[1275917433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1225; }","duration":"195.300836ms","start":"2026-08-24T15:25:03.252168Z","end":"2026-08-24T15:25:03.447469Z","steps":["trace[1275917433] 'agreement among raft nodes before linearized reading' (duration: 195.230085ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:25:03.447714Z","caller":"traceutil/trace.go:171","msg":"trace[331532136] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"210.76148ms","start":"2026-08-24T15:25:03.236941Z","end":"2026-08-24T15:25:03.447703Z","steps":["trace[331532136] 'process raft request' (duration: 210.259601ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:25:05.006089Z","caller":"traceutil/trace.go:171","msg":"trace[159907180] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"140.84958ms","start":"2026-08-24T15:25:04.865211Z","end":"2026-08-24T15:25:05.006061Z","steps":["trace[159907180] 'process raft request' (duration: 140.737248ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:25:05.006419Z","caller":"traceutil/trace.go:171","msg":"trace[1547895927] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"141.243538ms","start":"2026-08-24T15:25:04.865159Z","end":"2026-08-24T15:25:05.006403Z","steps":["trace[1547895927] 'process raft request' (duration: 140.642477ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:25:05.006552Z","caller":"traceutil/trace.go:171","msg":"trace[451757440] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"141.326939ms","start":"2026-08-24T15:25:04.8652Z","end":"2026-08-24T15:25:05.006527Z","steps":["trace[451757440] 'process raft request' (duration: 140.800719ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:25:09.105425Z","caller":"traceutil/trace.go:171","msg":"trace[2098300654] linearizableReadLoop","detail":"{readStateIndex:1293; appliedIndex:1292; }","duration":"198.143974ms","start":"2026-08-24T15:25:08.907257Z","end":"2026-08-24T15:25:09.1054Z","steps":["trace[2098300654] 'read index received' (duration: 197.972121ms)","trace[2098300654] 'applied index is now lower than readState.Index' (duration: 170.553µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:25:09.105478Z","caller":"traceutil/trace.go:171","msg":"trace[843449559] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"273.792321ms","start":"2026-08-24T15:25:08.831673Z","end":"2026-08-24T15:25:09.105466Z","steps":["trace[843449559] 'process raft request' (duration: 273.571167ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:25:09.10561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.361559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:25:09.105665Z","caller":"traceutil/trace.go:171","msg":"trace[2085073409] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1241; }","duration":"198.509782ms","start":"2026-08-24T15:25:08.907142Z","end":"2026-08-24T15:25:09.105652Z","steps":["trace[2085073409] 'agreement among raft nodes before linearized reading' (duration: 198.40931ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:25:09.105752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.903646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:25:09.105822Z","caller":"traceutil/trace.go:171","msg":"trace[370339620] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1241; }","duration":"158.035359ms","start":"2026-08-24T15:25:08.94777Z","end":"2026-08-24T15:25:09.105805Z","steps":["trace[370339620] 'agreement among raft nodes before linearized reading' (duration: 157.863406ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:25:09.105931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.046309ms","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-24T15:25:09.105957Z","caller":"traceutil/trace.go:171","msg":"trace[1804208808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1241; }","duration":"158.1251ms","start":"2026-08-24T15:25:08.947824Z","end":"2026-08-24T15:25:09.105949Z","steps":["trace[1804208808] 'agreement among raft nodes before linearized reading' (duration: 158.072399ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:25:09.241643Z","caller":"traceutil/trace.go:171","msg":"trace[823419928] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"131.752411ms","start":"2026-08-24T15:25:09.109861Z","end":"2026-08-24T15:25:09.241613Z","steps":["trace[823419928] 'process raft request' (duration: 96.398524ms)","trace[823419928] 'compare' (duration: 35.222734ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:25:09.25635Z","caller":"traceutil/trace.go:171","msg":"trace[1024542924] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"145.378547ms","start":"2026-08-24T15:25:09.110957Z","end":"2026-08-24T15:25:09.256336Z","steps":["trace[1024542924] 'process raft request' (duration: 145.316336ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:25:09.256584Z","caller":"traceutil/trace.go:171","msg":"trace[567660637] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"145.289074ms","start":"2026-08-24T15:25:09.111276Z","end":"2026-08-24T15:25:09.256565Z","steps":["trace[567660637] 'process raft request' (duration: 145.02648ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:25:09.256787Z","caller":"traceutil/trace.go:171","msg":"trace[1625945308] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"146.464017ms","start":"2026-08-24T15:25:09.110312Z","end":"2026-08-24T15:25:09.256776Z","steps":["trace[1625945308] 'process raft request' (duration: 145.873846ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:25:13.400414Z","caller":"traceutil/trace.go:171","msg":"trace[2034143910] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"246.074625ms","start":"2026-08-24T15:25:13.154313Z","end":"2026-08-24T15:25:13.400388Z","steps":["trace[2034143910] 'process raft request' (duration: 245.82234ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:25:13.535335Z","caller":"traceutil/trace.go:171","msg":"trace[1830487667] linearizableReadLoop","detail":"{readStateIndex:1307; appliedIndex:1306; }","duration":"129.867736ms","start":"2026-08-24T15:25:13.405448Z","end":"2026-08-24T15:25:13.535315Z","steps":["trace[1830487667] 'read index received' (duration: 129.396927ms)","trace[1830487667] 'applied index is now lower than readState.Index' (duration: 470.049µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:25:13.535616Z","caller":"traceutil/trace.go:171","msg":"trace[1689643033] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"131.533956ms","start":"2026-08-24T15:25:13.40401Z","end":"2026-08-24T15:25:13.535544Z","steps":["trace[1689643033] 'process raft request' (duration: 130.759822ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:25:13.535734Z","caller":"traceutil/trace.go:171","msg":"trace[1783842484] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"129.603819ms","start":"2026-08-24T15:25:13.406122Z","end":"2026-08-24T15:25:13.535725Z","steps":["trace[1783842484] 'process raft request' (duration: 129.476767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:25:13.535697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.234392ms","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-24T15:25:13.535878Z","caller":"traceutil/trace.go:171","msg":"trace[1840244430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1254; }","duration":"130.456006ms","start":"2026-08-24T15:25:13.405415Z","end":"2026-08-24T15:25:13.535871Z","steps":["trace[1840244430] 'agreement among raft nodes before linearized reading' (duration: 130.15158ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:25:13.535851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.962686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-24T15:25:13.536117Z","caller":"traceutil/trace.go:171","msg":"trace[1822831257] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1255; }","duration":"130.058758ms","start":"2026-08-24T15:25:13.405839Z","end":"2026-08-24T15:25:13.535898Z","steps":["trace[1822831257] 'agreement among raft nodes before linearized reading' (duration: 129.883925ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:25:13.727446Z","caller":"traceutil/trace.go:171","msg":"trace[2045206472] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"187.4211ms","start":"2026-08-24T15:25:13.539999Z","end":"2026-08-24T15:25:13.72742Z","steps":["trace[2045206472] 'process raft request' (duration: 139.77039ms)","trace[2045206472] 'compare' (duration: 47.341744ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:25:17.990405Z","caller":"traceutil/trace.go:171","msg":"trace[1294559603] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"181.888418ms","start":"2026-08-24T15:25:17.80848Z","end":"2026-08-24T15:25:17.990369Z","steps":["trace[1294559603] 'process raft request' (duration: 178.155059ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:25:36.518654Z","caller":"traceutil/trace.go:171","msg":"trace[1310410593] linearizableReadLoop","detail":"{readStateIndex:1428; appliedIndex:1427; }","duration":"449.543833ms","start":"2026-08-24T15:25:36.069078Z","end":"2026-08-24T15:25:36.518622Z","steps":["trace[1310410593] 'read index received' (duration: 378.167779ms)","trace[1310410593] 'applied index is now lower than readState.Index' (duration: 71.373814ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:25:36.518673Z","caller":"traceutil/trace.go:171","msg":"trace[761150884] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"474.765133ms","start":"2026-08-24T15:25:36.043871Z","end":"2026-08-24T15:25:36.518636Z","steps":["trace[761150884] 'process raft request' (duration: 403.307628ms)","trace[761150884] 'compare' (duration: 70.980607ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:25:36.518957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.865269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-24T15:25:36.519016Z","caller":"traceutil/trace.go:171","msg":"trace[1009748389] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1369; }","duration":"450.036061ms","start":"2026-08-24T15:25:36.068966Z","end":"2026-08-24T15:25:36.519002Z","steps":["trace[1009748389] 'agreement among raft nodes before linearized reading' (duration: 449.808557ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:25:36.519061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:25:36.068928Z","time spent":"450.121243ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-24T15:25:36.519219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:25:36.043845Z","time spent":"474.916856ms","remote":"127.0.0.1:41264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:25:36.656393Z","caller":"traceutil/trace.go:171","msg":"trace[247913543] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"133.33223ms","start":"2026-08-24T15:25:36.523012Z","end":"2026-08-24T15:25:36.656344Z","steps":["trace[247913543] 'process raft request' (duration: 133.230468ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:25:36.65654Z","caller":"traceutil/trace.go:171","msg":"trace[1373946504] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"208.952119ms","start":"2026-08-24T15:25:36.447479Z","end":"2026-08-24T15:25:36.656431Z","steps":["trace[1373946504] 'process raft request' (duration: 190.236296ms)","trace[1373946504] 'compare' (duration: 17.748646ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:25:36.657033Z","caller":"traceutil/trace.go:171","msg":"trace[387400421] linearizableReadLoop","detail":"{readStateIndex:1429; appliedIndex:1428; }","duration":"138.058153ms","start":"2026-08-24T15:25:36.51874Z","end":"2026-08-24T15:25:36.656798Z","steps":["trace[387400421] 'read index received' (duration: 119.041375ms)","trace[387400421] 'applied index is now lower than readState.Index' (duration: 19.014488ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:25:36.657212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.430117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:25:36.658405Z","caller":"traceutil/trace.go:171","msg":"trace[941260334] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1371; }","duration":"387.629248ms","start":"2026-08-24T15:25:36.270703Z","end":"2026-08-24T15:25:36.658333Z","steps":["trace[941260334] 'agreement among raft nodes before linearized reading' (duration: 386.403347ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:25:36.658435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.555268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-08-24T15:25:36.65855Z","caller":"traceutil/trace.go:171","msg":"trace[630651986] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1371; }","duration":"136.730191ms","start":"2026-08-24T15:25:36.521809Z","end":"2026-08-24T15:25:36.65854Z","steps":["trace[630651986] 'agreement among raft nodes before linearized reading' (duration: 136.567098ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:25:36.658587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:25:36.270669Z","time spent":"387.838331ms","remote":"127.0.0.1:41468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-08-24T15:25:57.895891Z","caller":"traceutil/trace.go:171","msg":"trace[141924916] linearizableReadLoop","detail":"{readStateIndex:1505; appliedIndex:1504; }","duration":"138.492684ms","start":"2026-08-24T15:25:57.757374Z","end":"2026-08-24T15:25:57.895867Z","steps":["trace[141924916] 'read index received' (duration: 138.25935ms)","trace[141924916] 'applied index is now lower than readState.Index' (duration: 232.264µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:25:57.895944Z","caller":"traceutil/trace.go:171","msg":"trace[741752378] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"155.424695ms","start":"2026-08-24T15:25:57.740489Z","end":"2026-08-24T15:25:57.895914Z","steps":["trace[741752378] 'process raft request' (duration: 155.12975ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:25:57.896059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.672257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1084"} {"level":"warn","ts":"2026-08-24T15:25:57.896103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.03337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:25:57.896129Z","caller":"traceutil/trace.go:171","msg":"trace[826636713] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:1442; }","duration":"138.752538ms","start":"2026-08-24T15:25:57.757359Z","end":"2026-08-24T15:25:57.896112Z","steps":["trace[826636713] 'agreement among raft nodes before linearized reading' (duration: 138.614476ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:25:57.896244Z","caller":"traceutil/trace.go:171","msg":"trace[1354668567] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1442; }","duration":"126.139232ms","start":"2026-08-24T15:25:57.770004Z","end":"2026-08-24T15:25:57.896144Z","steps":["trace[1354668567] 'agreement among raft nodes before linearized reading' (duration: 126.03947ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:26:00.998947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.292293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/kubeadm:kubeadm-certs\" ","response":"range_response_count:1 size:339"} {"level":"info","ts":"2026-08-24T15:26:00.999046Z","caller":"traceutil/trace.go:171","msg":"trace[313846348] range","detail":"{range_begin:/registry/roles/kube-system/kubeadm:kubeadm-certs; range_end:; response_count:1; response_revision:1454; }","duration":"116.433305ms","start":"2026-08-24T15:26:00.882595Z","end":"2026-08-24T15:26:00.999028Z","steps":["trace[313846348] 'range keys from in-memory index tree' (duration: 116.200801ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:26:00.99905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.684675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/kubeadm:kubelet-config\" ","response":"range_response_count:1 size:345"} {"level":"info","ts":"2026-08-24T15:26:00.999141Z","caller":"traceutil/trace.go:171","msg":"trace[850003786] range","detail":"{range_begin:/registry/roles/kube-system/kubeadm:kubelet-config; range_end:; response_count:1; response_revision:1454; }","duration":"114.785846ms","start":"2026-08-24T15:26:00.884331Z","end":"2026-08-24T15:26:00.999117Z","steps":["trace[850003786] 'range keys from in-memory index tree' (duration: 114.586473ms)"],"step_count":1}